{"level":"warn","ts":"2026-01-28T13:01:45.302464Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-28T13:01:45.302586Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.84:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.84:2380","--initial-cluster=instance=https://162.253.55.84:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.84:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.84:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-01-28T13:01:45.302674Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-28T13:01:45.302702Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.84:2380"]} {"level":"info","ts":"2026-01-28T13:01:45.302744Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-01-28T13:01:45.303439Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"]} {"level":"info","ts":"2026-01-28T13:01:45.303545Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.84:2380"],"listen-peer-urls":["https://162.253.55.84:2380"],"advertise-client-urls":["https://162.253.55.84:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.84:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-01-28T13:01:45.307535Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.753955ms"} {"level":"info","ts":"2026-01-28T13:01:45.318917Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ca4c710a8d5349d7","cluster-id":"8e28a51debf1c8ab"} {"level":"info","ts":"2026-01-28T13:01:45.318992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=()"} {"level":"info","ts":"2026-01-28T13:01:45.319033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became follower at term 0"} {"level":"info","ts":"2026-01-28T13:01:45.319069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ca4c710a8d5349d7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-28T13:01:45.319085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became follower at term 1"} {"level":"info","ts":"2026-01-28T13:01:45.319149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=(14577150384026110423)"} {"level":"warn","ts":"2026-01-28T13:01:45.330331Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-28T13:01:45.331455Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-28T13:01:45.334622Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-01-28T13:01:45.338859Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ca4c710a8d5349d7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-28T13:01:45.3459Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ca4c710a8d5349d7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-28T13:01:45.353403Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-28T13:01:45.353478Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-28T13:01:45.353505Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-28T13:01:45.354834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=(14577150384026110423)"} {"level":"info","ts":"2026-01-28T13:01:45.354934Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8e28a51debf1c8ab","local-member-id":"ca4c710a8d5349d7","added-peer-id":"ca4c710a8d5349d7","added-peer-peer-urls":["https://162.253.55.84:2380"]} {"level":"info","ts":"2026-01-28T13:01:45.355452Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-01-28T13:01:45.355822Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ca4c710a8d5349d7","initial-advertise-peer-urls":["https://162.253.55.84:2380"],"listen-peer-urls":["https://162.253.55.84:2380"],"advertise-client-urls":["https://162.253.55.84:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-28T13:01:45.355866Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-28T13:01:45.356169Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.84:2380"} {"level":"info","ts":"2026-01-28T13:01:45.356204Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.84:2380"} {"level":"info","ts":"2026-01-28T13:01:46.024387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 is starting a new election at term 1"} {"level":"info","ts":"2026-01-28T13:01:46.024487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became pre-candidate at term 1"} {"level":"info","ts":"2026-01-28T13:01:46.024521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 received MsgPreVoteResp from ca4c710a8d5349d7 at term 1"} {"level":"info","ts":"2026-01-28T13:01:46.024567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became candidate at term 2"} {"level":"info","ts":"2026-01-28T13:01:46.02459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 received MsgVoteResp from ca4c710a8d5349d7 at term 2"} {"level":"info","ts":"2026-01-28T13:01:46.024609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became leader at term 2"} {"level":"info","ts":"2026-01-28T13:01:46.024642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ca4c710a8d5349d7 elected leader ca4c710a8d5349d7 at term 2"} {"level":"info","ts":"2026-01-28T13:01:46.032789Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-28T13:01:46.040845Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ca4c710a8d5349d7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.84:2379]}","request-path":"/0/members/ca4c710a8d5349d7/attributes","cluster-id":"8e28a51debf1c8ab","publish-timeout":"7s"} {"level":"info","ts":"2026-01-28T13:01:46.04451Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-28T13:01:46.044805Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-28T13:01:46.04496Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-28T13:01:46.044999Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-28T13:01:46.046217Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.84:2379"} {"level":"info","ts":"2026-01-28T13:01:46.047347Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-28T13:01:46.047442Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8e28a51debf1c8ab","local-member-id":"ca4c710a8d5349d7","cluster-version":"3.5"} {"level":"info","ts":"2026-01-28T13:01:46.047523Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-28T13:01:46.052714Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-28T13:01:57.186346Z","caller":"traceutil/trace.go:171","msg":"trace[1493021058] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"110.327511ms","start":"2026-01-28T13:01:57.075977Z","end":"2026-01-28T13:01:57.186304Z","steps":["trace[1493021058] 'process raft request' (duration: 110.146047ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T13:02:03.321038Z","caller":"traceutil/trace.go:171","msg":"trace[1634983013] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"620.9005ms","start":"2026-01-28T13:02:02.700105Z","end":"2026-01-28T13:02:03.321005Z","steps":["trace[1634983013] 'process raft request' (duration: 620.607158ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:02:03.32173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:02:02.700091Z","time spent":"621.043357ms","remote":"127.0.0.1:49716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7213,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T13:02:03.828624Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5320893028742983095,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-28T13:02:04.588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.972901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T13:02:04.588174Z","caller":"traceutil/trace.go:171","msg":"trace[1045140747] linearizableReadLoop","detail":"{readStateIndex:459; appliedIndex:458; }","duration":"1.260036539s","start":"2026-01-28T13:02:03.328118Z","end":"2026-01-28T13:02:04.588154Z","steps":["trace[1045140747] 'read index received' (duration: 617.58841ms)","trace[1045140747] 'applied index is now lower than readState.Index' (duration: 642.446869ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T13:02:04.588245Z","caller":"traceutil/trace.go:171","msg":"trace[782487619] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"1.261169648s","start":"2026-01-28T13:02:03.327064Z","end":"2026-01-28T13:02:04.588234Z","steps":["trace[782487619] 'process raft request' (duration: 618.695709ms)","trace[782487619] 'compare' (duration: 641.799617ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T13:02:04.588291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:02:03.32704Z","time spent":"1.261228077s","remote":"127.0.0.1:49780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T13:02:04.58863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.260511206s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7228"} {"level":"info","ts":"2026-01-28T13:02:04.58865Z","caller":"traceutil/trace.go:171","msg":"trace[755435058] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:448; }","duration":"1.260560455s","start":"2026-01-28T13:02:03.328079Z","end":"2026-01-28T13:02:04.58864Z","steps":["trace[755435058] 'agreement among raft nodes before linearized reading' (duration: 1.260500106s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:02:04.58867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:02:03.328041Z","time spent":"1.260624513s","remote":"127.0.0.1:49716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7252,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-01-28T13:02:04.694397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.95993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T13:02:04.694461Z","caller":"traceutil/trace.go:171","msg":"trace[602886485] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:448; }","duration":"498.092257ms","start":"2026-01-28T13:02:04.196357Z","end":"2026-01-28T13:02:04.694449Z","steps":["trace[602886485] 'agreement among raft nodes before linearized reading' (duration: 497.9844ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:02:04.694493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:02:04.196323Z","time spent":"498.161885ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-28T13:02:04.694783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.462939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-28T13:02:04.694833Z","caller":"traceutil/trace.go:171","msg":"trace[1783520262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:448; }","duration":"877.534307ms","start":"2026-01-28T13:02:03.817292Z","end":"2026-01-28T13:02:04.694826Z","steps":["trace[1783520262] 'agreement among raft nodes before linearized reading' (duration: 877.456299ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:02:04.694857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:02:03.817281Z","time spent":"877.572436ms","remote":"127.0.0.1:49780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-28T13:02:04.695012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.711873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-28T13:02:04.695044Z","caller":"traceutil/trace.go:171","msg":"trace[1187336245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:448; }","duration":"881.754862ms","start":"2026-01-28T13:02:03.813281Z","end":"2026-01-28T13:02:04.695036Z","steps":["trace[1187336245] 'agreement among raft nodes before linearized reading' (duration: 881.711244ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:02:04.695068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:02:03.813275Z","time spent":"881.789112ms","remote":"127.0.0.1:49780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-28T13:02:04.695203Z","caller":"traceutil/trace.go:171","msg":"trace[1930141163] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:459; }","duration":"106.128518ms","start":"2026-01-28T13:02:04.588193Z","end":"2026-01-28T13:02:04.694322Z","steps":["trace[1930141163] 'read index received' (duration: 15.402454ms)","trace[1930141163] 'applied index is now lower than readState.Index' (duration: 90.724794ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T13:02:04.695275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.124374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-28T13:02:04.695297Z","caller":"traceutil/trace.go:171","msg":"trace[1707227465] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:448; }","duration":"890.182092ms","start":"2026-01-28T13:02:03.805109Z","end":"2026-01-28T13:02:04.695291Z","steps":["trace[1707227465] 'agreement among raft nodes before linearized reading' (duration: 890.121254ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:02:04.695316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:02:03.805089Z","time spent":"890.223562ms","remote":"127.0.0.1:49780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-01-28T13:02:04.726949Z","caller":"traceutil/trace.go:171","msg":"trace[2083228487] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"125.158184ms","start":"2026-01-28T13:02:04.601769Z","end":"2026-01-28T13:02:04.726927Z","steps":["trace[2083228487] 'process raft request' (duration: 125.003608ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:02:14.975939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.130338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-28T13:02:14.976029Z","caller":"traceutil/trace.go:171","msg":"trace[526968733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:507; }","duration":"152.270706ms","start":"2026-01-28T13:02:14.823747Z","end":"2026-01-28T13:02:14.976018Z","steps":["trace[526968733] 'range keys from in-memory index tree' (duration: 152.08622ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:02:14.976158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.325824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-28T13:02:14.97618Z","caller":"traceutil/trace.go:171","msg":"trace[391207557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:507; }","duration":"152.353333ms","start":"2026-01-28T13:02:14.823821Z","end":"2026-01-28T13:02:14.976174Z","steps":["trace[391207557] 'range keys from in-memory index tree' (duration: 151.947263ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T13:02:15.101264Z","caller":"traceutil/trace.go:171","msg":"trace[515650311] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"122.1689ms","start":"2026-01-28T13:02:14.979078Z","end":"2026-01-28T13:02:15.101247Z","steps":["trace[515650311] 'process raft request' (duration: 121.846868ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T13:02:15.193399Z","caller":"traceutil/trace.go:171","msg":"trace[1275467060] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"214.14735ms","start":"2026-01-28T13:02:14.979231Z","end":"2026-01-28T13:02:15.193378Z","steps":["trace[1275467060] 'process raft request' (duration: 213.576334ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:02:16.482403Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.029196062s","expected-duration":"1s"} {"level":"info","ts":"2026-01-28T13:02:16.482713Z","caller":"traceutil/trace.go:171","msg":"trace[562849410] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:523; }","duration":"285.130993ms","start":"2026-01-28T13:02:16.197561Z","end":"2026-01-28T13:02:16.482692Z","steps":["trace[562849410] 'read index received' (duration: 284.963727ms)","trace[562849410] 'applied index is now lower than readState.Index' (duration: 166.476µs)"],"step_count":2} {"level":"warn","ts":"2026-01-28T13:02:16.482812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.239089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T13:02:16.48284Z","caller":"traceutil/trace.go:171","msg":"trace[172483920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"285.325047ms","start":"2026-01-28T13:02:16.197505Z","end":"2026-01-28T13:02:16.482831Z","steps":["trace[172483920] 'agreement among raft nodes before linearized reading' (duration: 285.237469ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T13:02:16.483247Z","caller":"traceutil/trace.go:171","msg":"trace[1738530855] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"1.03008872s","start":"2026-01-28T13:02:15.453146Z","end":"2026-01-28T13:02:16.483234Z","steps":["trace[1738530855] 'process raft request' (duration: 1.029428346s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:02:16.483327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:02:15.453132Z","time spent":"1.030142528s","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4210,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T13:02:16.742231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.488686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-28T13:02:16.742303Z","caller":"traceutil/trace.go:171","msg":"trace[333800184] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:510; }","duration":"107.598414ms","start":"2026-01-28T13:02:16.634687Z","end":"2026-01-28T13:02:16.742286Z","steps":["trace[333800184] 'range keys from in-memory index tree' (duration: 107.351679ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:02:23.685034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.949671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T13:02:23.685097Z","caller":"traceutil/trace.go:171","msg":"trace[949972268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:554; }","duration":"490.046229ms","start":"2026-01-28T13:02:23.195037Z","end":"2026-01-28T13:02:23.685084Z","steps":["trace[949972268] 'range keys from in-memory index tree' (duration: 489.898172ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:02:23.685141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:02:23.195023Z","time spent":"490.092367ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-28T13:02:23.685336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.696362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-28T13:02:23.68535Z","caller":"traceutil/trace.go:171","msg":"trace[712047196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:554; }","duration":"415.736241ms","start":"2026-01-28T13:02:23.269609Z","end":"2026-01-28T13:02:23.685345Z","steps":["trace[712047196] 'range keys from in-memory index tree' (duration: 415.608984ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:02:23.68538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:02:23.269595Z","time spent":"415.76507ms","remote":"127.0.0.1:49780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-28T13:02:24.391861Z","caller":"traceutil/trace.go:171","msg":"trace[1755725973] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"693.116198ms","start":"2026-01-28T13:02:23.698728Z","end":"2026-01-28T13:02:24.391844Z","steps":["trace[1755725973] 'process raft request' (duration: 693.01398ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:02:24.39197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:02:23.698712Z","time spent":"693.195206ms","remote":"127.0.0.1:49780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T13:02:24.392234Z","caller":"traceutil/trace.go:171","msg":"trace[17914643] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:570; }","duration":"196.275994ms","start":"2026-01-28T13:02:24.195951Z","end":"2026-01-28T13:02:24.392227Z","steps":["trace[17914643] 'read index received' (duration: 196.272774ms)","trace[17914643] 'applied index is now lower than readState.Index' (duration: 2.58µs)"],"step_count":2} {"level":"warn","ts":"2026-01-28T13:02:24.392277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.322883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T13:02:24.392296Z","caller":"traceutil/trace.go:171","msg":"trace[1505684973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"196.368431ms","start":"2026-01-28T13:02:24.19592Z","end":"2026-01-28T13:02:24.392288Z","steps":["trace[1505684973] 'agreement among raft nodes before linearized reading' (duration: 196.336042ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:02:25.55797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016876511s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T13:02:25.558055Z","caller":"traceutil/trace.go:171","msg":"trace[184894978] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:555; }","duration":"1.016966399s","start":"2026-01-28T13:02:24.541071Z","end":"2026-01-28T13:02:25.558038Z","steps":["trace[184894978] 'range keys from in-memory index tree' (duration: 1.016863781s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:02:27.004524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.415056589s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-01-28T13:02:27.004613Z","caller":"traceutil/trace.go:171","msg":"trace[532330356] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:570; }","duration":"2.181836247s","start":"2026-01-28T13:02:24.822761Z","end":"2026-01-28T13:02:27.004597Z","steps":["trace[532330356] 'read index received' (duration: 15.59µs)","trace[532330356] 'applied index is now lower than readState.Index' (duration: 2.181819217s)"],"step_count":2} {"level":"warn","ts":"2026-01-28T13:02:27.005054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.182287687s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-28T13:02:27.005085Z","caller":"traceutil/trace.go:171","msg":"trace[612541181] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:555; }","duration":"2.182348825s","start":"2026-01-28T13:02:24.822727Z","end":"2026-01-28T13:02:27.005076Z","steps":["trace[612541181] 'agreement among raft nodes before linearized reading' (duration: 2.182220728s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:02:27.005115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:02:24.822711Z","time spent":"2.182396774s","remote":"127.0.0.1:49780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-28T13:02:27.005329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.154029695s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-28T13:02:27.005355Z","caller":"traceutil/trace.go:171","msg":"trace[108353361] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:555; }","duration":"2.154083964s","start":"2026-01-28T13:02:24.851264Z","end":"2026-01-28T13:02:27.005348Z","steps":["trace[108353361] 'agreement among raft nodes before linearized reading' (duration: 2.154005805s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:02:27.00538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:02:24.851248Z","time spent":"2.154125653s","remote":"127.0.0.1:49780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-28T13:02:27.005562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.722784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-28T13:02:27.00559Z","caller":"traceutil/trace.go:171","msg":"trace[1179682198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:555; }","duration":"610.772642ms","start":"2026-01-28T13:02:26.39481Z","end":"2026-01-28T13:02:27.005583Z","steps":["trace[1179682198] 'agreement among raft nodes before linearized reading' (duration: 610.702474ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:02:27.005613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:02:26.394797Z","time spent":"610.811642ms","remote":"127.0.0.1:49780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-28T13:02:27.005721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.764872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T13:02:27.005745Z","caller":"traceutil/trace.go:171","msg":"trace[70825167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"881.820131ms","start":"2026-01-28T13:02:26.123918Z","end":"2026-01-28T13:02:27.005738Z","steps":["trace[70825167] 'agreement among raft nodes before linearized reading' (duration: 881.784551ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:02:27.005782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:02:26.123904Z","time spent":"881.87366ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-28T13:02:27.005891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.811544507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T13:02:27.005914Z","caller":"traceutil/trace.go:171","msg":"trace[545090078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"1.811590376s","start":"2026-01-28T13:02:25.194316Z","end":"2026-01-28T13:02:27.005907Z","steps":["trace[545090078] 'agreement among raft nodes before linearized reading' (duration: 1.811557397s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:02:27.005938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:02:25.194304Z","time spent":"1.811626915s","remote":"127.0.0.1:49576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-28T13:02:27.006075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.858916329s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-28T13:02:27.006098Z","caller":"traceutil/trace.go:171","msg":"trace[1905825370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"1.858961948s","start":"2026-01-28T13:02:25.147129Z","end":"2026-01-28T13:02:27.006091Z","steps":["trace[1905825370] 'agreement among raft nodes before linearized reading' (duration: 1.858894059s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:02:27.006121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T13:02:25.147116Z","time spent":"1.858999257s","remote":"127.0.0.1:49780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-01-28T13:02:28.115286Z","caller":"traceutil/trace.go:171","msg":"trace[1211839221] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"187.677807ms","start":"2026-01-28T13:02:27.927591Z","end":"2026-01-28T13:02:28.115269Z","steps":["trace[1211839221] 'process raft request' (duration: 187.5718ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T13:02:28.124887Z","caller":"traceutil/trace.go:171","msg":"trace[955186023] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"179.450277ms","start":"2026-01-28T13:02:27.945421Z","end":"2026-01-28T13:02:28.124871Z","steps":["trace[955186023] 'process raft request' (duration: 179.368359ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:02:29.402569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.123767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.84\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-28T13:02:29.402645Z","caller":"traceutil/trace.go:171","msg":"trace[1532481628] range","detail":"{range_begin:/registry/masterleases/162.253.55.84; range_end:; response_count:1; response_revision:572; }","duration":"225.221185ms","start":"2026-01-28T13:02:29.177403Z","end":"2026-01-28T13:02:29.402624Z","steps":["trace[1532481628] 'range keys from in-memory index tree' (duration: 224.9754ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:02:32.397103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.39841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T13:02:32.397175Z","caller":"traceutil/trace.go:171","msg":"trace[1304226633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"201.512797ms","start":"2026-01-28T13:02:32.195645Z","end":"2026-01-28T13:02:32.397158Z","steps":["trace[1304226633] 'range keys from in-memory index tree' (duration: 201.34988ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:02:32.397437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.575179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-01-28T13:02:32.397471Z","caller":"traceutil/trace.go:171","msg":"trace[1785576822] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:628; }","duration":"187.615369ms","start":"2026-01-28T13:02:32.209841Z","end":"2026-01-28T13:02:32.397457Z","steps":["trace[1785576822] 'range keys from in-memory index tree' (duration: 187.464192ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T13:02:40.205374Z","caller":"traceutil/trace.go:171","msg":"trace[12462410] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"132.3753ms","start":"2026-01-28T13:02:40.072975Z","end":"2026-01-28T13:02:40.20535Z","steps":["trace[12462410] 'process raft request' (duration: 110.839307ms)","trace[12462410] 'compare' (duration: 21.438965ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T13:02:40.209217Z","caller":"traceutil/trace.go:171","msg":"trace[1578784999] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"130.292476ms","start":"2026-01-28T13:02:40.078913Z","end":"2026-01-28T13:02:40.209205Z","steps":["trace[1578784999] 'process raft request' (duration: 129.944593ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:02:47.427243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.379903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T13:02:47.427382Z","caller":"traceutil/trace.go:171","msg":"trace[875402694] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"151.337334ms","start":"2026-01-28T13:02:47.276034Z","end":"2026-01-28T13:02:47.427371Z","steps":["trace[875402694] 'process raft request' (duration: 151.284676ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T13:02:47.427564Z","caller":"traceutil/trace.go:171","msg":"trace[1557403791] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"152.96918ms","start":"2026-01-28T13:02:47.274566Z","end":"2026-01-28T13:02:47.427535Z","steps":["trace[1557403791] 'process raft request' (duration: 51.229045ms)","trace[1557403791] 'compare' (duration: 101.272105ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T13:03:47.617232Z","caller":"traceutil/trace.go:171","msg":"trace[462611698] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"137.332223ms","start":"2026-01-28T13:03:47.479881Z","end":"2026-01-28T13:03:47.617213Z","steps":["trace[462611698] 'process raft request' (duration: 136.832553ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:03:54.090024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.959462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-28T13:03:54.090094Z","caller":"traceutil/trace.go:171","msg":"trace[968922998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:987; }","duration":"251.06117ms","start":"2026-01-28T13:03:53.839017Z","end":"2026-01-28T13:03:54.090079Z","steps":["trace[968922998] 'range keys from in-memory index tree' (duration: 250.833965ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:03:54.090496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.608735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-28T13:03:54.090532Z","caller":"traceutil/trace.go:171","msg":"trace[589918414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:987; }","duration":"262.664184ms","start":"2026-01-28T13:03:53.827857Z","end":"2026-01-28T13:03:54.090521Z","steps":["trace[589918414] 'range keys from in-memory index tree' (duration: 262.489747ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:03:54.353999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.66912ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T13:03:54.354664Z","caller":"traceutil/trace.go:171","msg":"trace[1478573180] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1022; }","duration":"176.873432ms","start":"2026-01-28T13:03:54.177774Z","end":"2026-01-28T13:03:54.354648Z","steps":["trace[1478573180] 'read index received' (duration: 64.442317ms)","trace[1478573180] 'applied index is now lower than readState.Index' (duration: 112.429715ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T13:03:54.354881Z","caller":"traceutil/trace.go:171","msg":"trace[1650770935] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"260.263232ms","start":"2026-01-28T13:03:54.094603Z","end":"2026-01-28T13:03:54.354866Z","steps":["trace[1650770935] 'process raft request' (duration: 147.65907ms)","trace[1650770935] 'compare' (duration: 111.598641ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T13:03:54.355424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.269584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T13:03:54.357152Z","caller":"traceutil/trace.go:171","msg":"trace[112635531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:989; }","duration":"160.978531ms","start":"2026-01-28T13:03:54.196126Z","end":"2026-01-28T13:03:54.357104Z","steps":["trace[112635531] 'agreement among raft nodes before linearized reading' (duration: 159.248335ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:03:54.355468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.688796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T13:03:54.3578Z","caller":"traceutil/trace.go:171","msg":"trace[840731801] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:989; }","duration":"179.846315ms","start":"2026-01-28T13:03:54.177742Z","end":"2026-01-28T13:03:54.357589Z","steps":["trace[840731801] 'agreement among raft nodes before linearized reading' (duration: 177.704766ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T13:03:55.847707Z","caller":"traceutil/trace.go:171","msg":"trace[1019114205] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1025; }","duration":"135.91093ms","start":"2026-01-28T13:03:55.711772Z","end":"2026-01-28T13:03:55.847683Z","steps":["trace[1019114205] 'read index received' (duration: 135.669215ms)","trace[1019114205] 'applied index is now lower than readState.Index' (duration: 238.055µs)"],"step_count":2} {"level":"info","ts":"2026-01-28T13:03:55.848195Z","caller":"traceutil/trace.go:171","msg":"trace[448121632] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"161.514202ms","start":"2026-01-28T13:03:55.686654Z","end":"2026-01-28T13:03:55.848168Z","steps":["trace[448121632] 'process raft request' (duration: 160.862315ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T13:03:55.848238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.44247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-28T13:03:55.848389Z","caller":"traceutil/trace.go:171","msg":"trace[1271317440] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:991; }","duration":"136.596427ms","start":"2026-01-28T13:03:55.711747Z","end":"2026-01-28T13:03:55.848344Z","steps":["trace[1271317440] 'agreement among raft nodes before linearized reading' (duration: 136.363072ms)"],"step_count":1}