{"level":"warn","ts":"2026-01-19T22:09:38.111235Z","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-19T22:09:38.111371Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.213: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.213:2380","--initial-cluster=instance=https://162.253.55.213:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.213:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.213: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-19T22:09:38.111467Z","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-19T22:09:38.111521Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.213:2380"]} {"level":"info","ts":"2026-01-19T22:09:38.111563Z","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-19T22:09:38.112486Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.213:2379"]} {"level":"info","ts":"2026-01-19T22:09:38.112668Z","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.213:2380"],"listen-peer-urls":["https://162.253.55.213:2380"],"advertise-client-urls":["https://162.253.55.213:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.213:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.213: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-19T22:09:38.120177Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.583781ms"} {"level":"info","ts":"2026-01-19T22:09:38.146963Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"43ce62d0a10d51da","cluster-id":"25e0c2268a6b42c6"} {"level":"info","ts":"2026-01-19T22:09:38.147139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da switched to configuration voters=()"} {"level":"info","ts":"2026-01-19T22:09:38.147224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became follower at term 0"} {"level":"info","ts":"2026-01-19T22:09:38.147315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 43ce62d0a10d51da [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-19T22:09:38.147372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became follower at term 1"} {"level":"info","ts":"2026-01-19T22:09:38.147444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da switched to configuration voters=(4885951293938291162)"} {"level":"warn","ts":"2026-01-19T22:09:38.15909Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-19T22:09:38.160632Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-19T22:09:38.162108Z","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-19T22:09:38.16413Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"43ce62d0a10d51da","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-19T22:09:38.175751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da switched to configuration voters=(4885951293938291162)"} {"level":"info","ts":"2026-01-19T22:09:38.175918Z","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-19T22:09:38.176004Z","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-19T22:09:38.17606Z","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-19T22:09:38.176183Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"43ce62d0a10d51da","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-19T22:09:38.176392Z","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-19T22:09:38.176633Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"43ce62d0a10d51da","initial-advertise-peer-urls":["https://162.253.55.213:2380"],"listen-peer-urls":["https://162.253.55.213:2380"],"advertise-client-urls":["https://162.253.55.213:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.213:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-19T22:09:38.176713Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-19T22:09:38.176852Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25e0c2268a6b42c6","local-member-id":"43ce62d0a10d51da","added-peer-id":"43ce62d0a10d51da","added-peer-peer-urls":["https://162.253.55.213:2380"]} {"level":"info","ts":"2026-01-19T22:09:38.177024Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.213:2380"} {"level":"info","ts":"2026-01-19T22:09:38.177093Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.213:2380"} {"level":"info","ts":"2026-01-19T22:09:38.248247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da is starting a new election at term 1"} {"level":"info","ts":"2026-01-19T22:09:38.24841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became pre-candidate at term 1"} {"level":"info","ts":"2026-01-19T22:09:38.248513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da received MsgPreVoteResp from 43ce62d0a10d51da at term 1"} {"level":"info","ts":"2026-01-19T22:09:38.248597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became candidate at term 2"} {"level":"info","ts":"2026-01-19T22:09:38.248673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da received MsgVoteResp from 43ce62d0a10d51da at term 2"} {"level":"info","ts":"2026-01-19T22:09:38.248743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became leader at term 2"} {"level":"info","ts":"2026-01-19T22:09:38.248822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 43ce62d0a10d51da elected leader 43ce62d0a10d51da at term 2"} {"level":"info","ts":"2026-01-19T22:09:38.250064Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"43ce62d0a10d51da","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.213:2379]}","request-path":"/0/members/43ce62d0a10d51da/attributes","cluster-id":"25e0c2268a6b42c6","publish-timeout":"7s"} {"level":"info","ts":"2026-01-19T22:09:38.250347Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-19T22:09:38.250439Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-19T22:09:38.250926Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-19T22:09:38.252427Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-19T22:09:38.252492Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-19T22:09:38.259857Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.213:2379"} {"level":"info","ts":"2026-01-19T22:09:38.263396Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-19T22:09:38.26542Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25e0c2268a6b42c6","local-member-id":"43ce62d0a10d51da","cluster-version":"3.5"} {"level":"info","ts":"2026-01-19T22:09:38.265511Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-19T22:09:38.265535Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-19T22:10:09.073766Z","caller":"traceutil/trace.go:171","msg":"trace[2081321355] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:489; }","duration":"267.85913ms","start":"2026-01-19T22:10:08.805892Z","end":"2026-01-19T22:10:09.073751Z","steps":["trace[2081321355] 'read index received' (duration: 267.737577ms)","trace[2081321355] 'applied index is now lower than readState.Index' (duration: 120.843µs)"],"step_count":2} {"level":"info","ts":"2026-01-19T22:10:09.073854Z","caller":"traceutil/trace.go:171","msg":"trace[931680883] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"271.344396ms","start":"2026-01-19T22:10:08.802504Z","end":"2026-01-19T22:10:09.073848Z","steps":["trace[931680883] 'process raft request' (duration: 271.138761ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:09.073996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.091155ms","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-19T22:10:09.074031Z","caller":"traceutil/trace.go:171","msg":"trace[931376871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:477; }","duration":"268.162857ms","start":"2026-01-19T22:10:08.805862Z","end":"2026-01-19T22:10:09.074025Z","steps":["trace[931376871] 'agreement among raft nodes before linearized reading' (duration: 268.065595ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:10:10.283917Z","caller":"traceutil/trace.go:171","msg":"trace[1655483572] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"267.875761ms","start":"2026-01-19T22:10:10.016016Z","end":"2026-01-19T22:10:10.283892Z","steps":["trace[1655483572] 'process raft request' (duration: 267.761208ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:10:12.422199Z","caller":"traceutil/trace.go:171","msg":"trace[554368052] linearizableReadLoop","detail":"{readStateIndex:499; appliedIndex:498; }","duration":"211.528548ms","start":"2026-01-19T22:10:12.210644Z","end":"2026-01-19T22:10:12.422172Z","steps":["trace[554368052] 'read index received' (duration: 175.513366ms)","trace[554368052] 'applied index is now lower than readState.Index' (duration: 36.014152ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T22:10:12.422227Z","caller":"traceutil/trace.go:171","msg":"trace[975288142] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"211.706331ms","start":"2026-01-19T22:10:12.210493Z","end":"2026-01-19T22:10:12.422199Z","steps":["trace[975288142] 'process raft request' (duration: 175.72781ms)","trace[975288142] 'compare' (duration: 35.805787ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:10:12.422367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.703271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T22:10:12.422399Z","caller":"traceutil/trace.go:171","msg":"trace[248289975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:485; }","duration":"211.761192ms","start":"2026-01-19T22:10:12.21063Z","end":"2026-01-19T22:10:12.422392Z","steps":["trace[248289975] 'agreement among raft nodes before linearized reading' (duration: 211.65264ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:12.422705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.442676ms","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-19T22:10:12.42275Z","caller":"traceutil/trace.go:171","msg":"trace[572737651] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:485; }","duration":"134.527608ms","start":"2026-01-19T22:10:12.288209Z","end":"2026-01-19T22:10:12.422737Z","steps":["trace[572737651] 'agreement among raft nodes before linearized reading' (duration: 134.434796ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:10:15.409374Z","caller":"traceutil/trace.go:171","msg":"trace[319772617] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"290.559322ms","start":"2026-01-19T22:10:15.118793Z","end":"2026-01-19T22:10:15.409352Z","steps":["trace[319772617] 'process raft request' (duration: 290.393088ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:10:15.409658Z","caller":"traceutil/trace.go:171","msg":"trace[1807803750] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:506; }","duration":"235.53213ms","start":"2026-01-19T22:10:15.174119Z","end":"2026-01-19T22:10:15.409651Z","steps":["trace[1807803750] 'read index received' (duration: 235.52945ms)","trace[1807803750] 'applied index is now lower than readState.Index' (duration: 2.16µs)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:10:15.409764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.922663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-01-19T22:10:15.409781Z","caller":"traceutil/trace.go:171","msg":"trace[1878731054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:492; }","duration":"232.944744ms","start":"2026-01-19T22:10:15.176831Z","end":"2026-01-19T22:10:15.409776Z","steps":["trace[1878731054] 'agreement among raft nodes before linearized reading' (duration: 232.915463ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:15.409891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.612842ms","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-19T22:10:15.409906Z","caller":"traceutil/trace.go:171","msg":"trace[1233935368] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:492; }","duration":"235.806205ms","start":"2026-01-19T22:10:15.174095Z","end":"2026-01-19T22:10:15.409901Z","steps":["trace[1233935368] 'agreement among raft nodes before linearized reading' (duration: 235.618571ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:15.648342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.261781ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T22:10:15.648507Z","caller":"traceutil/trace.go:171","msg":"trace[662641539] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"235.11932ms","start":"2026-01-19T22:10:15.413372Z","end":"2026-01-19T22:10:15.648491Z","steps":["trace[662641539] 'process raft request' (duration: 235.057018ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:10:15.648767Z","caller":"traceutil/trace.go:171","msg":"trace[48734380] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"235.499738ms","start":"2026-01-19T22:10:15.413256Z","end":"2026-01-19T22:10:15.648756Z","steps":["trace[48734380] 'process raft request' (duration: 89.249736ms)","trace[48734380] 'compare' (duration: 145.146969ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T22:10:29.309383Z","caller":"traceutil/trace.go:171","msg":"trace[328760532] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"110.557458ms","start":"2026-01-19T22:10:29.198807Z","end":"2026-01-19T22:10:29.309365Z","steps":["trace[328760532] 'read index received' (duration: 17.847127ms)","trace[328760532] 'applied index is now lower than readState.Index' (duration: 92.707341ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:10:29.309501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.67478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T22:10:29.309523Z","caller":"traceutil/trace.go:171","msg":"trace[1870695012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"110.738842ms","start":"2026-01-19T22:10:29.198776Z","end":"2026-01-19T22:10:29.309515Z","steps":["trace[1870695012] 'agreement among raft nodes before linearized reading' (duration: 110.67201ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:10:29.309801Z","caller":"traceutil/trace.go:171","msg":"trace[1608109664] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"175.728761ms","start":"2026-01-19T22:10:29.134064Z","end":"2026-01-19T22:10:29.309793Z","steps":["trace[1608109664] 'process raft request' (duration: 82.578521ms)","trace[1608109664] 'compare' (duration: 92.638379ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:10:30.158625Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5898198015326382183,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T22:10:30.371566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.229365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T22:10:30.371657Z","caller":"traceutil/trace.go:171","msg":"trace[2071035008] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.051819354s","start":"2026-01-19T22:10:29.319819Z","end":"2026-01-19T22:10:30.371638Z","steps":["trace[2071035008] 'process raft request' (duration: 240.447035ms)","trace[2071035008] 'compare' (duration: 810.926889ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:10:30.37171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:29.319807Z","time spent":"1.051878116s","remote":"127.0.0.1:38318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1292,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T22:10:30.632058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.54912ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T22:10:30.632194Z","caller":"traceutil/trace.go:171","msg":"trace[11927981] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:658; }","duration":"974.519478ms","start":"2026-01-19T22:10:29.657664Z","end":"2026-01-19T22:10:30.632183Z","steps":["trace[11927981] 'read index received' (duration: 856.777143ms)","trace[11927981] 'applied index is now lower than readState.Index' (duration: 117.741795ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T22:10:30.632254Z","caller":"traceutil/trace.go:171","msg":"trace[1101449056] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.31210894s","start":"2026-01-19T22:10:29.320139Z","end":"2026-01-19T22:10:30.632248Z","steps":["trace[1101449056] 'process raft request' (duration: 1.194317014s)","trace[1101449056] 'compare' (duration: 117.460088ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T22:10:30.632271Z","caller":"traceutil/trace.go:171","msg":"trace[323763838] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.301174183s","start":"2026-01-19T22:10:29.331078Z","end":"2026-01-19T22:10:30.632252Z","steps":["trace[323763838] 'process raft request' (duration: 1.30107551s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:30.632387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:29.331049Z","time spent":"1.301271146s","remote":"127.0.0.1:38230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5008,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T22:10:30.632402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.741642ms","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-19T22:10:30.632422Z","caller":"traceutil/trace.go:171","msg":"trace[1903631031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"974.782534ms","start":"2026-01-19T22:10:29.657634Z","end":"2026-01-19T22:10:30.632416Z","steps":["trace[1903631031] 'agreement among raft nodes before linearized reading' (duration: 974.676231ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:30.63244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:29.657622Z","time spent":"974.814074ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-01-19T22:10:30.632561Z","caller":"traceutil/trace.go:171","msg":"trace[1578155915] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"1.302994042s","start":"2026-01-19T22:10:29.32956Z","end":"2026-01-19T22:10:30.632555Z","steps":["trace[1578155915] 'process raft request' (duration: 1.302558051s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:30.63259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:29.32955Z","time spent":"1.303025803s","remote":"127.0.0.1:38504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3866,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T22:10:30.632656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.100622ms","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-19T22:10:30.632683Z","caller":"traceutil/trace.go:171","msg":"trace[1867008676] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:642; }","duration":"869.153413ms","start":"2026-01-19T22:10:29.763522Z","end":"2026-01-19T22:10:30.632675Z","steps":["trace[1867008676] 'agreement among raft nodes before linearized reading' (duration: 869.111922ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:30.6327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:29.763509Z","time spent":"869.186984ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-19T22:10:30.632288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:29.320132Z","time spent":"1.312136462s","remote":"127.0.0.1:38204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":783,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T22:10:30.63295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.874766ms","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-19T22:10:30.632969Z","caller":"traceutil/trace.go:171","msg":"trace[1533131673] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:642; }","duration":"815.910287ms","start":"2026-01-19T22:10:29.817053Z","end":"2026-01-19T22:10:30.632963Z","steps":["trace[1533131673] 'agreement among raft nodes before linearized reading' (duration: 815.872497ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:30.632983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:29.817043Z","time spent":"815.937608ms","remote":"127.0.0.1:38314","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-19T22:10:30.633031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.968386ms","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-19T22:10:30.633042Z","caller":"traceutil/trace.go:171","msg":"trace[493541067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"826.005557ms","start":"2026-01-19T22:10:29.807033Z","end":"2026-01-19T22:10:30.633038Z","steps":["trace[493541067] 'agreement among raft nodes before linearized reading' (duration: 825.981606ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:30.633055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:29.807019Z","time spent":"826.033487ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-19T22:10:31.109699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.600789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T22:10:31.109769Z","caller":"traceutil/trace.go:171","msg":"trace[377942423] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"464.395573ms","start":"2026-01-19T22:10:30.64536Z","end":"2026-01-19T22:10:31.109756Z","steps":["trace[377942423] 'process raft request' (duration: 270.685351ms)","trace[377942423] 'compare' (duration: 193.510137ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:10:31.109806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:30.645342Z","time spent":"464.447224ms","remote":"127.0.0.1:38204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":745,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:10:31.113542Z","caller":"traceutil/trace.go:171","msg":"trace[1376332624] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:661; }","duration":"465.998867ms","start":"2026-01-19T22:10:30.647535Z","end":"2026-01-19T22:10:31.113534Z","steps":["trace[1376332624] 'read index received' (duration: 268.581196ms)","trace[1376332624] 'applied index is now lower than readState.Index' (duration: 197.416891ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:10:31.113644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.10252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-6q9hb\" ","response":"range_response_count:1 size:4697"} {"level":"info","ts":"2026-01-19T22:10:31.113677Z","caller":"traceutil/trace.go:171","msg":"trace[1562668128] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-6q9hb; range_end:; response_count:1; response_revision:650; }","duration":"466.13662ms","start":"2026-01-19T22:10:30.647519Z","end":"2026-01-19T22:10:31.113655Z","steps":["trace[1562668128] 'agreement among raft nodes before linearized reading' (duration: 466.048698ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:31.113696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:30.647515Z","time spent":"466.176481ms","remote":"127.0.0.1:38230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4719,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-6q9hb\" "} {"level":"info","ts":"2026-01-19T22:10:31.113854Z","caller":"traceutil/trace.go:171","msg":"trace[40179041] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"468.276517ms","start":"2026-01-19T22:10:30.64557Z","end":"2026-01-19T22:10:31.113846Z","steps":["trace[40179041] 'process raft request' (duration: 467.768586ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:31.113938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:30.64556Z","time spent":"468.355308ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:10:31.114014Z","caller":"traceutil/trace.go:171","msg":"trace[1630456175] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"468.40621ms","start":"2026-01-19T22:10:30.645602Z","end":"2026-01-19T22:10:31.114008Z","steps":["trace[1630456175] 'process raft request' (duration: 467.779356ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:31.114042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:30.645599Z","time spent":"468.427721ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:10:31.114234Z","caller":"traceutil/trace.go:171","msg":"trace[2024426965] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"449.49526ms","start":"2026-01-19T22:10:30.664731Z","end":"2026-01-19T22:10:31.114227Z","steps":["trace[2024426965] 'process raft request' (duration: 448.783704ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:31.114271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:30.664725Z","time spent":"449.526021ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:10:31.114352Z","caller":"traceutil/trace.go:171","msg":"trace[1615326848] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"466.880117ms","start":"2026-01-19T22:10:30.647466Z","end":"2026-01-19T22:10:31.114347Z","steps":["trace[1615326848] 'process raft request' (duration: 465.947326ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:31.114379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:30.647461Z","time spent":"466.903917ms","remote":"127.0.0.1:38318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1297,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:10:31.114412Z","caller":"traceutil/trace.go:171","msg":"trace[1810367855] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"466.910658ms","start":"2026-01-19T22:10:30.647498Z","end":"2026-01-19T22:10:31.114408Z","steps":["trace[1810367855] 'process raft request' (duration: 465.942116ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:31.11443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:30.647495Z","time spent":"466.925258ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:10:31.114478Z","caller":"traceutil/trace.go:171","msg":"trace[167540585] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"466.914767ms","start":"2026-01-19T22:10:30.64756Z","end":"2026-01-19T22:10:31.114474Z","steps":["trace[167540585] 'process raft request' (duration: 465.899215ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:31.114508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:30.647556Z","time spent":"466.940648ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:10:31.114643Z","caller":"traceutil/trace.go:171","msg":"trace[706240244] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"459.732752ms","start":"2026-01-19T22:10:30.654906Z","end":"2026-01-19T22:10:31.114639Z","steps":["trace[706240244] 'process raft request' (duration: 458.571346ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:31.114668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:30.654898Z","time spent":"459.755992ms","remote":"127.0.0.1:38504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T22:10:55.967742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.456382ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T22:10:55.967895Z","caller":"traceutil/trace.go:171","msg":"trace[122501522] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:762; }","duration":"705.478963ms","start":"2026-01-19T22:10:55.262404Z","end":"2026-01-19T22:10:55.967883Z","steps":["trace[122501522] 'read index received' (duration: 235.893987ms)","trace[122501522] 'applied index is now lower than readState.Index' (duration: 469.584256ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T22:10:55.968057Z","caller":"traceutil/trace.go:171","msg":"trace[164230198] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"741.472673ms","start":"2026-01-19T22:10:55.226577Z","end":"2026-01-19T22:10:55.968049Z","steps":["trace[164230198] 'process raft request' (duration: 265.632452ms)","trace[164230198] 'compare' (duration: 475.119935ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:10:55.968106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:55.226566Z","time spent":"741.517904ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T22:10:55.969514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.103677ms","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-19T22:10:55.969547Z","caller":"traceutil/trace.go:171","msg":"trace[919237872] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:740; }","duration":"707.151538ms","start":"2026-01-19T22:10:55.262387Z","end":"2026-01-19T22:10:55.969538Z","steps":["trace[919237872] 'agreement among raft nodes before linearized reading' (duration: 707.048866ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:55.969572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:55.262377Z","time spent":"707.190979ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-01-19T22:10:55.969629Z","caller":"traceutil/trace.go:171","msg":"trace[1531421870] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"740.778438ms","start":"2026-01-19T22:10:55.228844Z","end":"2026-01-19T22:10:55.969622Z","steps":["trace[1531421870] 'process raft request' (duration: 738.971669ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:55.969668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:55.228839Z","time spent":"740.808438ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:10:55.96972Z","caller":"traceutil/trace.go:171","msg":"trace[780765181] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"500.125318ms","start":"2026-01-19T22:10:55.469588Z","end":"2026-01-19T22:10:55.969713Z","steps":["trace[780765181] 'process raft request' (duration: 498.262398ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:55.969752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:55.469573Z","time spent":"500.163108ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T22:10:55.969843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.99213ms","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-19T22:10:55.969865Z","caller":"traceutil/trace.go:171","msg":"trace[661131692] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:740; }","duration":"693.014621ms","start":"2026-01-19T22:10:55.276845Z","end":"2026-01-19T22:10:55.969859Z","steps":["trace[661131692] 'agreement among raft nodes before linearized reading' (duration: 692.96022ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:10:55.969884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:10:55.276825Z","time spent":"693.053812ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-19T22:10:55.96994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.919343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T22:10:55.969962Z","caller":"traceutil/trace.go:171","msg":"trace[26964570] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:740; }","duration":"186.957084ms","start":"2026-01-19T22:10:55.782999Z","end":"2026-01-19T22:10:55.969956Z","steps":["trace[26964570] 'agreement among raft nodes before linearized reading' (duration: 186.927963ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:00.342859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.498847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-19T22:11:00.342963Z","caller":"traceutil/trace.go:171","msg":"trace[1274687532] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:752; }","duration":"207.66163ms","start":"2026-01-19T22:11:00.135284Z","end":"2026-01-19T22:11:00.342946Z","steps":["trace[1274687532] 'count revisions from in-memory index tree' (duration: 207.386624ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:00.343015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.139956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T22:11:00.343089Z","caller":"traceutil/trace.go:171","msg":"trace[1966862460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:752; }","duration":"146.264519ms","start":"2026-01-19T22:11:00.19681Z","end":"2026-01-19T22:11:00.343074Z","steps":["trace[1966862460] 'range keys from in-memory index tree' (duration: 146.072645ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:11:01.85783Z","caller":"traceutil/trace.go:171","msg":"trace[228794062] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:778; }","duration":"292.446086ms","start":"2026-01-19T22:11:01.565362Z","end":"2026-01-19T22:11:01.857808Z","steps":["trace[228794062] 'read index received' (duration: 292.228692ms)","trace[228794062] 'applied index is now lower than readState.Index' (duration: 216.404µs)"],"step_count":2} {"level":"info","ts":"2026-01-19T22:11:01.858043Z","caller":"traceutil/trace.go:171","msg":"trace[1370123661] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"612.927561ms","start":"2026-01-19T22:11:01.2451Z","end":"2026-01-19T22:11:01.858027Z","steps":["trace[1370123661] 'process raft request' (duration: 612.587244ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:01.858128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.63128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-19T22:11:01.858835Z","caller":"traceutil/trace.go:171","msg":"trace[1004333888] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:753; }","duration":"293.456408ms","start":"2026-01-19T22:11:01.565319Z","end":"2026-01-19T22:11:01.858775Z","steps":["trace[1004333888] 'agreement among raft nodes before linearized reading' (duration: 292.647391ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:01.858142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:01.245085Z","time spent":"613.001832ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T22:11:14.505666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.622508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-01-19T22:11:14.505718Z","caller":"traceutil/trace.go:171","msg":"trace[1529232130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:840; }","duration":"428.70116ms","start":"2026-01-19T22:11:14.077004Z","end":"2026-01-19T22:11:14.505705Z","steps":["trace[1529232130] 'range keys from in-memory index tree' (duration: 428.523906ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:14.505741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:14.076989Z","time spent":"428.74636ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-19T22:11:14.505905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.794752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-01-19T22:11:14.505924Z","caller":"traceutil/trace.go:171","msg":"trace[1507066032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:840; }","duration":"428.846293ms","start":"2026-01-19T22:11:14.077074Z","end":"2026-01-19T22:11:14.50592Z","steps":["trace[1507066032] 'range keys from in-memory index tree' (duration: 428.71178ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:14.505937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:14.077057Z","time spent":"428.876333ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-19T22:11:14.506022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.689483ms","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-19T22:11:14.506038Z","caller":"traceutil/trace.go:171","msg":"trace[1968194365] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:840; }","duration":"394.728274ms","start":"2026-01-19T22:11:14.111305Z","end":"2026-01-19T22:11:14.506034Z","steps":["trace[1968194365] 'range keys from in-memory index tree' (duration: 394.631042ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:14.50605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:14.111291Z","time spent":"394.755164ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-19T22:11:14.506131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.992474ms","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-19T22:11:14.506148Z","caller":"traceutil/trace.go:171","msg":"trace[1385686759] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:840; }","duration":"374.035555ms","start":"2026-01-19T22:11:14.132109Z","end":"2026-01-19T22:11:14.506144Z","steps":["trace[1385686759] 'range keys from in-memory index tree' (duration: 373.914382ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:14.506159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:14.132095Z","time spent":"374.061385ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-19T22:11:15.158473Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5898198015326382675,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-19T22:11:15.276937Z","caller":"traceutil/trace.go:171","msg":"trace[1150054322] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"767.89148ms","start":"2026-01-19T22:11:14.50903Z","end":"2026-01-19T22:11:15.276922Z","steps":["trace[1150054322] 'process raft request' (duration: 767.803088ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:15.277035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:14.509014Z","time spent":"767.970782ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:11:15.713391Z","caller":"traceutil/trace.go:171","msg":"trace[1819643805] linearizableReadLoop","detail":"{readStateIndex:875; appliedIndex:872; }","duration":"1.055107204s","start":"2026-01-19T22:11:14.658265Z","end":"2026-01-19T22:11:15.713372Z","steps":["trace[1819643805] 'read index received' (duration: 619.059365ms)","trace[1819643805] 'applied index is now lower than readState.Index' (duration: 436.046969ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T22:11:15.713626Z","caller":"traceutil/trace.go:171","msg":"trace[1570637962] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"1.202811635s","start":"2026-01-19T22:11:14.510805Z","end":"2026-01-19T22:11:15.713616Z","steps":["trace[1570637962] 'process raft request' (duration: 1.202009028s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:15.713717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:14.510791Z","time spent":"1.202863166s","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T22:11:15.713854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055562274s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-01-19T22:11:15.713908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.200334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T22:11:15.713919Z","caller":"traceutil/trace.go:171","msg":"trace[1326065688] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:844; }","duration":"1.055663736s","start":"2026-01-19T22:11:14.65824Z","end":"2026-01-19T22:11:15.713903Z","steps":["trace[1326065688] 'agreement among raft nodes before linearized reading' (duration: 1.055539484s)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:11:15.713944Z","caller":"traceutil/trace.go:171","msg":"trace[962377561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:844; }","duration":"515.236035ms","start":"2026-01-19T22:11:15.198697Z","end":"2026-01-19T22:11:15.713933Z","steps":["trace[962377561] 'agreement among raft nodes before linearized reading' (duration: 515.182324ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:15.713968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:14.658228Z","time spent":"1.055729087s","remote":"127.0.0.1:38112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":6,"response size":29,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-01-19T22:11:15.714166Z","caller":"traceutil/trace.go:171","msg":"trace[1960171689] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"1.203308046s","start":"2026-01-19T22:11:14.510846Z","end":"2026-01-19T22:11:15.714154Z","steps":["trace[1960171689] 'process raft request' (duration: 1.202436237s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:15.714247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:14.510843Z","time spent":"1.203347656s","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:11:15.714395Z","caller":"traceutil/trace.go:171","msg":"trace[2031055440] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"1.203293314s","start":"2026-01-19T22:11:14.511093Z","end":"2026-01-19T22:11:15.714386Z","steps":["trace[2031055440] 'process raft request' (duration: 1.202243562s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:15.714444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:14.511087Z","time spent":"1.203330046s","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T22:11:15.713973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:15.19866Z","time spent":"515.306336ms","remote":"127.0.0.1:38014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-19T22:11:15.715136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.61206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-19T22:11:15.715172Z","caller":"traceutil/trace.go:171","msg":"trace[487898113] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:844; }","duration":"435.676591ms","start":"2026-01-19T22:11:15.279485Z","end":"2026-01-19T22:11:15.715161Z","steps":["trace[487898113] 'agreement among raft nodes before linearized reading' (duration: 435.61552ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:15.715199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:15.279468Z","time spent":"435.723683ms","remote":"127.0.0.1:38354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-01-19T22:11:23.633277Z","caller":"traceutil/trace.go:171","msg":"trace[2048758267] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"316.743354ms","start":"2026-01-19T22:11:23.316516Z","end":"2026-01-19T22:11:23.633259Z","steps":["trace[2048758267] 'process raft request' (duration: 316.641552ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:23.633528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:23.316508Z","time spent":"316.967019ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T22:11:24.021728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.071991ms","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-19T22:11:24.021776Z","caller":"traceutil/trace.go:171","msg":"trace[286752274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:864; }","duration":"269.138382ms","start":"2026-01-19T22:11:23.752625Z","end":"2026-01-19T22:11:24.021763Z","steps":["trace[286752274] 'range keys from in-memory index tree' (duration: 268.980939ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:24.021865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.206136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-01-19T22:11:24.021901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.038819ms","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-19T22:11:24.021917Z","caller":"traceutil/trace.go:171","msg":"trace[1297129643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:864; }","duration":"268.07488ms","start":"2026-01-19T22:11:23.753837Z","end":"2026-01-19T22:11:24.021912Z","steps":["trace[1297129643] 'range keys from in-memory index tree' (duration: 267.980138ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:11:24.021919Z","caller":"traceutil/trace.go:171","msg":"trace[739947421] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:864; }","duration":"276.300828ms","start":"2026-01-19T22:11:23.745604Z","end":"2026-01-19T22:11:24.021905Z","steps":["trace[739947421] 'range keys from in-memory index tree' (duration: 276.061473ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:11:25.848574Z","caller":"traceutil/trace.go:171","msg":"trace[353593434] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"210.094132ms","start":"2026-01-19T22:11:25.638464Z","end":"2026-01-19T22:11:25.848559Z","steps":["trace[353593434] 'process raft request' (duration: 209.98291ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:11:26.22562Z","caller":"traceutil/trace.go:171","msg":"trace[1607185544] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"147.988517ms","start":"2026-01-19T22:11:26.077618Z","end":"2026-01-19T22:11:26.225607Z","steps":["trace[1607185544] 'process raft request' (duration: 147.905146ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:11:26.378445Z","caller":"traceutil/trace.go:171","msg":"trace[88143688] linearizableReadLoop","detail":"{readStateIndex:905; appliedIndex:903; }","duration":"255.843414ms","start":"2026-01-19T22:11:26.122581Z","end":"2026-01-19T22:11:26.378424Z","steps":["trace[88143688] 'read index received' (duration: 103.298389ms)","trace[88143688] 'applied index is now lower than readState.Index' (duration: 152.544485ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T22:11:26.378605Z","caller":"traceutil/trace.go:171","msg":"trace[1583446578] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"299.068781ms","start":"2026-01-19T22:11:26.07953Z","end":"2026-01-19T22:11:26.378599Z","steps":["trace[1583446578] 'process raft request' (duration: 217.725988ms)","trace[1583446578] 'compare' (duration: 81.032126ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:11:26.378702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.989627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T22:11:26.378745Z","caller":"traceutil/trace.go:171","msg":"trace[926451571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:872; }","duration":"184.073518ms","start":"2026-01-19T22:11:26.19466Z","end":"2026-01-19T22:11:26.378734Z","steps":["trace[926451571] 'agreement among raft nodes before linearized reading' (duration: 183.989136ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:11:26.37879Z","caller":"traceutil/trace.go:171","msg":"trace[861898123] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"298.169411ms","start":"2026-01-19T22:11:26.080615Z","end":"2026-01-19T22:11:26.378785Z","steps":["trace[861898123] 'process raft request' (duration: 297.785103ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:26.378912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.331035ms","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-19T22:11:26.378927Z","caller":"traceutil/trace.go:171","msg":"trace[869096143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:872; }","duration":"256.370045ms","start":"2026-01-19T22:11:26.122553Z","end":"2026-01-19T22:11:26.378923Z","steps":["trace[869096143] 'agreement among raft nodes before linearized reading' (duration: 256.296613ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:28.59442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.71759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-01-19T22:11:28.594487Z","caller":"traceutil/trace.go:171","msg":"trace[1701132714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:876; }","duration":"212.838102ms","start":"2026-01-19T22:11:28.381633Z","end":"2026-01-19T22:11:28.594471Z","steps":["trace[1701132714] 'range keys from in-memory index tree' (duration: 212.554566ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:28.594747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.577077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-01-19T22:11:28.594776Z","caller":"traceutil/trace.go:171","msg":"trace[1812032646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:876; }","duration":"212.617738ms","start":"2026-01-19T22:11:28.38215Z","end":"2026-01-19T22:11:28.594767Z","steps":["trace[1812032646] 'range keys from in-memory index tree' (duration: 212.503215ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:28.594933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.200815ms","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-19T22:11:28.594958Z","caller":"traceutil/trace.go:171","msg":"trace[1031058126] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:876; }","duration":"210.237806ms","start":"2026-01-19T22:11:28.384713Z","end":"2026-01-19T22:11:28.59495Z","steps":["trace[1031058126] 'range keys from in-memory index tree' (duration: 210.135644ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:11:34.216387Z","caller":"traceutil/trace.go:171","msg":"trace[1918810317] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"135.765361ms","start":"2026-01-19T22:11:34.080606Z","end":"2026-01-19T22:11:34.216371Z","steps":["trace[1918810317] 'process raft request' (duration: 135.435114ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:47.286263Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5898198015326383010,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T22:11:47.787084Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5898198015326383010,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T22:11:48.208313Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.481564776s","expected-duration":"1s"} {"level":"info","ts":"2026-01-19T22:11:48.215736Z","caller":"traceutil/trace.go:171","msg":"trace[158181819] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"1.489034828s","start":"2026-01-19T22:11:46.72668Z","end":"2026-01-19T22:11:48.215715Z","steps":["trace[158181819] 'process raft request' (duration: 1.488917825s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:48.215838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:46.726668Z","time spent":"1.489105909s","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T22:11:48.22269Z","caller":"traceutil/trace.go:171","msg":"trace[1701290697] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"1.067363811s","start":"2026-01-19T22:11:47.155298Z","end":"2026-01-19T22:11:48.222662Z","steps":["trace[1701290697] 'process raft request' (duration: 1.067144976s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:48.222915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:47.15528Z","time spent":"1.067542134s","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T22:11:48.363316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.311531ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-01-19T22:11:48.363457Z","caller":"traceutil/trace.go:171","msg":"trace[1769281402] linearizableReadLoop","detail":"{readStateIndex:1033; appliedIndex:1031; }","duration":"1.577218959s","start":"2026-01-19T22:11:46.786185Z","end":"2026-01-19T22:11:48.363404Z","steps":["trace[1769281402] 'read index received' (duration: 1.429974819s)","trace[1769281402] 'applied index is now lower than readState.Index' (duration: 147.24312ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T22:11:48.363637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.577448464s","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-19T22:11:48.363666Z","caller":"traceutil/trace.go:171","msg":"trace[1022547128] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:995; }","duration":"1.577520266s","start":"2026-01-19T22:11:46.786137Z","end":"2026-01-19T22:11:48.363658Z","steps":["trace[1022547128] 'agreement among raft nodes before linearized reading' (duration: 1.577405083s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:48.363728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:46.786126Z","time spent":"1.577594287s","remote":"127.0.0.1:38314","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-19T22:11:48.364217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.574160272s","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-19T22:11:48.364259Z","caller":"traceutil/trace.go:171","msg":"trace[711000549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:995; }","duration":"1.574220554s","start":"2026-01-19T22:11:46.790029Z","end":"2026-01-19T22:11:48.364249Z","steps":["trace[711000549] 'agreement among raft nodes before linearized reading' (duration: 1.574119052s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:48.364287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:46.790017Z","time spent":"1.574263265s","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-19T22:11:48.365017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.023829ms","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-19T22:11:48.365059Z","caller":"traceutil/trace.go:171","msg":"trace[185660750] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:995; }","duration":"358.10123ms","start":"2026-01-19T22:11:48.006948Z","end":"2026-01-19T22:11:48.36505Z","steps":["trace[185660750] 'agreement among raft nodes before linearized reading' (duration: 358.011859ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:48.365386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.196723313s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-01-19T22:11:48.365427Z","caller":"traceutil/trace.go:171","msg":"trace[45480051] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:995; }","duration":"1.196794015s","start":"2026-01-19T22:11:47.168622Z","end":"2026-01-19T22:11:48.365416Z","steps":["trace[45480051] 'agreement among raft nodes before linearized reading' (duration: 1.196697963s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:48.365515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:47.168607Z","time spent":"1.196899087s","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":547,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-01-19T22:11:48.365829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.588948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"warn","ts":"2026-01-19T22:11:48.365086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:48.006929Z","time spent":"358.150231ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-01-19T22:11:48.365871Z","caller":"traceutil/trace.go:171","msg":"trace[512153425] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:995; }","duration":"871.67176ms","start":"2026-01-19T22:11:47.494187Z","end":"2026-01-19T22:11:48.365859Z","steps":["trace[512153425] 'agreement among raft nodes before linearized reading' (duration: 871.578638ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:48.366828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.143428189s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-19T22:11:48.366872Z","caller":"traceutil/trace.go:171","msg":"trace[162076479] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:995; }","duration":"1.14349308s","start":"2026-01-19T22:11:47.223369Z","end":"2026-01-19T22:11:48.366862Z","steps":["trace[162076479] 'agreement among raft nodes before linearized reading' (duration: 1.143393988s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:48.366902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:47.223362Z","time spent":"1.143532411s","remote":"127.0.0.1:38372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-01-19T22:11:48.367415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139416582s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T22:11:48.367444Z","caller":"traceutil/trace.go:171","msg":"trace[855028621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:995; }","duration":"1.139457993s","start":"2026-01-19T22:11:47.227976Z","end":"2026-01-19T22:11:48.367434Z","steps":["trace[855028621] 'agreement among raft nodes before linearized reading' (duration: 1.139409882s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:48.367469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:47.227969Z","time spent":"1.139494494s","remote":"127.0.0.1:38014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-19T22:11:48.36918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T22:11:47.49417Z","time spent":"871.957185ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-01-19T22:11:48.558178Z","caller":"traceutil/trace.go:171","msg":"trace[434975626] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1034; }","duration":"176.582047ms","start":"2026-01-19T22:11:48.381544Z","end":"2026-01-19T22:11:48.558126Z","steps":["trace[434975626] 'read index received' (duration: 8.705639ms)","trace[434975626] 'applied index is now lower than readState.Index' (duration: 167.875598ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T22:11:48.558477Z","caller":"traceutil/trace.go:171","msg":"trace[1161387449] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"180.112904ms","start":"2026-01-19T22:11:48.378349Z","end":"2026-01-19T22:11:48.558462Z","steps":["trace[1161387449] 'process raft request' (duration: 179.528551ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:11:48.559089Z","caller":"traceutil/trace.go:171","msg":"trace[1612545972] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"183.304323ms","start":"2026-01-19T22:11:48.375772Z","end":"2026-01-19T22:11:48.559076Z","steps":["trace[1612545972] 'process raft request' (duration: 181.851651ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:11:48.559175Z","caller":"traceutil/trace.go:171","msg":"trace[268735959] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"182.210849ms","start":"2026-01-19T22:11:48.376908Z","end":"2026-01-19T22:11:48.559119Z","steps":["trace[268735959] 'process raft request' (duration: 180.92549ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T22:11:48.560999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.433478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T22:11:48.559985Z","caller":"traceutil/trace.go:171","msg":"trace[1514674376] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"178.388365ms","start":"2026-01-19T22:11:48.381571Z","end":"2026-01-19T22:11:48.559959Z","steps":["trace[1514674376] 'process raft request' (duration: 176.450903ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:11:48.561313Z","caller":"traceutil/trace.go:171","msg":"trace[614404588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1002; }","duration":"179.663133ms","start":"2026-01-19T22:11:48.381528Z","end":"2026-01-19T22:11:48.561191Z","steps":["trace[614404588] 'agreement among raft nodes before linearized reading' (duration: 178.514518ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:11:48.56004Z","caller":"traceutil/trace.go:171","msg":"trace[884290370] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"179.366867ms","start":"2026-01-19T22:11:48.380667Z","end":"2026-01-19T22:11:48.560034Z","steps":["trace[884290370] 'process raft request' (duration: 177.285773ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:11:48.561807Z","caller":"traceutil/trace.go:171","msg":"trace[1806949779] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"180.062382ms","start":"2026-01-19T22:11:48.381732Z","end":"2026-01-19T22:11:48.561795Z","steps":["trace[1806949779] 'process raft request' (duration: 176.319201ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:14:19.074735Z","caller":"traceutil/trace.go:171","msg":"trace[1030794119] transaction","detail":"{read_only:false; response_revision:1609; number_of_response:1; }","duration":"182.718624ms","start":"2026-01-19T22:14:18.891992Z","end":"2026-01-19T22:14:19.07471Z","steps":["trace[1030794119] 'process raft request' (duration: 182.585591ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:14:19.088264Z","caller":"traceutil/trace.go:171","msg":"trace[1351081208] transaction","detail":"{read_only:false; response_revision:1610; number_of_response:1; }","duration":"194.881283ms","start":"2026-01-19T22:14:18.893351Z","end":"2026-01-19T22:14:19.088233Z","steps":["trace[1351081208] 'process raft request' (duration: 194.465284ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T22:14:53.982241Z","caller":"traceutil/trace.go:171","msg":"trace[421587934] transaction","detail":"{read_only:false; response_revision:1780; number_of_response:1; }","duration":"141.541558ms","start":"2026-01-19T22:14:53.840683Z","end":"2026-01-19T22:14:53.982224Z","steps":["trace[421587934] 'process raft request' (duration: 138.345818ms)"],"step_count":1}