{"level":"warn","ts":"2026-01-31T21:22:30.165631Z","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-31T21:22:30.165701Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.227: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.227:2380","--initial-cluster=instance=https://162.253.55.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.227: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-31T21:22:30.166532Z","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-31T21:22:30.166549Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-01-31T21:22:30.166565Z","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-31T21:22:30.167165Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"]} {"level":"info","ts":"2026-01-31T21:22:30.167227Z","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.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.227: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-31T21:22:30.170643Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.257839ms"} {"level":"info","ts":"2026-01-31T21:22:30.181761Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9bd37ed2859641e3","cluster-id":"47d0f4f0a1c3b0a9"} {"level":"info","ts":"2026-01-31T21:22:30.181815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=()"} {"level":"info","ts":"2026-01-31T21:22:30.181834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 0"} {"level":"info","ts":"2026-01-31T21:22:30.181886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9bd37ed2859641e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-31T21:22:30.181901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 1"} {"level":"info","ts":"2026-01-31T21:22:30.181938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"warn","ts":"2026-01-31T21:22:30.190889Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-31T21:22:30.197351Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-31T21:22:30.200107Z","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-31T21:22:30.207984Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9bd37ed2859641e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-31T21:22:30.227127Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9bd37ed2859641e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-31T21:22:30.227399Z","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-31T21:22:30.227446Z","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-31T21:22:30.227478Z","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-31T21:22:30.231678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"info","ts":"2026-01-31T21:22:30.231757Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","added-peer-id":"9bd37ed2859641e3","added-peer-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-01-31T21:22:30.231955Z","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-31T21:22:30.232076Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9bd37ed2859641e3","initial-advertise-peer-urls":["https://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-31T21:22:30.232091Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-31T21:22:30.232171Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-01-31T21:22:30.23218Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-01-31T21:22:30.695702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 is starting a new election at term 1"} {"level":"info","ts":"2026-01-31T21:22:30.695751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-01-31T21:22:30.695767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgPreVoteResp from 9bd37ed2859641e3 at term 1"} {"level":"info","ts":"2026-01-31T21:22:30.695777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became candidate at term 2"} {"level":"info","ts":"2026-01-31T21:22:30.695783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgVoteResp from 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-01-31T21:22:30.695791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became leader at term 2"} {"level":"info","ts":"2026-01-31T21:22:30.695797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9bd37ed2859641e3 elected leader 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-01-31T21:22:30.699323Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9bd37ed2859641e3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.227:2379]}","request-path":"/0/members/9bd37ed2859641e3/attributes","cluster-id":"47d0f4f0a1c3b0a9","publish-timeout":"7s"} {"level":"info","ts":"2026-01-31T21:22:30.699431Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T21:22:30.700577Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-31T21:22:30.700808Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-31T21:22:30.700893Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-31T21:22:30.7009Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-31T21:22:30.700914Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T21:22:30.700952Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T21:22:30.700962Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T21:22:30.704383Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.227:2379"} {"level":"info","ts":"2026-01-31T21:22:30.708691Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-01-31T21:22:47.229448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.454158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-31T21:22:47.229522Z","caller":"traceutil/trace.go:171","msg":"trace[2100319758] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:420; }","duration":"140.587861ms","start":"2026-01-31T21:22:47.088919Z","end":"2026-01-31T21:22:47.229507Z","steps":["trace[2100319758] 'range keys from in-memory index tree' (duration: 140.364196ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:22:47.229582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.914122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"info","ts":"2026-01-31T21:22:47.229645Z","caller":"traceutil/trace.go:171","msg":"trace[2092806395] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:420; }","duration":"138.017433ms","start":"2026-01-31T21:22:47.091611Z","end":"2026-01-31T21:22:47.229629Z","steps":["trace[2092806395] 'range keys from in-memory index tree' (duration: 137.8509ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T21:22:57.796255Z","caller":"traceutil/trace.go:171","msg":"trace[406102823] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"506.210261ms","start":"2026-01-31T21:22:57.290022Z","end":"2026-01-31T21:22:57.796232Z","steps":["trace[406102823] 'process raft request' (duration: 506.098099ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:22:57.796793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:22:57.290011Z","time spent":"506.310353ms","remote":"127.0.0.1:53260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T21:23:03.162366Z","caller":"traceutil/trace.go:171","msg":"trace[229195888] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"189.279754ms","start":"2026-01-31T21:23:02.973071Z","end":"2026-01-31T21:23:03.16235Z","steps":["trace[229195888] 'read index received' (duration: 189.186402ms)","trace[229195888] 'applied index is now lower than readState.Index' (duration: 92.752µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T21:23:03.162441Z","caller":"traceutil/trace.go:171","msg":"trace[101060685] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"195.487244ms","start":"2026-01-31T21:23:02.966948Z","end":"2026-01-31T21:23:03.162435Z","steps":["trace[101060685] 'process raft request' (duration: 195.31131ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:03.162564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.480438ms","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-31T21:23:03.162581Z","caller":"traceutil/trace.go:171","msg":"trace[2143752163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:520; }","duration":"189.51656ms","start":"2026-01-31T21:23:02.973059Z","end":"2026-01-31T21:23:03.162576Z","steps":["trace[2143752163] 'agreement among raft nodes before linearized reading' (duration: 189.441908ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:03.162646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.07368ms","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-31T21:23:03.162657Z","caller":"traceutil/trace.go:171","msg":"trace[2085596808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:520; }","duration":"188.09319ms","start":"2026-01-31T21:23:02.97456Z","end":"2026-01-31T21:23:03.162653Z","steps":["trace[2085596808] 'agreement among raft nodes before linearized reading' (duration: 188.06121ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:03.539926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.129373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T21:23:03.540045Z","caller":"traceutil/trace.go:171","msg":"trace[908457861] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"373.055816ms","start":"2026-01-31T21:23:03.166979Z","end":"2026-01-31T21:23:03.540035Z","steps":["trace[908457861] 'process raft request' (duration: 373.030245ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:03.540078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:03.166964Z","time spent":"373.098406ms","remote":"127.0.0.1:53260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T21:23:03.54024Z","caller":"traceutil/trace.go:171","msg":"trace[1841194463] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"373.993805ms","start":"2026-01-31T21:23:03.16624Z","end":"2026-01-31T21:23:03.540234Z","steps":["trace[1841194463] 'process raft request' (duration: 88.437903ms)","trace[1841194463] 'compare' (duration: 285.062322ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T21:23:03.540265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:03.16623Z","time spent":"374.021945ms","remote":"127.0.0.1:53260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T21:23:03.814159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.365224ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T21:23:03.814226Z","caller":"traceutil/trace.go:171","msg":"trace[2010039349] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"298.896371ms","start":"2026-01-31T21:23:03.515317Z","end":"2026-01-31T21:23:03.814214Z","steps":["trace[2010039349] 'process raft request' (duration: 149.430805ms)","trace[2010039349] 'compare' (duration: 149.1819ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T21:23:04.311738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.088365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T21:23:04.3118Z","caller":"traceutil/trace.go:171","msg":"trace[636060435] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:539; }","duration":"169.84624ms","start":"2026-01-31T21:23:04.141944Z","end":"2026-01-31T21:23:04.31179Z","steps":["trace[636060435] 'read index received' (duration: 46.665653ms)","trace[636060435] 'applied index is now lower than readState.Index' (duration: 123.179997ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T21:23:04.311851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.906901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-31T21:23:04.311863Z","caller":"traceutil/trace.go:171","msg":"trace[1006168456] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:1; response_revision:524; }","duration":"169.942833ms","start":"2026-01-31T21:23:04.141915Z","end":"2026-01-31T21:23:04.311857Z","steps":["trace[1006168456] 'agreement among raft nodes before linearized reading' (duration: 169.899772ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T21:23:04.312006Z","caller":"traceutil/trace.go:171","msg":"trace[1960887114] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"482.19743ms","start":"2026-01-31T21:23:03.829801Z","end":"2026-01-31T21:23:04.311998Z","steps":["trace[1960887114] 'process raft request' (duration: 358.803889ms)","trace[1960887114] 'compare' (duration: 123.025314ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T21:23:04.312036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:03.829792Z","time spent":"482.227091ms","remote":"127.0.0.1:53260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T21:23:08.82228Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810050206771861,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-31T21:23:08.867167Z","caller":"traceutil/trace.go:171","msg":"trace[1203299939] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:553; }","duration":"545.669534ms","start":"2026-01-31T21:23:08.321484Z","end":"2026-01-31T21:23:08.867154Z","steps":["trace[1203299939] 'read index received' (duration: 545.48494ms)","trace[1203299939] 'applied index is now lower than readState.Index' (duration: 183.994µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T21:23:08.867489Z","caller":"traceutil/trace.go:171","msg":"trace[95233070] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"705.831752ms","start":"2026-01-31T21:23:08.161649Z","end":"2026-01-31T21:23:08.86748Z","steps":["trace[95233070] 'process raft request' (duration: 705.388863ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:08.867632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:08.161634Z","time spent":"705.898503ms","remote":"127.0.0.1:53190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T21:23:08.8679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.41505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-31T21:23:08.867928Z","caller":"traceutil/trace.go:171","msg":"trace[945230176] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:537; }","duration":"546.47591ms","start":"2026-01-31T21:23:08.321446Z","end":"2026-01-31T21:23:08.867922Z","steps":["trace[945230176] 'agreement among raft nodes before linearized reading' (duration: 546.397329ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:08.868273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:08.321428Z","time spent":"546.835808ms","remote":"127.0.0.1:53260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-31T21:23:09.679486Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810050206771864,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-31T21:23:09.71717Z","caller":"traceutil/trace.go:171","msg":"trace[1025738206] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"839.686853ms","start":"2026-01-31T21:23:08.877462Z","end":"2026-01-31T21:23:09.717148Z","steps":["trace[1025738206] 'process raft request' (duration: 839.566581ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:09.717329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:08.877448Z","time spent":"839.792225ms","remote":"127.0.0.1:53260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T21:23:09.724753Z","caller":"traceutil/trace.go:171","msg":"trace[549367656] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:555; }","duration":"545.44735ms","start":"2026-01-31T21:23:09.179294Z","end":"2026-01-31T21:23:09.724741Z","steps":["trace[549367656] 'read index received' (duration: 538.406832ms)","trace[549367656] 'applied index is now lower than readState.Index' (duration: 7.039768ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T21:23:09.724899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.597493ms","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-31T21:23:09.724919Z","caller":"traceutil/trace.go:171","msg":"trace[1487416161] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:538; }","duration":"545.660253ms","start":"2026-01-31T21:23:09.179253Z","end":"2026-01-31T21:23:09.724913Z","steps":["trace[1487416161] 'agreement among raft nodes before linearized reading' (duration: 545.559651ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:09.724938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:09.179234Z","time spent":"545.700085ms","remote":"127.0.0.1:53260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-31T21:23:09.725196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.543564ms","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-31T21:23:09.725226Z","caller":"traceutil/trace.go:171","msg":"trace[1629868782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:538; }","duration":"158.603196ms","start":"2026-01-31T21:23:09.566616Z","end":"2026-01-31T21:23:09.72522Z","steps":["trace[1629868782] 'agreement among raft nodes before linearized reading' (duration: 158.529415ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:09.725306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.863095ms","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-31T21:23:09.725326Z","caller":"traceutil/trace.go:171","msg":"trace[144993216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:538; }","duration":"152.911186ms","start":"2026-01-31T21:23:09.572411Z","end":"2026-01-31T21:23:09.725322Z","steps":["trace[144993216] 'agreement among raft nodes before linearized reading' (duration: 152.859345ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T21:23:14.446424Z","caller":"traceutil/trace.go:171","msg":"trace[465848652] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"128.057489ms","start":"2026-01-31T21:23:14.318337Z","end":"2026-01-31T21:23:14.446395Z","steps":["trace[465848652] 'process raft request' (duration: 107.9466ms)","trace[465848652] 'compare' (duration: 19.898834ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T21:23:14.451824Z","caller":"traceutil/trace.go:171","msg":"trace[1778007353] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"133.159926ms","start":"2026-01-31T21:23:14.318647Z","end":"2026-01-31T21:23:14.451807Z","steps":["trace[1778007353] 'process raft request' (duration: 133.086404ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:22.44992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.502838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T21:23:22.450194Z","caller":"traceutil/trace.go:171","msg":"trace[72503448] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"155.318808ms","start":"2026-01-31T21:23:22.294858Z","end":"2026-01-31T21:23:22.450176Z","steps":["trace[72503448] 'process raft request' (duration: 155.203205ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T21:23:22.450234Z","caller":"traceutil/trace.go:171","msg":"trace[1242864759] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"144.358559ms","start":"2026-01-31T21:23:22.305864Z","end":"2026-01-31T21:23:22.450222Z","steps":["trace[1242864759] 'process raft request' (duration: 144.303068ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T21:23:22.450338Z","caller":"traceutil/trace.go:171","msg":"trace[1899137867] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"155.662544ms","start":"2026-01-31T21:23:22.294668Z","end":"2026-01-31T21:23:22.450331Z","steps":["trace[1899137867] 'process raft request' (duration: 14.677655ms)","trace[1899137867] 'compare' (duration: 140.398156ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T21:23:22.450576Z","caller":"traceutil/trace.go:171","msg":"trace[102196433] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"149.320903ms","start":"2026-01-31T21:23:22.301249Z","end":"2026-01-31T21:23:22.45057Z","steps":["trace[102196433] 'process raft request' (duration: 148.858603ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T21:23:22.450714Z","caller":"traceutil/trace.go:171","msg":"trace[431827100] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"148.486065ms","start":"2026-01-31T21:23:22.302221Z","end":"2026-01-31T21:23:22.450707Z","steps":["trace[431827100] 'process raft request' (duration: 147.922794ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:23.146712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.78105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T21:23:23.146885Z","caller":"traceutil/trace.go:171","msg":"trace[20558750] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"681.648807ms","start":"2026-01-31T21:23:22.465226Z","end":"2026-01-31T21:23:23.146875Z","steps":["trace[20558750] 'process raft request' (duration: 681.627407ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:23.14692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:22.465221Z","time spent":"681.683138ms","remote":"127.0.0.1:41424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3866,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T21:23:23.147101Z","caller":"traceutil/trace.go:171","msg":"trace[1581778601] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"688.348357ms","start":"2026-01-31T21:23:22.458747Z","end":"2026-01-31T21:23:23.147095Z","steps":["trace[1581778601] 'process raft request' (duration: 149.112577ms)","trace[1581778601] 'compare' (duration: 538.593056ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T21:23:23.147128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:22.45873Z","time spent":"688.384607ms","remote":"127.0.0.1:53268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1614,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T21:23:23.147235Z","caller":"traceutil/trace.go:171","msg":"trace[810749235] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"687.986739ms","start":"2026-01-31T21:23:22.459244Z","end":"2026-01-31T21:23:23.147231Z","steps":["trace[810749235] 'process raft request' (duration: 687.52722ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:23.14726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:22.459231Z","time spent":"688.01556ms","remote":"127.0.0.1:53172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1019,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T21:23:23.148467Z","caller":"traceutil/trace.go:171","msg":"trace[1581119501] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"683.350074ms","start":"2026-01-31T21:23:22.465111Z","end":"2026-01-31T21:23:23.148461Z","steps":["trace[1581119501] 'process raft request' (duration: 681.706029ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:23.148528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:22.4651Z","time spent":"683.388584ms","remote":"127.0.0.1:41424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3830,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T21:23:24.365709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.894164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-31T21:23:24.365833Z","caller":"traceutil/trace.go:171","msg":"trace[84589374] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"1.208703143s","start":"2026-01-31T21:23:23.157118Z","end":"2026-01-31T21:23:24.365822Z","steps":["trace[84589374] 'process raft request' (duration: 1.208658283s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:24.365879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:23.157111Z","time spent":"1.208743264s","remote":"127.0.0.1:41412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T21:23:24.366007Z","caller":"traceutil/trace.go:171","msg":"trace[1911950966] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"1.209711184s","start":"2026-01-31T21:23:23.156288Z","end":"2026-01-31T21:23:24.365999Z","steps":["trace[1911950966] 'process raft request' (duration: 419.473463ms)","trace[1911950966] 'compare' (duration: 789.550327ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T21:23:24.366034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:23.156271Z","time spent":"1.209750055s","remote":"127.0.0.1:53082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-31T21:23:24.366131Z","caller":"traceutil/trace.go:171","msg":"trace[579379456] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:713; }","duration":"1.209627973s","start":"2026-01-31T21:23:23.156497Z","end":"2026-01-31T21:23:24.366125Z","steps":["trace[579379456] 'read index received' (duration: 419.271439ms)","trace[579379456] 'applied index is now lower than readState.Index' (duration: 790.355804ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T21:23:24.366337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.209836918s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-fcp9d\" ","response":"range_response_count:1 size:4812"} {"level":"info","ts":"2026-01-31T21:23:24.366357Z","caller":"traceutil/trace.go:171","msg":"trace[2105539014] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-fcp9d; range_end:; response_count:1; response_revision:693; }","duration":"1.209870148s","start":"2026-01-31T21:23:23.156479Z","end":"2026-01-31T21:23:24.36635Z","steps":["trace[2105539014] 'agreement among raft nodes before linearized reading' (duration: 1.209796717s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:24.366374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:23.156474Z","time spent":"1.209896499s","remote":"127.0.0.1:53190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4835,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-fcp9d\" "} {"level":"warn","ts":"2026-01-31T21:23:24.368034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.535153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zld4d\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-01-31T21:23:24.368088Z","caller":"traceutil/trace.go:171","msg":"trace[927316152] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zld4d; range_end:; response_count:1; response_revision:694; }","duration":"536.635645ms","start":"2026-01-31T21:23:23.831441Z","end":"2026-01-31T21:23:24.368077Z","steps":["trace[927316152] 'agreement among raft nodes before linearized reading' (duration: 536.441821ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:24.368116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:23.831425Z","time spent":"536.686566ms","remote":"127.0.0.1:53190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18273,"request content":"key:\"/registry/pods/kube-system/cilium-zld4d\" "} {"level":"info","ts":"2026-01-31T21:23:24.368333Z","caller":"traceutil/trace.go:171","msg":"trace[1856072161] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"237.419159ms","start":"2026-01-31T21:23:24.130905Z","end":"2026-01-31T21:23:24.368324Z","steps":["trace[1856072161] 'process raft request' (duration: 236.798886ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:24.368468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.466299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-31T21:23:24.368486Z","caller":"traceutil/trace.go:171","msg":"trace[1019859167] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:1; response_revision:694; }","duration":"225.50004ms","start":"2026-01-31T21:23:24.14298Z","end":"2026-01-31T21:23:24.36848Z","steps":["trace[1019859167] 'agreement among raft nodes before linearized reading' (duration: 225.435609ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:24.368604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.050348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-31T21:23:24.368621Z","caller":"traceutil/trace.go:171","msg":"trace[1162995248] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:694; }","duration":"353.10752ms","start":"2026-01-31T21:23:24.015508Z","end":"2026-01-31T21:23:24.368616Z","steps":["trace[1162995248] 'agreement among raft nodes before linearized reading' (duration: 353.058069ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:24.368637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:24.015495Z","time spent":"353.13725ms","remote":"127.0.0.1:53260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-31T21:23:24.368749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.451535ms","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-31T21:23:24.368767Z","caller":"traceutil/trace.go:171","msg":"trace[1900696176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:694; }","duration":"411.490876ms","start":"2026-01-31T21:23:23.957271Z","end":"2026-01-31T21:23:24.368762Z","steps":["trace[1900696176] 'agreement among raft nodes before linearized reading' (duration: 411.431085ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:24.368784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:23.957265Z","time spent":"411.513356ms","remote":"127.0.0.1:53260","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":"warn","ts":"2026-01-31T21:23:24.368883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.450105ms","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-31T21:23:24.368898Z","caller":"traceutil/trace.go:171","msg":"trace[396403547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:694; }","duration":"434.489696ms","start":"2026-01-31T21:23:23.934404Z","end":"2026-01-31T21:23:24.368894Z","steps":["trace[396403547] 'agreement among raft nodes before linearized reading' (duration: 434.446606ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:24.368914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:23.934392Z","time spent":"434.518497ms","remote":"127.0.0.1:53260","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":"warn","ts":"2026-01-31T21:23:24.368991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.557659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T21:23:24.369008Z","caller":"traceutil/trace.go:171","msg":"trace[1636235501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:694; }","duration":"507.59675ms","start":"2026-01-31T21:23:23.861405Z","end":"2026-01-31T21:23:24.369001Z","steps":["trace[1636235501] 'agreement among raft nodes before linearized reading' (duration: 507.573139ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:24.369022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:23.861391Z","time spent":"507.627831ms","remote":"127.0.0.1:53016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-31T21:23:24.369131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.874456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-31T21:23:24.369147Z","caller":"traceutil/trace.go:171","msg":"trace[960573447] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:694; }","duration":"531.913098ms","start":"2026-01-31T21:23:23.837229Z","end":"2026-01-31T21:23:24.369142Z","steps":["trace[960573447] 'agreement among raft nodes before linearized reading' (duration: 531.862056ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:24.369174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:23.837213Z","time spent":"531.956988ms","remote":"127.0.0.1:53260","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-31T21:23:24.369273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.110603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-31T21:23:24.369288Z","caller":"traceutil/trace.go:171","msg":"trace[1939678109] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:694; }","duration":"533.127553ms","start":"2026-01-31T21:23:23.836156Z","end":"2026-01-31T21:23:24.369284Z","steps":["trace[1939678109] 'agreement among raft nodes before linearized reading' (duration: 533.083503ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:24.369303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:23.836119Z","time spent":"533.180094ms","remote":"127.0.0.1:53260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-31T21:23:24.741913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:24.377241Z","time spent":"364.668961ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-01-31T21:23:24.962132Z","caller":"traceutil/trace.go:171","msg":"trace[1087980477] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:717; }","duration":"581.00413ms","start":"2026-01-31T21:23:24.381114Z","end":"2026-01-31T21:23:24.962118Z","steps":["trace[1087980477] 'read index received' (duration: 360.77166ms)","trace[1087980477] 'applied index is now lower than readState.Index' (duration: 220.23187ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T21:23:24.962276Z","caller":"traceutil/trace.go:171","msg":"trace[1440539287] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"584.82972ms","start":"2026-01-31T21:23:24.377441Z","end":"2026-01-31T21:23:24.962271Z","steps":["trace[1440539287] 'process raft request' (duration: 584.461842ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:24.962339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:24.377433Z","time spent":"584.86219ms","remote":"127.0.0.1:53190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4809,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T21:23:24.962417Z","caller":"traceutil/trace.go:171","msg":"trace[315981926] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"570.798167ms","start":"2026-01-31T21:23:24.391612Z","end":"2026-01-31T21:23:24.96241Z","steps":["trace[315981926] 'process raft request' (duration: 570.435819ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:24.962444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:24.391609Z","time spent":"570.819367ms","remote":"127.0.0.1:53260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T21:23:24.962576Z","caller":"traceutil/trace.go:171","msg":"trace[1360852270] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"573.950354ms","start":"2026-01-31T21:23:24.388621Z","end":"2026-01-31T21:23:24.962571Z","steps":["trace[1360852270] 'process raft request' (duration: 573.357762ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:24.962608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:24.38861Z","time spent":"573.976014ms","remote":"127.0.0.1:53260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T21:23:24.962662Z","caller":"traceutil/trace.go:171","msg":"trace[1302532402] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"573.961993ms","start":"2026-01-31T21:23:24.388697Z","end":"2026-01-31T21:23:24.962659Z","steps":["trace[1302532402] 'process raft request' (duration: 573.3208ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:24.962683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:24.388693Z","time spent":"573.978163ms","remote":"127.0.0.1:53260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T21:23:24.962733Z","caller":"traceutil/trace.go:171","msg":"trace[1110557205] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"571.187865ms","start":"2026-01-31T21:23:24.391541Z","end":"2026-01-31T21:23:24.962729Z","steps":["trace[1110557205] 'process raft request' (duration: 570.493951ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:24.962753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:24.391532Z","time spent":"571.209525ms","remote":"127.0.0.1:53260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T21:23:24.963059Z","caller":"traceutil/trace.go:171","msg":"trace[288761074] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"555.199553ms","start":"2026-01-31T21:23:24.407855Z","end":"2026-01-31T21:23:24.963054Z","steps":["trace[288761074] 'process raft request' (duration: 554.235182ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:24.963108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:24.407848Z","time spent":"555.223083ms","remote":"127.0.0.1:53190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18047,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T21:23:24.963177Z","caller":"traceutil/trace.go:171","msg":"trace[1890125818] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"570.326018ms","start":"2026-01-31T21:23:24.392846Z","end":"2026-01-31T21:23:24.963172Z","steps":["trace[1890125818] 'process raft request' (duration: 569.218004ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:24.963199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:24.392832Z","time spent":"570.353898ms","remote":"127.0.0.1:53260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T21:23:24.963332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.217465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T21:23:24.963349Z","caller":"traceutil/trace.go:171","msg":"trace[114002147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:701; }","duration":"582.232606ms","start":"2026-01-31T21:23:24.381111Z","end":"2026-01-31T21:23:24.963344Z","steps":["trace[114002147] 'agreement among raft nodes before linearized reading' (duration: 582.206045ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:24.963363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:24.381091Z","time spent":"582.269057ms","remote":"127.0.0.1:53028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-31T21:23:24.995906Z","caller":"traceutil/trace.go:171","msg":"trace[1897057937] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"253.410352ms","start":"2026-01-31T21:23:24.742479Z","end":"2026-01-31T21:23:24.99589Z","steps":["trace[1897057937] 'process raft request' (duration: 253.32257ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:24.996257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.565385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4206"} {"level":"info","ts":"2026-01-31T21:23:24.996289Z","caller":"traceutil/trace.go:171","msg":"trace[1626058332] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:702; }","duration":"608.625856ms","start":"2026-01-31T21:23:24.387658Z","end":"2026-01-31T21:23:24.996284Z","steps":["trace[1626058332] 'agreement among raft nodes before linearized reading' (duration: 608.482503ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:24.996304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:24.387644Z","time spent":"608.655737ms","remote":"127.0.0.1:41412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4229,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-01-31T21:23:25.119599Z","caller":"traceutil/trace.go:171","msg":"trace[773720120] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"135.673077ms","start":"2026-01-31T21:23:24.98391Z","end":"2026-01-31T21:23:25.119583Z","steps":["trace[773720120] 'process raft request' (duration: 135.634927ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T21:23:25.119842Z","caller":"traceutil/trace.go:171","msg":"trace[547291939] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"148.340621ms","start":"2026-01-31T21:23:24.971494Z","end":"2026-01-31T21:23:25.119834Z","steps":["trace[547291939] 'process raft request' (duration: 147.911352ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T21:23:25.119971Z","caller":"traceutil/trace.go:171","msg":"trace[777347499] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"148.301121ms","start":"2026-01-31T21:23:24.971663Z","end":"2026-01-31T21:23:25.119964Z","steps":["trace[777347499] 'process raft request' (duration: 147.834441ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T21:23:25.129481Z","caller":"traceutil/trace.go:171","msg":"trace[1971477843] linearizableReadLoop","detail":"{readStateIndex:729; appliedIndex:728; }","duration":"131.623823ms","start":"2026-01-31T21:23:24.997846Z","end":"2026-01-31T21:23:25.12947Z","steps":["trace[1971477843] 'read index received' (duration: 122.798159ms)","trace[1971477843] 'applied index is now lower than readState.Index' (duration: 8.825104ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T21:23:25.129617Z","caller":"traceutil/trace.go:171","msg":"trace[1081958179] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"131.683755ms","start":"2026-01-31T21:23:24.997908Z","end":"2026-01-31T21:23:25.129592Z","steps":["trace[1081958179] 'process raft request' (duration: 131.499161ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T21:23:25.129839Z","caller":"traceutil/trace.go:171","msg":"trace[135455916] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"133.290558ms","start":"2026-01-31T21:23:24.996542Z","end":"2026-01-31T21:23:25.129832Z","steps":["trace[135455916] 'process raft request' (duration: 132.767978ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T21:23:25.129855Z","caller":"traceutil/trace.go:171","msg":"trace[15122713] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"131.979911ms","start":"2026-01-31T21:23:24.997873Z","end":"2026-01-31T21:23:25.129852Z","steps":["trace[15122713] 'process raft request' (duration: 131.512261ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T21:23:25.129896Z","caller":"traceutil/trace.go:171","msg":"trace[824393928] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"127.004937ms","start":"2026-01-31T21:23:25.002888Z","end":"2026-01-31T21:23:25.129893Z","steps":["trace[824393928] 'process raft request' (duration: 126.535287ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T21:23:25.130063Z","caller":"traceutil/trace.go:171","msg":"trace[1369296218] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"124.585706ms","start":"2026-01-31T21:23:25.005472Z","end":"2026-01-31T21:23:25.130058Z","steps":["trace[1369296218] 'process raft request' (duration: 123.978484ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:25.130138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.278686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-fcp9d\" ","response":"range_response_count:1 size:4824"} {"level":"info","ts":"2026-01-31T21:23:25.133959Z","caller":"traceutil/trace.go:171","msg":"trace[1825708903] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-fcp9d; range_end:; response_count:1; response_revision:710; }","duration":"136.105817ms","start":"2026-01-31T21:23:24.997845Z","end":"2026-01-31T21:23:25.13395Z","steps":["trace[1825708903] 'agreement among raft nodes before linearized reading' (duration: 132.233165ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:25.133738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.894227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-01-31T21:23:25.136606Z","caller":"traceutil/trace.go:171","msg":"trace[329989870] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:711; }","duration":"133.783928ms","start":"2026-01-31T21:23:25.00281Z","end":"2026-01-31T21:23:25.136594Z","steps":["trace[329989870] 'agreement among raft nodes before linearized reading' (duration: 130.888928ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:25.133881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.788866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T21:23:25.138807Z","caller":"traceutil/trace.go:171","msg":"trace[406528688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:711; }","duration":"135.716668ms","start":"2026-01-31T21:23:25.003081Z","end":"2026-01-31T21:23:25.138798Z","steps":["trace[406528688] 'agreement among raft nodes before linearized reading' (duration: 130.789586ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T21:23:39.264122Z","caller":"traceutil/trace.go:171","msg":"trace[1369657230] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:780; }","duration":"176.684869ms","start":"2026-01-31T21:23:39.087415Z","end":"2026-01-31T21:23:39.2641Z","steps":["trace[1369657230] 'read index received' (duration: 176.492404ms)","trace[1369657230] 'applied index is now lower than readState.Index' (duration: 191.155µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T21:23:39.264348Z","caller":"traceutil/trace.go:171","msg":"trace[364583587] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"195.227406ms","start":"2026-01-31T21:23:39.069109Z","end":"2026-01-31T21:23:39.264336Z","steps":["trace[364583587] 'process raft request' (duration: 194.884949ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:39.264673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.240131ms","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-31T21:23:39.264708Z","caller":"traceutil/trace.go:171","msg":"trace[1194151497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:756; }","duration":"177.315643ms","start":"2026-01-31T21:23:39.087382Z","end":"2026-01-31T21:23:39.264698Z","steps":["trace[1194151497] 'agreement among raft nodes before linearized reading' (duration: 177.18352ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:39.264984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.522023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-31T21:23:39.265016Z","caller":"traceutil/trace.go:171","msg":"trace[1114968880] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:756; }","duration":"175.569414ms","start":"2026-01-31T21:23:39.089438Z","end":"2026-01-31T21:23:39.265007Z","steps":["trace[1114968880] 'agreement among raft nodes before linearized reading' (duration: 175.472202ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:39.265567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.198555ms","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-31T21:23:39.265591Z","caller":"traceutil/trace.go:171","msg":"trace[1319333569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:756; }","duration":"175.232826ms","start":"2026-01-31T21:23:39.09035Z","end":"2026-01-31T21:23:39.265583Z","steps":["trace[1319333569] 'agreement among raft nodes before linearized reading' (duration: 175.169975ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T21:23:40.08078Z","caller":"traceutil/trace.go:171","msg":"trace[1958789242] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:784; }","duration":"216.446533ms","start":"2026-01-31T21:23:39.864314Z","end":"2026-01-31T21:23:40.08076Z","steps":["trace[1958789242] 'read index received' (duration: 180.498942ms)","trace[1958789242] 'applied index is now lower than readState.Index' (duration: 35.946281ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T21:23:40.080946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.615617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T21:23:40.080977Z","caller":"traceutil/trace.go:171","msg":"trace[1288671128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:759; }","duration":"216.684659ms","start":"2026-01-31T21:23:39.864284Z","end":"2026-01-31T21:23:40.080969Z","steps":["trace[1288671128] 'agreement among raft nodes before linearized reading' (duration: 216.620047ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:45.537188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.100215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-31T21:23:45.537262Z","caller":"traceutil/trace.go:171","msg":"trace[718278237] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:772; }","duration":"255.215827ms","start":"2026-01-31T21:23:45.282028Z","end":"2026-01-31T21:23:45.537244Z","steps":["trace[718278237] 'range keys from in-memory index tree' (duration: 254.921651ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:45.537605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.312994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-31T21:23:45.537648Z","caller":"traceutil/trace.go:171","msg":"trace[347787783] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:772; }","duration":"207.378175ms","start":"2026-01-31T21:23:45.33026Z","end":"2026-01-31T21:23:45.537638Z","steps":["trace[347787783] 'range keys from in-memory index tree' (duration: 207.215872ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:45.537819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.991793ms","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-31T21:23:45.537855Z","caller":"traceutil/trace.go:171","msg":"trace[2096910327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:772; }","duration":"196.041405ms","start":"2026-01-31T21:23:45.341805Z","end":"2026-01-31T21:23:45.537846Z","steps":["trace[2096910327] 'range keys from in-memory index tree' (duration: 195.918242ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:45.538016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.27118ms","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-31T21:23:45.538049Z","caller":"traceutil/trace.go:171","msg":"trace[460509768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:772; }","duration":"196.344601ms","start":"2026-01-31T21:23:45.341697Z","end":"2026-01-31T21:23:45.538042Z","steps":["trace[460509768] 'range keys from in-memory index tree' (duration: 196.031635ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T21:23:49.751471Z","caller":"traceutil/trace.go:171","msg":"trace[1869214687] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"146.179737ms","start":"2026-01-31T21:23:49.605272Z","end":"2026-01-31T21:23:49.751452Z","steps":["trace[1869214687] 'process raft request' (duration: 135.254447ms)","trace[1869214687] 'compare' (duration: 10.828168ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T21:23:49.771177Z","caller":"traceutil/trace.go:171","msg":"trace[1928477144] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"163.644722ms","start":"2026-01-31T21:23:49.607508Z","end":"2026-01-31T21:23:49.771152Z","steps":["trace[1928477144] 'process raft request' (duration: 163.600641ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T21:23:49.771436Z","caller":"traceutil/trace.go:171","msg":"trace[1378029666] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"165.633076ms","start":"2026-01-31T21:23:49.605723Z","end":"2026-01-31T21:23:49.771356Z","steps":["trace[1378029666] 'process raft request' (duration: 165.254138ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:51.575399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.628997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T21:23:51.575475Z","caller":"traceutil/trace.go:171","msg":"trace[314759942] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:788; }","duration":"316.74446ms","start":"2026-01-31T21:23:51.258709Z","end":"2026-01-31T21:23:51.575454Z","steps":["trace[314759942] 'count revisions from in-memory index tree' (duration: 316.540335ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:51.57554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:23:51.258691Z","time spent":"316.814651ms","remote":"127.0.0.1:41434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-01-31T21:23:51.71293Z","caller":"traceutil/trace.go:171","msg":"trace[1497351795] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"104.617711ms","start":"2026-01-31T21:23:51.60828Z","end":"2026-01-31T21:23:51.712898Z","steps":["trace[1497351795] 'process raft request' (duration: 104.524289ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T21:23:51.886592Z","caller":"traceutil/trace.go:171","msg":"trace[732821178] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"110.715617ms","start":"2026-01-31T21:23:51.775858Z","end":"2026-01-31T21:23:51.886574Z","steps":["trace[732821178] 'read index received' (duration: 110.298158ms)","trace[732821178] 'applied index is now lower than readState.Index' (duration: 416.649µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T21:23:51.88697Z","caller":"traceutil/trace.go:171","msg":"trace[258092446] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"127.924326ms","start":"2026-01-31T21:23:51.759033Z","end":"2026-01-31T21:23:51.886957Z","steps":["trace[258092446] 'process raft request' (duration: 127.169459ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:51.887944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.068457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-31T21:23:51.888907Z","caller":"traceutil/trace.go:171","msg":"trace[881056963] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:790; }","duration":"113.057688ms","start":"2026-01-31T21:23:51.775824Z","end":"2026-01-31T21:23:51.888882Z","steps":["trace[881056963] 'agreement among raft nodes before linearized reading' (duration: 111.543005ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:23:51.889542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.443655ms","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-31T21:23:51.889748Z","caller":"traceutil/trace.go:171","msg":"trace[227510046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:790; }","duration":"112.67079ms","start":"2026-01-31T21:23:51.777064Z","end":"2026-01-31T21:23:51.889734Z","steps":["trace[227510046] 'agreement among raft nodes before linearized reading' (duration: 112.393683ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:24:05.661569Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810050206772595,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T21:24:06.162459Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810050206772595,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-31T21:24:06.598369Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.555277636s","expected-duration":"1s"} {"level":"info","ts":"2026-01-31T21:24:06.598618Z","caller":"traceutil/trace.go:171","msg":"trace[906092312] linearizableReadLoop","detail":"{readStateIndex:911; appliedIndex:910; }","duration":"1.437442679s","start":"2026-01-31T21:24:05.161159Z","end":"2026-01-31T21:24:06.598602Z","steps":["trace[906092312] 'read index received' (duration: 1.437311226s)","trace[906092312] 'applied index is now lower than readState.Index' (duration: 130.853µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T21:24:06.598828Z","caller":"traceutil/trace.go:171","msg":"trace[1241975135] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"1.555844449s","start":"2026-01-31T21:24:05.042974Z","end":"2026-01-31T21:24:06.598819Z","steps":["trace[1241975135] 'process raft request' (duration: 1.555535792s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:24:06.598899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:24:05.042954Z","time spent":"1.555891439s","remote":"127.0.0.1:53260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T21:24:06.599089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.437910169s","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-31T21:24:06.599108Z","caller":"traceutil/trace.go:171","msg":"trace[413560977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:878; }","duration":"1.43794702s","start":"2026-01-31T21:24:05.161155Z","end":"2026-01-31T21:24:06.599102Z","steps":["trace[413560977] 'agreement among raft nodes before linearized reading' (duration: 1.437856298s)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:24:06.599126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:24:05.161116Z","time spent":"1.438005581s","remote":"127.0.0.1:53260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-31T21:24:06.599237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.405571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-31T21:24:06.599253Z","caller":"traceutil/trace.go:171","msg":"trace[328707935] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:878; }","duration":"835.446532ms","start":"2026-01-31T21:24:05.763802Z","end":"2026-01-31T21:24:06.599248Z","steps":["trace[328707935] 'agreement among raft nodes before linearized reading' (duration: 835.397931ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:24:06.599268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:24:05.76379Z","time spent":"835.474722ms","remote":"127.0.0.1:53260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-31T21:24:06.599797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.179329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-31T21:24:06.599835Z","caller":"traceutil/trace.go:171","msg":"trace[1792668511] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:878; }","duration":"586.230549ms","start":"2026-01-31T21:24:06.013598Z","end":"2026-01-31T21:24:06.599828Z","steps":["trace[1792668511] 'agreement among raft nodes before linearized reading' (duration: 586.151128ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:24:06.599857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:24:06.013593Z","time spent":"586.25745ms","remote":"127.0.0.1:53260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-31T21:24:06.599964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.81944ms","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-31T21:24:06.599981Z","caller":"traceutil/trace.go:171","msg":"trace[376595931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:878; }","duration":"590.852421ms","start":"2026-01-31T21:24:06.009124Z","end":"2026-01-31T21:24:06.599976Z","steps":["trace[376595931] 'agreement among raft nodes before linearized reading' (duration: 590.80471ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:24:06.599996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:24:06.009111Z","time spent":"590.881211ms","remote":"127.0.0.1:53260","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":"warn","ts":"2026-01-31T21:24:06.600115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.258967ms","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-31T21:24:06.600133Z","caller":"traceutil/trace.go:171","msg":"trace[1073093577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:878; }","duration":"627.310647ms","start":"2026-01-31T21:24:05.972816Z","end":"2026-01-31T21:24:06.600127Z","steps":["trace[1073093577] 'agreement among raft nodes before linearized reading' (duration: 627.264236ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:24:06.600148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:24:05.972798Z","time spent":"627.346658ms","remote":"127.0.0.1:53260","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-31T21:24:12.971628Z","caller":"traceutil/trace.go:171","msg":"trace[1911152265] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"289.699058ms","start":"2026-01-31T21:24:12.681907Z","end":"2026-01-31T21:24:12.971606Z","steps":["trace[1911152265] 'process raft request' (duration: 274.768048ms)","trace[1911152265] 'compare' (duration: 14.522662ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T21:24:12.998407Z","caller":"traceutil/trace.go:171","msg":"trace[1769493612] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"315.481049ms","start":"2026-01-31T21:24:12.68291Z","end":"2026-01-31T21:24:12.998391Z","steps":["trace[1769493612] 'process raft request' (duration: 315.458529ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T21:24:12.998452Z","caller":"traceutil/trace.go:171","msg":"trace[1892406652] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"316.40413ms","start":"2026-01-31T21:24:12.682016Z","end":"2026-01-31T21:24:12.99842Z","steps":["trace[1892406652] 'process raft request' (duration: 316.254617ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:24:12.998505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:24:12.682888Z","time spent":"315.564372ms","remote":"127.0.0.1:53260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T21:24:12.998596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:24:12.682011Z","time spent":"316.518932ms","remote":"127.0.0.1:53260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T21:24:12.99865Z","caller":"traceutil/trace.go:171","msg":"trace[2130082119] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"316.573533ms","start":"2026-01-31T21:24:12.682068Z","end":"2026-01-31T21:24:12.998642Z","steps":["trace[2130082119] 'process raft request' (duration: 316.281946ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T21:24:12.998679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T21:24:12.682045Z","time spent":"316.617594ms","remote":"127.0.0.1:53260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T21:24:14.81515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.441385ms","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-31T21:24:14.815353Z","caller":"traceutil/trace.go:171","msg":"trace[1392605951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:900; }","duration":"130.673341ms","start":"2026-01-31T21:24:14.684667Z","end":"2026-01-31T21:24:14.815341Z","steps":["trace[1392605951] 'range keys from in-memory index tree' (duration: 130.324962ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T21:24:27.068454Z","caller":"traceutil/trace.go:171","msg":"trace[1118321976] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"192.968059ms","start":"2026-01-31T21:24:26.875471Z","end":"2026-01-31T21:24:27.068439Z","steps":["trace[1118321976] 'process raft request' (duration: 192.737474ms)"],"step_count":1}