{"level":"warn","ts":"2026-03-08T02:23:01.115029Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-08T02:23:01.115103Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.33: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://199.204.45.33:2380","--initial-cluster=instance=https://199.204.45.33:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.33:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.33: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-03-08T02:23:01.115159Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-08T02:23:01.115171Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-03-08T02:23:01.115186Z","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-03-08T02:23:01.115794Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"]} {"level":"info","ts":"2026-03-08T02:23:01.115868Z","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":8,"max-cpu-available":8,"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://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.33: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-03-08T02:23:01.120761Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.697392ms"} {"level":"info","ts":"2026-03-08T02:23:01.132948Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e5729930cf7e1f","cluster-id":"14aeb8c3ec66b749"} {"level":"info","ts":"2026-03-08T02:23:01.133035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=()"} {"level":"info","ts":"2026-03-08T02:23:01.133062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 0"} {"level":"info","ts":"2026-03-08T02:23:01.133083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e5729930cf7e1f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-08T02:23:01.133098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 1"} {"level":"info","ts":"2026-03-08T02:23:01.13314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"warn","ts":"2026-03-08T02:23:01.140782Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-08T02:23:01.142279Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-08T02:23:01.144524Z","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-03-08T02:23:01.146451Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e5729930cf7e1f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-08T02:23:01.148622Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e5729930cf7e1f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-08T02:23:01.148754Z","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-03-08T02:23:01.148803Z","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-03-08T02:23:01.148825Z","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-03-08T02:23:01.153361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"info","ts":"2026-03-08T02:23:01.153526Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","added-peer-id":"f1e5729930cf7e1f","added-peer-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-03-08T02:23:01.153821Z","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-03-08T02:23:01.153973Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-03-08T02:23:01.154032Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-03-08T02:23:01.154302Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e5729930cf7e1f","initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-08T02:23:01.154349Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-08T02:23:01.433487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f is starting a new election at term 1"} {"level":"info","ts":"2026-03-08T02:23:01.433548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became pre-candidate at term 1"} {"level":"info","ts":"2026-03-08T02:23:01.433587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgPreVoteResp from f1e5729930cf7e1f at term 1"} {"level":"info","ts":"2026-03-08T02:23:01.433612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became candidate at term 2"} {"level":"info","ts":"2026-03-08T02:23:01.433628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgVoteResp from f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-03-08T02:23:01.433649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became leader at term 2"} {"level":"info","ts":"2026-03-08T02:23:01.433667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e5729930cf7e1f elected leader f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-03-08T02:23:01.434511Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-08T02:23:01.435247Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-08T02:23:01.435224Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e5729930cf7e1f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.33:2379]}","request-path":"/0/members/f1e5729930cf7e1f/attributes","cluster-id":"14aeb8c3ec66b749","publish-timeout":"7s"} {"level":"info","ts":"2026-03-08T02:23:01.435384Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-08T02:23:01.435708Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","cluster-version":"3.5"} {"level":"info","ts":"2026-03-08T02:23:01.435822Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-08T02:23:01.435898Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-08T02:23:01.435845Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-08T02:23:01.436044Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-08T02:23:01.438281Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-08T02:23:01.439833Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.33:2379"} {"level":"warn","ts":"2026-03-08T02:23:07.756617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.272795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-08T02:23:07.756808Z","caller":"traceutil/trace.go:171","msg":"trace[204129915] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:0; response_revision:274; }","duration":"134.51318ms","start":"2026-03-08T02:23:07.622273Z","end":"2026-03-08T02:23:07.756786Z","steps":["trace[204129915] 'range keys from in-memory index tree' (duration: 134.195153ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:23:07.927138Z","caller":"traceutil/trace.go:171","msg":"trace[1705483009] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"164.285902ms","start":"2026-03-08T02:23:07.762645Z","end":"2026-03-08T02:23:07.92693Z","steps":["trace[1705483009] 'process raft request' (duration: 164.148219ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:23:08.653859Z","caller":"traceutil/trace.go:171","msg":"trace[857754936] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"131.494322ms","start":"2026-03-08T02:23:08.522331Z","end":"2026-03-08T02:23:08.653825Z","steps":["trace[857754936] 'process raft request' (duration: 131.306408ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:09.055387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.343774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-03-08T02:23:09.0555Z","caller":"traceutil/trace.go:171","msg":"trace[1099182555] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:281; }","duration":"232.520758ms","start":"2026-03-08T02:23:08.822952Z","end":"2026-03-08T02:23:09.055473Z","steps":["trace[1099182555] 'range keys from in-memory index tree' (duration: 232.201261ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:09.056295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.424369ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-08T02:23:09.056611Z","caller":"traceutil/trace.go:171","msg":"trace[191944621] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"184.711486ms","start":"2026-03-08T02:23:08.871841Z","end":"2026-03-08T02:23:09.056553Z","steps":["trace[191944621] 'process raft request' (duration: 26.148322ms)","trace[191944621] 'compare' (duration: 157.210544ms)"],"step_count":2} {"level":"info","ts":"2026-03-08T02:23:09.387746Z","caller":"traceutil/trace.go:171","msg":"trace[770297696] linearizableReadLoop","detail":"{readStateIndex:296; appliedIndex:295; }","duration":"256.629374ms","start":"2026-03-08T02:23:09.131093Z","end":"2026-03-08T02:23:09.387723Z","steps":["trace[770297696] 'read index received' (duration: 209.366724ms)","trace[770297696] 'applied index is now lower than readState.Index' (duration: 47.26162ms)"],"step_count":2} {"level":"warn","ts":"2026-03-08T02:23:09.387917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.806867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-08T02:23:09.38791Z","caller":"traceutil/trace.go:171","msg":"trace[1950599208] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"272.745672ms","start":"2026-03-08T02:23:09.11514Z","end":"2026-03-08T02:23:09.387885Z","steps":["trace[1950599208] 'process raft request' (duration: 225.404421ms)","trace[1950599208] 'compare' (duration: 47.034805ms)"],"step_count":2} {"level":"info","ts":"2026-03-08T02:23:09.387962Z","caller":"traceutil/trace.go:171","msg":"trace[142981646] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:284; }","duration":"256.87408ms","start":"2026-03-08T02:23:09.131077Z","end":"2026-03-08T02:23:09.387951Z","steps":["trace[142981646] 'agreement among raft nodes before linearized reading' (duration: 256.742166ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:09.589215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.280461ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-08T02:23:09.589423Z","caller":"traceutil/trace.go:171","msg":"trace[1188241331] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"154.79738ms","start":"2026-03-08T02:23:09.434592Z","end":"2026-03-08T02:23:09.589389Z","steps":["trace[1188241331] 'process raft request' (duration: 53.267154ms)","trace[1188241331] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/serviceaccounts/kube-system/replicaset-controller; req_size:189; } (duration: 101.137338ms)"],"step_count":2} {"level":"info","ts":"2026-03-08T02:23:09.589404Z","caller":"traceutil/trace.go:171","msg":"trace[1135411365] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"154.424022ms","start":"2026-03-08T02:23:09.434963Z","end":"2026-03-08T02:23:09.589387Z","steps":["trace[1135411365] 'process raft request' (duration: 154.356011ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:09.944823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.092728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-08T02:23:09.944908Z","caller":"traceutil/trace.go:171","msg":"trace[2075717735] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:289; }","duration":"223.238612ms","start":"2026-03-08T02:23:09.72165Z","end":"2026-03-08T02:23:09.944888Z","steps":["trace[2075717735] 'range keys from in-memory index tree' (duration: 222.991536ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:10.860024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.506537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-08T02:23:10.86012Z","caller":"traceutil/trace.go:171","msg":"trace[233745616] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:0; response_revision:294; }","duration":"137.66449ms","start":"2026-03-08T02:23:10.722436Z","end":"2026-03-08T02:23:10.8601Z","steps":["trace[233745616] 'range keys from in-memory index tree' (duration: 137.409304ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:23:10.979255Z","caller":"traceutil/trace.go:171","msg":"trace[1565612209] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"115.761403ms","start":"2026-03-08T02:23:10.863464Z","end":"2026-03-08T02:23:10.979225Z","steps":["trace[1565612209] 'process raft request' (duration: 115.654461ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:23:10.983103Z","caller":"traceutil/trace.go:171","msg":"trace[1117167214] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"117.175715ms","start":"2026-03-08T02:23:10.865903Z","end":"2026-03-08T02:23:10.983079Z","steps":["trace[1117167214] 'process raft request' (duration: 117.038722ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:11.256824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.797257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-08T02:23:11.256863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.079134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-08T02:23:11.256906Z","caller":"traceutil/trace.go:171","msg":"trace[197679381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:297; }","duration":"139.899769ms","start":"2026-03-08T02:23:11.116989Z","end":"2026-03-08T02:23:11.256889Z","steps":["trace[197679381] 'range keys from in-memory index tree' (duration: 139.617743ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:23:11.256925Z","caller":"traceutil/trace.go:171","msg":"trace[1734920855] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:297; }","duration":"185.156386ms","start":"2026-03-08T02:23:11.071753Z","end":"2026-03-08T02:23:11.25691Z","steps":["trace[1734920855] 'range keys from in-memory index tree' (duration: 184.995692ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:14.593211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.678299932s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-08T02:23:14.593283Z","caller":"traceutil/trace.go:171","msg":"trace[1698618279] linearizableReadLoop","detail":"{readStateIndex:312; appliedIndex:311; }","duration":"3.158283982s","start":"2026-03-08T02:23:11.434987Z","end":"2026-03-08T02:23:14.593271Z","steps":["trace[1698618279] 'read index received' (duration: 479.860867ms)","trace[1698618279] 'applied index is now lower than readState.Index' (duration: 2.678421945s)"],"step_count":2} {"level":"info","ts":"2026-03-08T02:23:14.593392Z","caller":"traceutil/trace.go:171","msg":"trace[864983514] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"3.223834559s","start":"2026-03-08T02:23:11.36955Z","end":"2026-03-08T02:23:14.593384Z","steps":["trace[864983514] 'process raft request' (duration: 545.312662ms)","trace[864983514] 'compare' (duration: 2.677406261s)"],"step_count":2} {"level":"warn","ts":"2026-03-08T02:23:14.593424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:11.369538Z","time spent":"3.223871839s","remote":"127.0.0.1:58412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":176,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-08T02:23:14.593578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.158597699s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-08T02:23:14.593601Z","caller":"traceutil/trace.go:171","msg":"trace[1548040819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:300; }","duration":"3.15865639s","start":"2026-03-08T02:23:11.434934Z","end":"2026-03-08T02:23:14.593591Z","steps":["trace[1548040819] 'agreement among raft nodes before linearized reading' (duration: 3.158590289s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:14.59362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:11.434917Z","time spent":"3.158697151s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-08T02:23:14.624481Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.143777644s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-08T02:23:14.624928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.641569488s","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-03-08T02:23:14.624938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.418734832s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-08T02:23:14.625011Z","caller":"traceutil/trace.go:171","msg":"trace[1743793210] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:301; }","duration":"1.641671281s","start":"2026-03-08T02:23:12.983304Z","end":"2026-03-08T02:23:14.624975Z","steps":["trace[1743793210] 'agreement among raft nodes before linearized reading' (duration: 1.641549308s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:14.624995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.031841491s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-08T02:23:14.625058Z","caller":"traceutil/trace.go:171","msg":"trace[240219474] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:301; }","duration":"2.418859445s","start":"2026-03-08T02:23:12.206176Z","end":"2026-03-08T02:23:14.625035Z","steps":["trace[240219474] 'agreement among raft nodes before linearized reading' (duration: 2.418691571s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:14.625059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:12.983279Z","time spent":"1.641769502s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-08T02:23:14.625088Z","caller":"traceutil/trace.go:171","msg":"trace[1942141574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:301; }","duration":"3.031978644s","start":"2026-03-08T02:23:11.593089Z","end":"2026-03-08T02:23:14.625067Z","steps":["trace[1942141574] 'agreement among raft nodes before linearized reading' (duration: 3.031858551s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:14.625129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:11.593067Z","time spent":"3.032053615s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-08T02:23:14.625124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.094025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.33\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-03-08T02:23:14.625144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.262631585s","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-03-08T02:23:14.625204Z","caller":"traceutil/trace.go:171","msg":"trace[1867551426] range","detail":"{range_begin:/registry/masterleases/199.204.45.33; range_end:; response_count:1; response_revision:301; }","duration":"275.323769ms","start":"2026-03-08T02:23:14.349856Z","end":"2026-03-08T02:23:14.62518Z","steps":["trace[1867551426] 'agreement among raft nodes before linearized reading' (duration: 275.042763ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:23:14.625211Z","caller":"traceutil/trace.go:171","msg":"trace[343464877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:301; }","duration":"1.262722427s","start":"2026-03-08T02:23:13.362466Z","end":"2026-03-08T02:23:14.625188Z","steps":["trace[343464877] 'agreement among raft nodes before linearized reading' (duration: 1.262612835s)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:23:14.62527Z","caller":"traceutil/trace.go:171","msg":"trace[1502197827] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"1.585124594s","start":"2026-03-08T02:23:13.040133Z","end":"2026-03-08T02:23:14.625258Z","steps":["trace[1502197827] 'process raft request' (duration: 1.584523041s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:14.625258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:13.362442Z","time spent":"1.26280569s","remote":"127.0.0.1:58496","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-03-08T02:23:14.625355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.081291102s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-03-08T02:23:14.625448Z","caller":"traceutil/trace.go:171","msg":"trace[754258503] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:301; }","duration":"2.081428435s","start":"2026-03-08T02:23:12.544008Z","end":"2026-03-08T02:23:14.625437Z","steps":["trace[754258503] 'agreement among raft nodes before linearized reading' (duration: 2.081283642s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:14.625499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:12.543989Z","time spent":"2.081486376s","remote":"127.0.0.1:58408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4345,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-03-08T02:23:14.625664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:13.040112Z","time spent":"1.585199985s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T02:23:15.729624Z","caller":"traceutil/trace.go:171","msg":"trace[1395423957] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"427.989664ms","start":"2026-03-08T02:23:15.301615Z","end":"2026-03-08T02:23:15.729605Z","steps":["trace[1395423957] 'process raft request' (duration: 427.842141ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:15.729751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:15.301588Z","time spent":"428.098506ms","remote":"127.0.0.1:58408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T02:23:18.956834Z","caller":"traceutil/trace.go:171","msg":"trace[838209680] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:381; }","duration":"234.829691ms","start":"2026-03-08T02:23:18.721981Z","end":"2026-03-08T02:23:18.956811Z","steps":["trace[838209680] 'read index received' (duration: 234.705918ms)","trace[838209680] 'applied index is now lower than readState.Index' (duration: 122.583µs)"],"step_count":2} {"level":"info","ts":"2026-03-08T02:23:18.956928Z","caller":"traceutil/trace.go:171","msg":"trace[146074570] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"302.360611ms","start":"2026-03-08T02:23:18.654507Z","end":"2026-03-08T02:23:18.956868Z","steps":["trace[146074570] 'process raft request' (duration: 302.150156ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:18.956997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.003994ms","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-03-08T02:23:18.957033Z","caller":"traceutil/trace.go:171","msg":"trace[1523233840] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:367; }","duration":"235.090416ms","start":"2026-03-08T02:23:18.721933Z","end":"2026-03-08T02:23:18.957023Z","steps":["trace[1523233840] 'agreement among raft nodes before linearized reading' (duration: 235.010644ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:18.957081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.414333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-08T02:23:18.957133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:18.654479Z","time spent":"302.554365ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-08T02:23:18.957165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.418986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-08T02:23:18.957198Z","caller":"traceutil/trace.go:171","msg":"trace[1043187762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:367; }","duration":"222.566307ms","start":"2026-03-08T02:23:18.734615Z","end":"2026-03-08T02:23:18.957181Z","steps":["trace[1043187762] 'agreement among raft nodes before linearized reading' (duration: 222.361962ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:23:18.957253Z","caller":"traceutil/trace.go:171","msg":"trace[365428805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:367; }","duration":"205.537108ms","start":"2026-03-08T02:23:18.751695Z","end":"2026-03-08T02:23:18.957232Z","steps":["trace[365428805] 'agreement among raft nodes before linearized reading' (duration: 205.397405ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:19.11669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.424291ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-08T02:23:19.116944Z","caller":"traceutil/trace.go:171","msg":"trace[1379755751] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"155.808973ms","start":"2026-03-08T02:23:18.961102Z","end":"2026-03-08T02:23:19.116911Z","steps":["trace[1379755751] 'process raft request' (duration: 41.984554ms)","trace[1379755751] 'compare' (duration: 113.292887ms)"],"step_count":2} {"level":"info","ts":"2026-03-08T02:23:19.117023Z","caller":"traceutil/trace.go:171","msg":"trace[1616784436] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"154.611256ms","start":"2026-03-08T02:23:18.962398Z","end":"2026-03-08T02:23:19.117009Z","steps":["trace[1616784436] 'process raft request' (duration: 154.554315ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:23:19.117282Z","caller":"traceutil/trace.go:171","msg":"trace[1625684871] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"154.873543ms","start":"2026-03-08T02:23:18.962398Z","end":"2026-03-08T02:23:19.117272Z","steps":["trace[1625684871] 'process raft request' (duration: 154.385671ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:19.818328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.3828ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-03-08T02:23:21.621742Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154969845010500,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-08T02:23:22.122962Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154969845010500,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-08T02:23:22.623594Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154969845010500,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-08T02:23:22.9329Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.967541605s","expected-duration":"1s"} {"level":"info","ts":"2026-03-08T02:23:22.933276Z","caller":"traceutil/trace.go:171","msg":"trace[2025153863] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:386; }","duration":"1.812123759s","start":"2026-03-08T02:23:21.121124Z","end":"2026-03-08T02:23:22.933248Z","steps":["trace[2025153863] 'read index received' (duration: 1.811915465s)","trace[2025153863] 'applied index is now lower than readState.Index' (duration: 206.974µs)"],"step_count":2} {"level":"info","ts":"2026-03-08T02:23:22.933341Z","caller":"traceutil/trace.go:171","msg":"trace[2109287083] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"1.968120028s","start":"2026-03-08T02:23:20.965194Z","end":"2026-03-08T02:23:22.933314Z","steps":["trace[2109287083] 'process raft request' (duration: 1.967858322s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:22.933463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.811728121s","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-03-08T02:23:22.933466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:20.965167Z","time spent":"1.96822178s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-08T02:23:22.933474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.114813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-08T02:23:22.933498Z","caller":"traceutil/trace.go:171","msg":"trace[588290828] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:371; }","duration":"1.811824474s","start":"2026-03-08T02:23:21.121664Z","end":"2026-03-08T02:23:22.933488Z","steps":["trace[588290828] 'agreement among raft nodes before linearized reading' (duration: 1.811740602s)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:23:22.933528Z","caller":"traceutil/trace.go:171","msg":"trace[562746469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:371; }","duration":"902.206634ms","start":"2026-03-08T02:23:22.031305Z","end":"2026-03-08T02:23:22.933511Z","steps":["trace[562746469] 'agreement among raft nodes before linearized reading' (duration: 902.113622ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:22.933527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.81169484s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-08T02:23:22.933537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:21.121642Z","time spent":"1.811887254s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-08T02:23:22.933511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.812368925s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-08T02:23:22.933563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:22.031286Z","time spent":"902.270726ms","remote":"127.0.0.1:58198","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-03-08T02:23:22.933576Z","caller":"traceutil/trace.go:171","msg":"trace[763520873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:371; }","duration":"1.811759072s","start":"2026-03-08T02:23:21.1218Z","end":"2026-03-08T02:23:22.93356Z","steps":["trace[763520873] 'agreement among raft nodes before linearized reading' (duration: 1.81166524s)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:23:22.933594Z","caller":"traceutil/trace.go:171","msg":"trace[1796141780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:371; }","duration":"1.812494897s","start":"2026-03-08T02:23:21.121081Z","end":"2026-03-08T02:23:22.933576Z","steps":["trace[1796141780] 'agreement among raft nodes before linearized reading' (duration: 1.812333604s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:22.933606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:21.121795Z","time spent":"1.811804773s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-08T02:23:22.933644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:21.121061Z","time spent":"1.81257059s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-08T02:23:25.411599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136958552s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-08T02:23:25.411745Z","caller":"traceutil/trace.go:171","msg":"trace[1059737866] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"2.473194824s","start":"2026-03-08T02:23:22.938516Z","end":"2026-03-08T02:23:25.411711Z","steps":["trace[1059737866] 'process raft request' (duration: 1.336014037s)","trace[1059737866] 'compare' (duration: 1.135352147s)"],"step_count":2} {"level":"warn","ts":"2026-03-08T02:23:25.411833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:22.93849Z","time spent":"2.473297556s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-08T02:23:26.031393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999767621s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-08T02:23:26.031473Z","caller":"traceutil/trace.go:171","msg":"trace[1437573098] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999900464s","start":"2026-03-08T02:23:24.031554Z","end":"2026-03-08T02:23:26.031454Z","steps":["trace[1437573098] 'agreement among raft nodes before linearized reading' (duration: 1.999802032s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:26.031524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:24.031532Z","time spent":"1.999982825s","remote":"127.0.0.1:58198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/08 02:23:26 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-08T02:23:27.086152Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.811641306s","expected-duration":"1s"} {"level":"info","ts":"2026-03-08T02:23:27.181512Z","caller":"traceutil/trace.go:171","msg":"trace[2093332495] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"4.24289372s","start":"2026-03-08T02:23:22.938579Z","end":"2026-03-08T02:23:27.181472Z","steps":["trace[2093332495] 'process raft request' (duration: 4.147728674s)","trace[2093332495] 'compare' (duration: 95.033163ms)"],"step_count":2} {"level":"warn","ts":"2026-03-08T02:23:27.181655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:22.938559Z","time spent":"4.243021403s","remote":"127.0.0.1:58496","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-03-08T02:23:27.181763Z","caller":"traceutil/trace.go:171","msg":"trace[541845990] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"4.243176256s","start":"2026-03-08T02:23:22.938577Z","end":"2026-03-08T02:23:27.181754Z","steps":["trace[541845990] 'process raft request' (duration: 4.242863999s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:27.181817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:22.938559Z","time spent":"4.243231268s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T02:23:27.181778Z","caller":"traceutil/trace.go:171","msg":"trace[211753311] linearizableReadLoop","detail":"{readStateIndex:390; appliedIndex:387; }","duration":"3.150115741s","start":"2026-03-08T02:23:24.031607Z","end":"2026-03-08T02:23:27.181723Z","steps":["trace[211753311] 'read index received' (duration: 242.92877ms)","trace[211753311] 'applied index is now lower than readState.Index' (duration: 2.90718521s)"],"step_count":2} {"level":"warn","ts":"2026-03-08T02:23:28.032267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000466216s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-08T02:23:28.032356Z","caller":"traceutil/trace.go:171","msg":"trace[253055639] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000613409s","start":"2026-03-08T02:23:26.031724Z","end":"2026-03-08T02:23:28.032337Z","steps":["trace[253055639] 'agreement among raft nodes before linearized reading' (duration: 2.000500307s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:28.03241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:26.031702Z","time spent":"2.000698551s","remote":"127.0.0.1:58184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-08T02:23:29.937586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999203343s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-08T02:23:29.937675Z","caller":"traceutil/trace.go:171","msg":"trace[177313579] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999364506s","start":"2026-03-08T02:23:24.938289Z","end":"2026-03-08T02:23:29.937653Z","steps":["trace[177313579] 'agreement among raft nodes before linearized reading' (duration: 4.999233173s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:29.937728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:24.938273Z","time spent":"4.999444128s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-08T02:23:30.040568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.0001671s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-08T02:23:30.040656Z","caller":"traceutil/trace.go:171","msg":"trace[1271362025] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000302133s","start":"2026-03-08T02:23:28.040334Z","end":"2026-03-08T02:23:30.040636Z","steps":["trace[1271362025] 'agreement among raft nodes before linearized reading' (duration: 2.00016262s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:30.040705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:28.040248Z","time spent":"2.000448046s","remote":"127.0.0.1:58198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/08 02:23:30 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-08T02:23:30.3313Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.294359719s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-08T02:23:30.332581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.479560387s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-08T02:23:30.332745Z","caller":"traceutil/trace.go:171","msg":"trace[1147447973] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:374; }","duration":"1.479733521s","start":"2026-03-08T02:23:28.852985Z","end":"2026-03-08T02:23:30.332719Z","steps":["trace[1147447973] 'range keys from in-memory index tree' (duration: 1.479542397s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:30.333193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.910816221s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-08T02:23:30.333465Z","caller":"traceutil/trace.go:171","msg":"trace[2091149427] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:390; }","duration":"3.15141072s","start":"2026-03-08T02:23:27.182034Z","end":"2026-03-08T02:23:30.333445Z","steps":["trace[2091149427] 'read index received' (duration: 240.148789ms)","trace[2091149427] 'applied index is now lower than readState.Index' (duration: 2.91126106s)"],"step_count":2} {"level":"info","ts":"2026-03-08T02:23:30.33351Z","caller":"traceutil/trace.go:171","msg":"trace[2049638893] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"3.631693174s","start":"2026-03-08T02:23:26.701788Z","end":"2026-03-08T02:23:30.333481Z","steps":["trace[2049638893] 'process raft request' (duration: 3.63151171s)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:23:30.333594Z","caller":"traceutil/trace.go:171","msg":"trace[2028104978] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"2.296743441s","start":"2026-03-08T02:23:28.036834Z","end":"2026-03-08T02:23:30.333578Z","steps":["trace[2028104978] 'process raft request' (duration: 2.296577358s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:30.333615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.983347237s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.33\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-08T02:23:30.333649Z","caller":"traceutil/trace.go:171","msg":"trace[454972932] range","detail":"{range_begin:/registry/masterleases/199.204.45.33; range_end:; response_count:1; response_revision:377; }","duration":"5.983420219s","start":"2026-03-08T02:23:24.350217Z","end":"2026-03-08T02:23:30.333637Z","steps":["trace[454972932] 'agreement among raft nodes before linearized reading' (duration: 5.983321257s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:30.333663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:26.701763Z","time spent":"3.631817087s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-08T02:23:30.333669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:28.036802Z","time spent":"2.296834383s","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-08T02:23:30.333692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:24.350196Z","time spent":"5.98348666s","remote":"127.0.0.1:58212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.33\" "} {"level":"info","ts":"2026-03-08T02:23:30.333538Z","caller":"traceutil/trace.go:171","msg":"trace[2133414346] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"5.510416975s","start":"2026-03-08T02:23:24.823103Z","end":"2026-03-08T02:23:30.33352Z","steps":["trace[2133414346] 'process raft request' (duration: 2.599177405s)","trace[2133414346] 'compare' (duration: 2.909590644s)"],"step_count":2} {"level":"warn","ts":"2026-03-08T02:23:30.333891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:24.823078Z","time spent":"5.510689701s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-08T02:23:30.834357Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154969845010513,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-08T02:23:31.334854Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154969845010513,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-08T02:23:31.835816Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154969845010513,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-08T02:23:32.041534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000606249s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-08T02:23:32.041612Z","caller":"traceutil/trace.go:171","msg":"trace[839226452] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000739963s","start":"2026-03-08T02:23:30.040853Z","end":"2026-03-08T02:23:32.041593Z","steps":["trace[839226452] 'agreement among raft nodes before linearized reading' (duration: 2.00063548s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:32.041652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:30.040835Z","time spent":"2.000809464s","remote":"127.0.0.1:58184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-08T02:23:32.687395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.93269ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-08T02:23:32.687613Z","caller":"traceutil/trace.go:171","msg":"trace[31648840] linearizableReadLoop","detail":"{readStateIndex:395; appliedIndex:393; }","duration":"2.354087897s","start":"2026-03-08T02:23:30.333507Z","end":"2026-03-08T02:23:32.687595Z","steps":["trace[31648840] 'read index received' (duration: 1.54085503s)","trace[31648840] 'applied index is now lower than readState.Index' (duration: 813.231567ms)"],"step_count":2} {"level":"warn","ts":"2026-03-08T02:23:32.687742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:30.334677Z","time spent":"2.353060904s","remote":"127.0.0.1:58212","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-08T02:23:32.687925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.271666689s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-08T02:23:32.688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.501383459s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-08T02:23:32.688018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.795662ms","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-03-08T02:23:32.688058Z","caller":"traceutil/trace.go:171","msg":"trace[1972306528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:378; }","duration":"3.501481511s","start":"2026-03-08T02:23:29.186558Z","end":"2026-03-08T02:23:32.68804Z","steps":["trace[1972306528] 'agreement among raft nodes before linearized reading' (duration: 3.501353658s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:32.68805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.421081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-08T02:23:32.688084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.387412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.189abc739b19a22d\" ","response":"range_response_count:1 size:774"} {"level":"warn","ts":"2026-03-08T02:23:32.688096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:29.186551Z","time spent":"3.501537282s","remote":"127.0.0.1:58496","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-03-08T02:23:32.688098Z","caller":"traceutil/trace.go:171","msg":"trace[934703620] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:378; }","duration":"747.939515ms","start":"2026-03-08T02:23:31.940136Z","end":"2026-03-08T02:23:32.688075Z","steps":["trace[934703620] 'agreement among raft nodes before linearized reading' (duration: 747.756021ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:32.688092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.501628534s","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-03-08T02:23:32.688104Z","caller":"traceutil/trace.go:171","msg":"trace[1409335003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:378; }","duration":"638.514913ms","start":"2026-03-08T02:23:32.049575Z","end":"2026-03-08T02:23:32.68809Z","steps":["trace[1409335003] 'agreement among raft nodes before linearized reading' (duration: 638.423491ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:23:32.688128Z","caller":"traceutil/trace.go:171","msg":"trace[1291042802] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.189abc739b19a22d; range_end:; response_count:1; response_revision:378; }","duration":"643.451653ms","start":"2026-03-08T02:23:32.04466Z","end":"2026-03-08T02:23:32.688111Z","steps":["trace[1291042802] 'agreement among raft nodes before linearized reading' (duration: 643.376711ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:32.688148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:31.940115Z","time spent":"748.023567ms","remote":"127.0.0.1:58496","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":"info","ts":"2026-03-08T02:23:32.688151Z","caller":"traceutil/trace.go:171","msg":"trace[425523076] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:378; }","duration":"3.501727086s","start":"2026-03-08T02:23:29.18641Z","end":"2026-03-08T02:23:32.688137Z","steps":["trace[425523076] 'agreement among raft nodes before linearized reading' (duration: 3.501604064s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:32.688154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:32.04955Z","time spent":"638.595974ms","remote":"127.0.0.1:58198","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-03-08T02:23:32.68819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:29.186388Z","time spent":"3.501794257s","remote":"127.0.0.1:58496","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-03-08T02:23:32.688182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:32.044647Z","time spent":"643.523814ms","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.189abc739b19a22d\" "} {"level":"info","ts":"2026-03-08T02:23:32.688714Z","caller":"traceutil/trace.go:171","msg":"trace[1134398484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:378; }","duration":"5.271800581s","start":"2026-03-08T02:23:27.416193Z","end":"2026-03-08T02:23:32.687993Z","steps":["trace[1134398484] 'agreement among raft nodes before linearized reading' (duration: 5.271621387s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:32.688895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:27.416171Z","time spent":"5.272706321s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-08T02:23:33.071923Z","caller":"traceutil/trace.go:171","msg":"trace[1363925468] transaction","detail":"{read_only:false; number_of_response:1; response_revision:378; }","duration":"382.354279ms","start":"2026-03-08T02:23:32.689547Z","end":"2026-03-08T02:23:33.071901Z","steps":["trace[1363925468] 'process raft request' (duration: 382.296928ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:33.072045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:32.689524Z","time spent":"382.453621ms","remote":"127.0.0.1:58212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":40,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T02:23:33.075447Z","caller":"traceutil/trace.go:171","msg":"trace[702284301] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"382.593074ms","start":"2026-03-08T02:23:32.6928Z","end":"2026-03-08T02:23:33.075393Z","steps":["trace[702284301] 'process raft request' (duration: 382.366949ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:23:33.075652Z","caller":"traceutil/trace.go:171","msg":"trace[390339334] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"381.729245ms","start":"2026-03-08T02:23:32.693903Z","end":"2026-03-08T02:23:33.075632Z","steps":["trace[390339334] 'process raft request' (duration: 381.651523ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:33.075692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:32.692779Z","time spent":"382.798028ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T02:23:33.075747Z","caller":"traceutil/trace.go:171","msg":"trace[1810613410] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"382.90666ms","start":"2026-03-08T02:23:32.692829Z","end":"2026-03-08T02:23:33.075736Z","steps":["trace[1810613410] 'process raft request' (duration: 382.573193ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:33.075769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:32.693891Z","time spent":"381.815897ms","remote":"127.0.0.1:58496","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-03-08T02:23:33.075796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:32.692811Z","time spent":"382.959222ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T02:23:33.075866Z","caller":"traceutil/trace.go:171","msg":"trace[1934740801] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"383.029253ms","start":"2026-03-08T02:23:32.692827Z","end":"2026-03-08T02:23:33.075857Z","steps":["trace[1934740801] 'process raft request' (duration: 382.491111ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:33.075913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:32.69281Z","time spent":"383.077635ms","remote":"127.0.0.1:58496","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-03-08T02:23:33.075938Z","caller":"traceutil/trace.go:171","msg":"trace[546197364] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"382.44202ms","start":"2026-03-08T02:23:32.69348Z","end":"2026-03-08T02:23:33.075922Z","steps":["trace[546197364] 'process raft request' (duration: 381.97833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:33.076002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:32.693469Z","time spent":"382.492741ms","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T02:23:41.59674Z","caller":"traceutil/trace.go:171","msg":"trace[299953930] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"421.872488ms","start":"2026-03-08T02:23:41.174834Z","end":"2026-03-08T02:23:41.596706Z","steps":["trace[299953930] 'process raft request' (duration: 409.445552ms)","trace[299953930] 'compare' (duration: 12.297153ms)"],"step_count":2} {"level":"warn","ts":"2026-03-08T02:23:41.596887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:41.17482Z","time spent":"421.9994ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T02:23:41.596972Z","caller":"traceutil/trace.go:171","msg":"trace[522649003] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"420.989907ms","start":"2026-03-08T02:23:41.175962Z","end":"2026-03-08T02:23:41.596952Z","steps":["trace[522649003] 'process raft request' (duration: 420.709101ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:41.597155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:41.175951Z","time spent":"421.151881ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T02:23:41.597175Z","caller":"traceutil/trace.go:171","msg":"trace[351199253] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"421.005968ms","start":"2026-03-08T02:23:41.176154Z","end":"2026-03-08T02:23:41.59716Z","steps":["trace[351199253] 'process raft request' (duration: 420.745182ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:41.59724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:41.176137Z","time spent":"421.0677ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T02:23:49.745215Z","caller":"traceutil/trace.go:171","msg":"trace[204099340] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"114.314881ms","start":"2026-03-08T02:23:49.630879Z","end":"2026-03-08T02:23:49.745194Z","steps":["trace[204099340] 'process raft request' (duration: 114.203379ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:23:49.748426Z","caller":"traceutil/trace.go:171","msg":"trace[1893668985] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"117.104433ms","start":"2026-03-08T02:23:49.631302Z","end":"2026-03-08T02:23:49.748407Z","steps":["trace[1893668985] 'process raft request' (duration: 116.51739ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:23:49.748489Z","caller":"traceutil/trace.go:171","msg":"trace[1991933053] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"116.257755ms","start":"2026-03-08T02:23:49.632196Z","end":"2026-03-08T02:23:49.748454Z","steps":["trace[1991933053] 'process raft request' (duration: 116.119132ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:23:51.1191Z","caller":"traceutil/trace.go:171","msg":"trace[1665183592] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"508.214567ms","start":"2026-03-08T02:23:50.610861Z","end":"2026-03-08T02:23:51.119076Z","steps":["trace[1665183592] 'process raft request' (duration: 507.961301ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:51.119273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:50.610831Z","time spent":"508.34377ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T02:23:51.119711Z","caller":"traceutil/trace.go:171","msg":"trace[493782422] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"420.62991ms","start":"2026-03-08T02:23:50.699064Z","end":"2026-03-08T02:23:51.119694Z","steps":["trace[493782422] 'process raft request' (duration: 420.431276ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:51.119788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:50.699033Z","time spent":"420.718442ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T02:23:57.460108Z","caller":"traceutil/trace.go:171","msg":"trace[1607836906] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"143.515951ms","start":"2026-03-08T02:23:57.316569Z","end":"2026-03-08T02:23:57.460085Z","steps":["trace[1607836906] 'process raft request' (duration: 143.374748ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:57.809585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.471865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9qntj\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-08T02:23:57.809657Z","caller":"traceutil/trace.go:171","msg":"trace[1724632976] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9qntj; range_end:; response_count:1; response_revision:504; }","duration":"290.587149ms","start":"2026-03-08T02:23:57.519054Z","end":"2026-03-08T02:23:57.809641Z","steps":["trace[1724632976] 'range keys from in-memory index tree' (duration: 290.317192ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:23:58.145463Z","caller":"traceutil/trace.go:171","msg":"trace[971271892] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"328.946111ms","start":"2026-03-08T02:23:57.816492Z","end":"2026-03-08T02:23:58.145439Z","steps":["trace[971271892] 'process raft request' (duration: 328.790258ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:58.145619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:57.816468Z","time spent":"329.068364ms","remote":"127.0.0.1:58496","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-03-08T02:23:58.598478Z","caller":"traceutil/trace.go:171","msg":"trace[1425324349] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"780.878527ms","start":"2026-03-08T02:23:57.817579Z","end":"2026-03-08T02:23:58.598458Z","steps":["trace[1425324349] 'process raft request' (duration: 780.701103ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:58.598602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:57.817571Z","time spent":"780.97116ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T02:23:58.598772Z","caller":"traceutil/trace.go:171","msg":"trace[1654934909] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"774.040675ms","start":"2026-03-08T02:23:57.824724Z","end":"2026-03-08T02:23:58.598764Z","steps":["trace[1654934909] 'process raft request' (duration: 773.698567ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:58.598836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:57.824695Z","time spent":"774.110357ms","remote":"127.0.0.1:58408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T02:23:58.598599Z","caller":"traceutil/trace.go:171","msg":"trace[823516703] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"780.045968ms","start":"2026-03-08T02:23:57.818471Z","end":"2026-03-08T02:23:58.598517Z","steps":["trace[823516703] 'process raft request' (duration: 779.920545ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:23:58.599264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:57.818463Z","time spent":"780.480008ms","remote":"127.0.0.1:58496","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-03-08T02:24:00.312234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.458903079s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-08T02:24:00.312301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.280780309s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-08T02:24:00.312349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.819228ms","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-03-08T02:24:00.312391Z","caller":"traceutil/trace.go:171","msg":"trace[1925518225] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:508; }","duration":"1.280914102s","start":"2026-03-08T02:23:59.031456Z","end":"2026-03-08T02:24:00.31237Z","steps":["trace[1925518225] 'range keys from in-memory index tree' (duration: 1.280641766s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:24:00.312429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:59.031409Z","time spent":"1.281011674s","remote":"127.0.0.1:58198","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-03-08T02:24:00.3124Z","caller":"traceutil/trace.go:171","msg":"trace[1587694333] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:508; }","duration":"848.90738ms","start":"2026-03-08T02:23:59.463478Z","end":"2026-03-08T02:24:00.312385Z","steps":["trace[1587694333] 'range keys from in-memory index tree' (duration: 848.659874ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:24:00.312385Z","caller":"traceutil/trace.go:171","msg":"trace[961164729] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:508; }","duration":"1.459068142s","start":"2026-03-08T02:23:58.853292Z","end":"2026-03-08T02:24:00.31236Z","steps":["trace[961164729] 'range keys from in-memory index tree' (duration: 1.458883869s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:24:00.312495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:23:59.463457Z","time spent":"849.023282ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-08T02:24:00.454831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.276473ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-08T02:24:00.455105Z","caller":"traceutil/trace.go:171","msg":"trace[931511773] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"138.435758ms","start":"2026-03-08T02:24:00.316651Z","end":"2026-03-08T02:24:00.455086Z","steps":["trace[931511773] 'process raft request' (duration: 138.312925ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:24:00.455127Z","caller":"traceutil/trace.go:171","msg":"trace[686463329] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:534; }","duration":"305.342357ms","start":"2026-03-08T02:24:00.149756Z","end":"2026-03-08T02:24:00.455098Z","steps":["trace[686463329] 'read index received' (duration: 30.671µs)","trace[686463329] 'applied index is now lower than readState.Index' (duration: 305.307576ms)"],"step_count":2} {"level":"warn","ts":"2026-03-08T02:24:00.455375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.601853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-08T02:24:00.455373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.892434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-08T02:24:00.455407Z","caller":"traceutil/trace.go:171","msg":"trace[1218979711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:509; }","duration":"305.696075ms","start":"2026-03-08T02:24:00.149703Z","end":"2026-03-08T02:24:00.4554Z","steps":["trace[1218979711] 'agreement among raft nodes before linearized reading' (duration: 305.509971ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:24:00.455446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:24:00.149681Z","time spent":"305.758017ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-08T02:24:00.455459Z","caller":"traceutil/trace.go:171","msg":"trace[839422374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"141.982376ms","start":"2026-03-08T02:24:00.313446Z","end":"2026-03-08T02:24:00.455428Z","steps":["trace[839422374] 'agreement among raft nodes before linearized reading' (duration: 141.846063ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:24:02.153703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.336779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-08T02:24:02.153804Z","caller":"traceutil/trace.go:171","msg":"trace[404648551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:522; }","duration":"122.501153ms","start":"2026-03-08T02:24:02.03128Z","end":"2026-03-08T02:24:02.153782Z","steps":["trace[404648551] 'range keys from in-memory index tree' (duration: 122.238536ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:24:04.947935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.430583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-03-08T02:24:04.948028Z","caller":"traceutil/trace.go:171","msg":"trace[1759138108] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:578; }","duration":"154.573207ms","start":"2026-03-08T02:24:04.793434Z","end":"2026-03-08T02:24:04.948007Z","steps":["trace[1759138108] 'range keys from in-memory index tree' (duration: 154.277369ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:24:19.025642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.108018ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-08T02:24:19.025756Z","caller":"traceutil/trace.go:171","msg":"trace[300271977] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:693; }","duration":"172.245351ms","start":"2026-03-08T02:24:18.853494Z","end":"2026-03-08T02:24:19.025739Z","steps":["trace[300271977] 'range keys from in-memory index tree' (duration: 172.086278ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:24:19.025866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.30521ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-08T02:24:19.025975Z","caller":"traceutil/trace.go:171","msg":"trace[2026542122] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"440.873807ms","start":"2026-03-08T02:24:18.585082Z","end":"2026-03-08T02:24:19.025955Z","steps":["trace[2026542122] 'process raft request' (duration: 90.392504ms)","trace[2026542122] 'compare' (duration: 350.048113ms)"],"step_count":2} {"level":"warn","ts":"2026-03-08T02:24:19.026079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:24:18.585056Z","time spent":"440.958569ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T02:24:19.030383Z","caller":"traceutil/trace.go:171","msg":"trace[1420751069] linearizableReadLoop","detail":"{readStateIndex:727; appliedIndex:726; }","duration":"321.071714ms","start":"2026-03-08T02:24:18.709294Z","end":"2026-03-08T02:24:19.030366Z","steps":["trace[1420751069] 'read index received' (duration: 321.011803ms)","trace[1420751069] 'applied index is now lower than readState.Index' (duration: 58.951µs)"],"step_count":2} {"level":"info","ts":"2026-03-08T02:24:19.030442Z","caller":"traceutil/trace.go:171","msg":"trace[1570778211] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"443.539237ms","start":"2026-03-08T02:24:18.586891Z","end":"2026-03-08T02:24:19.03043Z","steps":["trace[1570778211] 'process raft request' (duration: 443.401964ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:24:19.03054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:24:18.58687Z","time spent":"443.606869ms","remote":"127.0.0.1:58496","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-03-08T02:24:19.030566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.268978ms","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-03-08T02:24:19.030599Z","caller":"traceutil/trace.go:171","msg":"trace[588249310] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:695; }","duration":"321.34642ms","start":"2026-03-08T02:24:18.709243Z","end":"2026-03-08T02:24:19.030589Z","steps":["trace[588249310] 'agreement among raft nodes before linearized reading' (duration: 321.225858ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:24:19.030629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:24:18.709216Z","time spent":"321.406262ms","remote":"127.0.0.1:58496","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-03-08T02:24:19.03075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.39149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-08T02:24:19.03078Z","caller":"traceutil/trace.go:171","msg":"trace[1319577274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:695; }","duration":"320.472671ms","start":"2026-03-08T02:24:18.710298Z","end":"2026-03-08T02:24:19.030771Z","steps":["trace[1319577274] 'agreement among raft nodes before linearized reading' (duration: 320.348189ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:24:19.0308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:24:18.710277Z","time spent":"320.518813ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-08T02:24:21.549536Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154969845011198,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-08T02:24:21.745945Z","caller":"traceutil/trace.go:171","msg":"trace[1540029253] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"710.275703ms","start":"2026-03-08T02:24:21.03565Z","end":"2026-03-08T02:24:21.745925Z","steps":["trace[1540029253] 'process raft request' (duration: 710.14447ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:24:21.746091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:24:21.035637Z","time spent":"710.387935ms","remote":"127.0.0.1:58496","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-03-08T02:24:21.749434Z","caller":"traceutil/trace.go:171","msg":"trace[1914761959] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"710.406005ms","start":"2026-03-08T02:24:21.039004Z","end":"2026-03-08T02:24:21.74941Z","steps":["trace[1914761959] 'process raft request' (duration: 710.294852ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:24:21.749547Z","caller":"traceutil/trace.go:171","msg":"trace[437187034] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:731; }","duration":"700.346873ms","start":"2026-03-08T02:24:21.049188Z","end":"2026-03-08T02:24:21.749535Z","steps":["trace[437187034] 'read index received' (duration: 696.664152ms)","trace[437187034] 'applied index is now lower than readState.Index' (duration: 3.681841ms)"],"step_count":2} {"level":"warn","ts":"2026-03-08T02:24:21.749553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:24:21.038991Z","time spent":"710.496946ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T02:24:21.749573Z","caller":"traceutil/trace.go:171","msg":"trace[113248144] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"173.362656ms","start":"2026-03-08T02:24:21.576191Z","end":"2026-03-08T02:24:21.749554Z","steps":["trace[113248144] 'process raft request' (duration: 173.194522ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:24:21.749709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.115263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-08T02:24:21.749734Z","caller":"traceutil/trace.go:171","msg":"trace[1637201882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:701; }","duration":"688.160364ms","start":"2026-03-08T02:24:21.061567Z","end":"2026-03-08T02:24:21.749727Z","steps":["trace[1637201882] 'agreement among raft nodes before linearized reading' (duration: 688.046741ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:24:21.749755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:24:21.061559Z","time spent":"688.191734ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-08T02:24:21.749868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.65634ms","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-03-08T02:24:21.749948Z","caller":"traceutil/trace.go:171","msg":"trace[1726651563] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:701; }","duration":"700.757902ms","start":"2026-03-08T02:24:21.049169Z","end":"2026-03-08T02:24:21.749927Z","steps":["trace[1726651563] 'agreement among raft nodes before linearized reading' (duration: 700.438265ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:24:21.749992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:24:21.049163Z","time spent":"700.820203ms","remote":"127.0.0.1:58496","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":"info","ts":"2026-03-08T02:24:22.187474Z","caller":"traceutil/trace.go:171","msg":"trace[764151138] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"320.730877ms","start":"2026-03-08T02:24:21.866718Z","end":"2026-03-08T02:24:22.187448Z","steps":["trace[764151138] 'process raft request' (duration: 277.172986ms)","trace[764151138] 'compare' (duration: 43.443119ms)"],"step_count":2} {"level":"warn","ts":"2026-03-08T02:24:22.187636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:24:21.866686Z","time spent":"320.871161ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T02:24:44.282973Z","caller":"traceutil/trace.go:171","msg":"trace[1687455121] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"427.449302ms","start":"2026-03-08T02:24:43.855496Z","end":"2026-03-08T02:24:44.282946Z","steps":["trace[1687455121] 'process raft request' (duration: 427.286129ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:24:44.283149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:24:43.855478Z","time spent":"427.576055ms","remote":"127.0.0.1:58496","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-03-08T02:24:44.411057Z","caller":"traceutil/trace.go:171","msg":"trace[398183039] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:804; }","duration":"545.241252ms","start":"2026-03-08T02:24:43.865789Z","end":"2026-03-08T02:24:44.41103Z","steps":["trace[398183039] 'read index received' (duration: 416.990331ms)","trace[398183039] 'applied index is now lower than readState.Index' (duration: 128.249781ms)"],"step_count":2} {"level":"info","ts":"2026-03-08T02:24:44.411176Z","caller":"traceutil/trace.go:171","msg":"trace[286736092] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"554.67041ms","start":"2026-03-08T02:24:43.856475Z","end":"2026-03-08T02:24:44.411146Z","steps":["trace[286736092] 'process raft request' (duration: 538.718508ms)","trace[286736092] 'compare' (duration: 15.719317ms)"],"step_count":2} {"level":"warn","ts":"2026-03-08T02:24:44.411293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:24:43.856454Z","time spent":"554.771962ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-08T02:24:44.411335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.516407ms","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-03-08T02:24:44.411393Z","caller":"traceutil/trace.go:171","msg":"trace[171228822] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:768; }","duration":"545.61527ms","start":"2026-03-08T02:24:43.865761Z","end":"2026-03-08T02:24:44.411376Z","steps":["trace[171228822] 'agreement among raft nodes before linearized reading' (duration: 545.437606ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:24:44.411437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:24:43.865749Z","time spent":"545.676661ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-08T02:24:44.411574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.684742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-08T02:24:44.411645Z","caller":"traceutil/trace.go:171","msg":"trace[1622205939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:768; }","duration":"545.763824ms","start":"2026-03-08T02:24:43.865862Z","end":"2026-03-08T02:24:44.411626Z","steps":["trace[1622205939] 'agreement among raft nodes before linearized reading' (duration: 545.499718ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:24:44.411697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:24:43.865847Z","time spent":"545.839865ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-08T02:24:44.645048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.124249ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-08T02:24:44.645166Z","caller":"traceutil/trace.go:171","msg":"trace[2096682432] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"155.01005ms","start":"2026-03-08T02:24:44.490138Z","end":"2026-03-08T02:24:44.645148Z","steps":["trace[2096682432] 'process raft request' (duration: 30.692218ms)","trace[2096682432] 'compare' (duration: 124.022516ms)"],"step_count":2} {"level":"warn","ts":"2026-03-08T02:24:46.854274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.31117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-08T02:24:46.854372Z","caller":"traceutil/trace.go:171","msg":"trace[1852982922] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:773; }","duration":"108.474314ms","start":"2026-03-08T02:24:46.745881Z","end":"2026-03-08T02:24:46.854355Z","steps":["trace[1852982922] 'count revisions from in-memory index tree' (duration: 108.188448ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:24:46.854426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.602508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-08T02:24:46.854517Z","caller":"traceutil/trace.go:171","msg":"trace[1787690792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:773; }","duration":"341.68378ms","start":"2026-03-08T02:24:46.512795Z","end":"2026-03-08T02:24:46.854479Z","steps":["trace[1787690792] 'range keys from in-memory index tree' (duration: 341.436084ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:24:46.85454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.710701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-08T02:24:46.854554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:24:46.512728Z","time spent":"341.815982ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-08T02:24:46.854571Z","caller":"traceutil/trace.go:171","msg":"trace[237123733] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:773; }","duration":"438.890565ms","start":"2026-03-08T02:24:46.415673Z","end":"2026-03-08T02:24:46.854563Z","steps":["trace[237123733] 'range keys from in-memory index tree' (duration: 438.277371ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:24:46.85459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:24:46.415626Z","time spent":"438.959146ms","remote":"127.0.0.1:58496","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-03-08T02:24:46.854669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.602028ms","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-03-08T02:24:46.854685Z","caller":"traceutil/trace.go:171","msg":"trace[1478644201] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:773; }","duration":"341.636288ms","start":"2026-03-08T02:24:46.513043Z","end":"2026-03-08T02:24:46.854679Z","steps":["trace[1478644201] 'range keys from in-memory index tree' (duration: 341.23914ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:24:46.854701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:24:46.513036Z","time spent":"341.660938ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-08T02:24:47.074235Z","caller":"traceutil/trace.go:171","msg":"trace[1298989647] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"216.056658ms","start":"2026-03-08T02:24:46.858149Z","end":"2026-03-08T02:24:47.074206Z","steps":["trace[1298989647] 'process raft request' (duration: 215.893764ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:24:47.088463Z","caller":"traceutil/trace.go:171","msg":"trace[1369325289] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"229.75086ms","start":"2026-03-08T02:24:46.858697Z","end":"2026-03-08T02:24:47.088448Z","steps":["trace[1369325289] 'process raft request' (duration: 229.713259ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:24:47.088783Z","caller":"traceutil/trace.go:171","msg":"trace[1913988806] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"230.460936ms","start":"2026-03-08T02:24:46.858301Z","end":"2026-03-08T02:24:47.088762Z","steps":["trace[1913988806] 'process raft request' (duration: 229.963844ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:24:47.088805Z","caller":"traceutil/trace.go:171","msg":"trace[498370440] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"230.384674ms","start":"2026-03-08T02:24:46.858391Z","end":"2026-03-08T02:24:47.088775Z","steps":["trace[498370440] 'process raft request' (duration: 229.974924ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:01.56858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.420712ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-08T02:25:01.568668Z","caller":"traceutil/trace.go:171","msg":"trace[1033994094] linearizableReadLoop","detail":"{readStateIndex:905; appliedIndex:904; }","duration":"1.354548378s","start":"2026-03-08T02:25:00.214107Z","end":"2026-03-08T02:25:01.568655Z","steps":["trace[1033994094] 'read index received' (duration: 470.997743ms)","trace[1033994094] 'applied index is now lower than readState.Index' (duration: 883.549425ms)"],"step_count":2} {"level":"warn","ts":"2026-03-08T02:25:01.568771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.35466172s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-08T02:25:01.56879Z","caller":"traceutil/trace.go:171","msg":"trace[2092924730] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:862; }","duration":"1.354723512s","start":"2026-03-08T02:25:00.21406Z","end":"2026-03-08T02:25:01.568784Z","steps":["trace[2092924730] 'agreement among raft nodes before linearized reading' (duration: 1.354673131s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:01.568809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:00.214037Z","time spent":"1.354766462s","remote":"127.0.0.1:58678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-03-08T02:25:01.56895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.13025562s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-08T02:25:01.568972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:01.081358Z","time spent":"487.61193ms","remote":"127.0.0.1:33814","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-08T02:25:01.56901Z","caller":"traceutil/trace.go:171","msg":"trace[1761002547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:862; }","duration":"1.130347981s","start":"2026-03-08T02:25:00.438646Z","end":"2026-03-08T02:25:01.568994Z","steps":["trace[1761002547] 'agreement among raft nodes before linearized reading' (duration: 1.130181947s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:01.56904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:00.438629Z","time spent":"1.130405373s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-08T02:25:01.569052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.660742ms","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-03-08T02:25:01.569067Z","caller":"traceutil/trace.go:171","msg":"trace[131337351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:862; }","duration":"352.676152ms","start":"2026-03-08T02:25:01.216386Z","end":"2026-03-08T02:25:01.569062Z","steps":["trace[131337351] 'agreement among raft nodes before linearized reading' (duration: 352.59975ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:01.569055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.971497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-03-08T02:25:01.569083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:01.216353Z","time spent":"352.727153ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-08T02:25:01.56911Z","caller":"traceutil/trace.go:171","msg":"trace[535696655] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:862; }","duration":"362.05976ms","start":"2026-03-08T02:25:01.207031Z","end":"2026-03-08T02:25:01.56909Z","steps":["trace[535696655] 'agreement among raft nodes before linearized reading' (duration: 361.937987ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:01.569144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.54462ms","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":"warn","ts":"2026-03-08T02:25:01.569146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:01.207016Z","time spent":"362.121651ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-08T02:25:01.569157Z","caller":"traceutil/trace.go:171","msg":"trace[1795760444] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:862; }","duration":"357.600431ms","start":"2026-03-08T02:25:01.211552Z","end":"2026-03-08T02:25:01.569152Z","steps":["trace[1795760444] 'agreement among raft nodes before linearized reading' (duration: 357.56416ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:01.569172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:01.211531Z","time spent":"357.638181ms","remote":"127.0.0.1:58496","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-03-08T02:25:01.569179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.906592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-08T02:25:01.569203Z","caller":"traceutil/trace.go:171","msg":"trace[150663923] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:862; }","duration":"553.979464ms","start":"2026-03-08T02:25:01.015216Z","end":"2026-03-08T02:25:01.569196Z","steps":["trace[150663923] 'agreement among raft nodes before linearized reading' (duration: 553.936883ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:01.569223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:01.015196Z","time spent":"554.021465ms","remote":"127.0.0.1:58596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-08T02:25:01.569254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.227516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-08T02:25:01.569269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.240627ms","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-03-08T02:25:01.569291Z","caller":"traceutil/trace.go:171","msg":"trace[1149008491] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:862; }","duration":"538.293998ms","start":"2026-03-08T02:25:01.030982Z","end":"2026-03-08T02:25:01.569276Z","steps":["trace[1149008491] 'agreement among raft nodes before linearized reading' (duration: 538.254687ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:25:01.569295Z","caller":"traceutil/trace.go:171","msg":"trace[1200602284] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:862; }","duration":"369.298769ms","start":"2026-03-08T02:25:01.199987Z","end":"2026-03-08T02:25:01.569286Z","steps":["trace[1200602284] 'agreement among raft nodes before linearized reading' (duration: 369.223647ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:01.569321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:01.03096Z","time spent":"538.353359ms","remote":"127.0.0.1:58198","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-03-08T02:25:01.569321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:01.199965Z","time spent":"369.349989ms","remote":"127.0.0.1:58496","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":"info","ts":"2026-03-08T02:25:16.119795Z","caller":"traceutil/trace.go:171","msg":"trace[48642971] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:949; }","duration":"475.272269ms","start":"2026-03-08T02:25:15.644496Z","end":"2026-03-08T02:25:16.119768Z","steps":["trace[48642971] 'read index received' (duration: 475.024514ms)","trace[48642971] 'applied index is now lower than readState.Index' (duration: 246.745µs)"],"step_count":2} {"level":"info","ts":"2026-03-08T02:25:16.120256Z","caller":"traceutil/trace.go:171","msg":"trace[871502205] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"485.693898ms","start":"2026-03-08T02:25:15.634546Z","end":"2026-03-08T02:25:16.12024Z","steps":["trace[871502205] 'process raft request' (duration: 485.063965ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:16.120433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:15.634529Z","time spent":"485.821871ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-08T02:25:16.12077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.264491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-08T02:25:16.120826Z","caller":"traceutil/trace.go:171","msg":"trace[1235726666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:904; }","duration":"476.342842ms","start":"2026-03-08T02:25:15.644469Z","end":"2026-03-08T02:25:16.120812Z","steps":["trace[1235726666] 'agreement among raft nodes before linearized reading' (duration: 476.188639ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:16.120866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:15.644459Z","time spent":"476.397364ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-08T02:25:16.121353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.777533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-08T02:25:16.121408Z","caller":"traceutil/trace.go:171","msg":"trace[1511713930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:904; }","duration":"472.851735ms","start":"2026-03-08T02:25:15.648543Z","end":"2026-03-08T02:25:16.121395Z","steps":["trace[1511713930] 'agreement among raft nodes before linearized reading' (duration: 472.717222ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:16.121446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:15.648535Z","time spent":"472.901155ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-08T02:25:16.122049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.69931ms","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-03-08T02:25:16.122115Z","caller":"traceutil/trace.go:171","msg":"trace[1852709700] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:904; }","duration":"471.780452ms","start":"2026-03-08T02:25:15.650317Z","end":"2026-03-08T02:25:16.122098Z","steps":["trace[1852709700] 'agreement among raft nodes before linearized reading' (duration: 471.635748ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:16.12227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:15.650309Z","time spent":"471.945586ms","remote":"127.0.0.1:58496","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-03-08T02:25:16.122432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.784144ms","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-03-08T02:25:16.122553Z","caller":"traceutil/trace.go:171","msg":"trace[1140794802] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:904; }","duration":"472.957178ms","start":"2026-03-08T02:25:15.649572Z","end":"2026-03-08T02:25:16.122529Z","steps":["trace[1140794802] 'agreement among raft nodes before linearized reading' (duration: 472.58026ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:16.12261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:15.649566Z","time spent":"473.03389ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-08T02:25:20.721446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080913206s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-08T02:25:20.721883Z","caller":"traceutil/trace.go:171","msg":"trace[1957779516] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:980; }","duration":"691.190149ms","start":"2026-03-08T02:25:20.030669Z","end":"2026-03-08T02:25:20.721859Z","steps":["trace[1957779516] 'read index received' (duration: 192.263596ms)","trace[1957779516] 'applied index is now lower than readState.Index' (duration: 498.924723ms)"],"step_count":2} {"level":"info","ts":"2026-03-08T02:25:20.721912Z","caller":"traceutil/trace.go:171","msg":"trace[1000632046] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"1.038693805s","start":"2026-03-08T02:25:19.683199Z","end":"2026-03-08T02:25:20.721892Z","steps":["trace[1000632046] 'process raft request' (duration: 1.038365876s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:20.722346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:19.68318Z","time spent":"1.038957261s","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-08T02:25:21.195636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052134261s","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-03-08T02:25:21.195712Z","caller":"traceutil/trace.go:171","msg":"trace[23158410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:934; }","duration":"1.052251914s","start":"2026-03-08T02:25:20.143441Z","end":"2026-03-08T02:25:21.195693Z","steps":["trace[23158410] 'agreement among raft nodes before linearized reading' (duration: 578.611452ms)","trace[23158410] 'range keys from in-memory index tree' (duration: 473.403587ms)"],"step_count":2} {"level":"warn","ts":"2026-03-08T02:25:21.195758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:20.14343Z","time spent":"1.052316956s","remote":"127.0.0.1:58496","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-03-08T02:25:21.195774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036511906s","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-03-08T02:25:21.195809Z","caller":"traceutil/trace.go:171","msg":"trace[983017754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:934; }","duration":"1.036570988s","start":"2026-03-08T02:25:20.15923Z","end":"2026-03-08T02:25:21.195801Z","steps":["trace[983017754] 'agreement among raft nodes before linearized reading' (duration: 562.797483ms)","trace[983017754] 'range keys from in-memory index tree' (duration: 473.696513ms)"],"step_count":2} {"level":"warn","ts":"2026-03-08T02:25:21.195829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:20.159216Z","time spent":"1.036609358s","remote":"127.0.0.1:58496","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-03-08T02:25:21.195891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1652251s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-08T02:25:21.195907Z","caller":"traceutil/trace.go:171","msg":"trace[569071218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"1.165285641s","start":"2026-03-08T02:25:20.030617Z","end":"2026-03-08T02:25:21.195902Z","steps":["trace[569071218] 'agreement among raft nodes before linearized reading' (duration: 691.366293ms)","trace[569071218] 'range keys from in-memory index tree' (duration: 473.883917ms)"],"step_count":2} {"level":"warn","ts":"2026-03-08T02:25:21.195919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:20.030567Z","time spent":"1.165349012s","remote":"127.0.0.1:58198","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-03-08T02:25:21.196038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061781524s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-08T02:25:21.196055Z","caller":"traceutil/trace.go:171","msg":"trace[442534593] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:934; }","duration":"1.061835755s","start":"2026-03-08T02:25:20.134215Z","end":"2026-03-08T02:25:21.196051Z","steps":["trace[442534593] 'agreement among raft nodes before linearized reading' (duration: 587.792754ms)","trace[442534593] 'range keys from in-memory index tree' (duration: 473.98095ms)"],"step_count":2} {"level":"warn","ts":"2026-03-08T02:25:21.196076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:20.134194Z","time spent":"1.061877157s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-08T02:25:21.196062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.051410916s","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-03-08T02:25:21.196112Z","caller":"traceutil/trace.go:171","msg":"trace[1463746540] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:934; }","duration":"1.051488007s","start":"2026-03-08T02:25:20.144619Z","end":"2026-03-08T02:25:21.196107Z","steps":["trace[1463746540] 'agreement among raft nodes before linearized reading' (duration: 577.416516ms)","trace[1463746540] 'range keys from in-memory index tree' (duration: 473.948099ms)"],"step_count":2} {"level":"warn","ts":"2026-03-08T02:25:21.196134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:20.144608Z","time spent":"1.051522239s","remote":"127.0.0.1:58496","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-03-08T02:25:21.19627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05249164s","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-03-08T02:25:21.196287Z","caller":"traceutil/trace.go:171","msg":"trace[1911943173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:934; }","duration":"1.052572201s","start":"2026-03-08T02:25:20.143711Z","end":"2026-03-08T02:25:21.196283Z","steps":["trace[1911943173] 'agreement among raft nodes before linearized reading' (duration: 578.333426ms)","trace[1911943173] 'range keys from in-memory index tree' (duration: 474.198225ms)"],"step_count":2} {"level":"warn","ts":"2026-03-08T02:25:21.1963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:20.14369Z","time spent":"1.052606573s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-08T02:25:21.342506Z","caller":"traceutil/trace.go:171","msg":"trace[1945190156] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"141.857864ms","start":"2026-03-08T02:25:21.200631Z","end":"2026-03-08T02:25:21.342489Z","steps":["trace[1945190156] 'process raft request' (duration: 141.748431ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:25:22.105535Z","caller":"traceutil/trace.go:171","msg":"trace[1485102143] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"899.302076ms","start":"2026-03-08T02:25:21.20621Z","end":"2026-03-08T02:25:22.105512Z","steps":["trace[1485102143] 'process raft request' (duration: 899.222414ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:22.105688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:21.2062Z","time spent":"899.412258ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T02:25:22.105792Z","caller":"traceutil/trace.go:171","msg":"trace[432325432] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:983; }","duration":"900.342519ms","start":"2026-03-08T02:25:21.205421Z","end":"2026-03-08T02:25:22.105763Z","steps":["trace[432325432] 'read index received' (duration: 137.111039ms)","trace[432325432] 'applied index is now lower than readState.Index' (duration: 763.23009ms)"],"step_count":2} {"level":"info","ts":"2026-03-08T02:25:22.105867Z","caller":"traceutil/trace.go:171","msg":"trace[558893875] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"899.542142ms","start":"2026-03-08T02:25:21.206308Z","end":"2026-03-08T02:25:22.10585Z","steps":["trace[558893875] 'process raft request' (duration: 899.163593ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:22.105893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.455491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-08T02:25:22.105924Z","caller":"traceutil/trace.go:171","msg":"trace[1720211525] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:939; }","duration":"900.525743ms","start":"2026-03-08T02:25:21.205388Z","end":"2026-03-08T02:25:22.105914Z","steps":["trace[1720211525] 'agreement among raft nodes before linearized reading' (duration: 900.459292ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:22.105954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:21.20538Z","time spent":"900.567564ms","remote":"127.0.0.1:58198","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-03-08T02:25:22.105968Z","caller":"traceutil/trace.go:171","msg":"trace[1598250135] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"903.285344ms","start":"2026-03-08T02:25:21.202669Z","end":"2026-03-08T02:25:22.105955Z","steps":["trace[1598250135] 'process raft request' (duration: 902.558117ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:22.105976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:21.206302Z","time spent":"899.609093ms","remote":"127.0.0.1:58496","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-03-08T02:25:22.106025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:21.202662Z","time spent":"903.332285ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T02:25:22.106123Z","caller":"traceutil/trace.go:171","msg":"trace[1507746917] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"901.062305ms","start":"2026-03-08T02:25:21.205046Z","end":"2026-03-08T02:25:22.106108Z","steps":["trace[1507746917] 'process raft request' (duration: 900.343438ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:22.106193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:21.205023Z","time spent":"901.137027ms","remote":"127.0.0.1:58496","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-03-08T02:25:23.351381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.685626ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-08T02:25:23.351584Z","caller":"traceutil/trace.go:171","msg":"trace[1047223161] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:991; }","duration":"320.681283ms","start":"2026-03-08T02:25:23.030886Z","end":"2026-03-08T02:25:23.351567Z","steps":["trace[1047223161] 'read index received' (duration: 269.044463ms)","trace[1047223161] 'applied index is now lower than readState.Index' (duration: 51.63582ms)"],"step_count":2} {"level":"info","ts":"2026-03-08T02:25:23.351674Z","caller":"traceutil/trace.go:171","msg":"trace[820849898] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"477.804064ms","start":"2026-03-08T02:25:22.873858Z","end":"2026-03-08T02:25:23.351662Z","steps":["trace[820849898] 'process raft request' (duration: 477.63805ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:25:23.351683Z","caller":"traceutil/trace.go:171","msg":"trace[333215073] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"554.200563ms","start":"2026-03-08T02:25:22.79745Z","end":"2026-03-08T02:25:23.351651Z","steps":["trace[333215073] 'process raft request' (duration: 69.140148ms)","trace[333215073] 'compare' (duration: 484.521443ms)"],"step_count":2} {"level":"warn","ts":"2026-03-08T02:25:23.35175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:22.873829Z","time spent":"477.893896ms","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-08T02:25:23.35182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:22.797432Z","time spent":"554.313465ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-08T02:25:23.351837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.939279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-08T02:25:23.351909Z","caller":"traceutil/trace.go:171","msg":"trace[805414340] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:945; }","duration":"321.043181ms","start":"2026-03-08T02:25:23.030847Z","end":"2026-03-08T02:25:23.35189Z","steps":["trace[805414340] 'agreement among raft nodes before linearized reading' (duration: 320.905658ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:23.351956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:23.03083Z","time spent":"321.118423ms","remote":"127.0.0.1:58198","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-03-08T02:25:24.533977Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154969845011776,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-08T02:25:24.568324Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.213551778s","expected-duration":"1s"} {"level":"info","ts":"2026-03-08T02:25:24.568779Z","caller":"traceutil/trace.go:171","msg":"trace[939913153] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"1.21409031s","start":"2026-03-08T02:25:23.354662Z","end":"2026-03-08T02:25:24.568752Z","steps":["trace[939913153] 'process raft request' (duration: 1.213898855s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:24.568911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:23.354645Z","time spent":"1.214192841s","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":835,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-08T02:25:24.909386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.331751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-08T02:25:24.909553Z","caller":"traceutil/trace.go:171","msg":"trace[2011585478] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:994; }","duration":"876.094582ms","start":"2026-03-08T02:25:24.033442Z","end":"2026-03-08T02:25:24.909537Z","steps":["trace[2011585478] 'read index received' (duration: 535.687353ms)","trace[2011585478] 'applied index is now lower than readState.Index' (duration: 340.406239ms)"],"step_count":2} {"level":"info","ts":"2026-03-08T02:25:24.909645Z","caller":"traceutil/trace.go:171","msg":"trace[1627315223] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"946.946288ms","start":"2026-03-08T02:25:23.962689Z","end":"2026-03-08T02:25:24.909635Z","steps":["trace[1627315223] 'process raft request' (duration: 946.791645ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:25:24.909638Z","caller":"traceutil/trace.go:171","msg":"trace[740232095] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"1.55274643s","start":"2026-03-08T02:25:23.356816Z","end":"2026-03-08T02:25:24.909562Z","steps":["trace[740232095] 'process raft request' (duration: 1.416154283s)","trace[740232095] 'compare' (duration: 136.205078ms)"],"step_count":2} {"level":"warn","ts":"2026-03-08T02:25:24.909708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:23.962658Z","time spent":"947.017979ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-08T02:25:24.909879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.468931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-08T02:25:24.909895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.504792ms","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-03-08T02:25:24.909906Z","caller":"traceutil/trace.go:171","msg":"trace[1879830582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:948; }","duration":"876.495222ms","start":"2026-03-08T02:25:24.033402Z","end":"2026-03-08T02:25:24.909897Z","steps":["trace[1879830582] 'agreement among raft nodes before linearized reading' (duration: 876.4531ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:24.909915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:23.356807Z","time spent":"1.552941084s","remote":"127.0.0.1:58496","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-03-08T02:25:24.909993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.048052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-08T02:25:24.910006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.938312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-08T02:25:24.910103Z","caller":"traceutil/trace.go:171","msg":"trace[83447541] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:948; }","duration":"800.102325ms","start":"2026-03-08T02:25:24.10998Z","end":"2026-03-08T02:25:24.910082Z","steps":["trace[83447541] 'agreement among raft nodes before linearized reading' (duration: 799.845938ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:24.910096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.380945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.33\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-03-08T02:25:24.910133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:24.109939Z","time spent":"800.187657ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-08T02:25:24.909939Z","caller":"traceutil/trace.go:171","msg":"trace[558263869] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:948; }","duration":"799.595243ms","start":"2026-03-08T02:25:24.110329Z","end":"2026-03-08T02:25:24.909925Z","steps":["trace[558263869] 'agreement among raft nodes before linearized reading' (duration: 799.451169ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:25:24.910098Z","caller":"traceutil/trace.go:171","msg":"trace[2030978526] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:948; }","duration":"799.189495ms","start":"2026-03-08T02:25:24.110881Z","end":"2026-03-08T02:25:24.91007Z","steps":["trace[2030978526] 'agreement among raft nodes before linearized reading' (duration: 798.904027ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:24.910209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:24.110308Z","time spent":"799.88333ms","remote":"127.0.0.1:58496","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-03-08T02:25:24.910221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:24.110866Z","time spent":"799.343759ms","remote":"127.0.0.1:58496","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-03-08T02:25:24.909936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:24.0332Z","time spent":"876.730027ms","remote":"127.0.0.1:58198","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-03-08T02:25:24.910159Z","caller":"traceutil/trace.go:171","msg":"trace[1282886829] range","detail":"{range_begin:/registry/masterleases/199.204.45.33; range_end:; response_count:1; response_revision:948; }","duration":"553.526038ms","start":"2026-03-08T02:25:24.356619Z","end":"2026-03-08T02:25:24.910145Z","steps":["trace[1282886829] 'agreement among raft nodes before linearized reading' (duration: 553.313334ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:24.910316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:24.3566Z","time spent":"553.708792ms","remote":"127.0.0.1:58212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.33\" "} {"level":"warn","ts":"2026-03-08T02:25:24.91044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.555405ms","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-03-08T02:25:24.91049Z","caller":"traceutil/trace.go:171","msg":"trace[1880173292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:948; }","duration":"800.665647ms","start":"2026-03-08T02:25:24.109812Z","end":"2026-03-08T02:25:24.910477Z","steps":["trace[1880173292] 'agreement among raft nodes before linearized reading' (duration: 799.905409ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:24.910527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:24.109791Z","time spent":"800.729728ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-08T02:25:25.41017Z","caller":"traceutil/trace.go:171","msg":"trace[2017493041] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"495.789072ms","start":"2026-03-08T02:25:24.914358Z","end":"2026-03-08T02:25:25.410147Z","steps":["trace[2017493041] 'process raft request' (duration: 495.589208ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:25.410288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:24.914332Z","time spent":"495.893245ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T02:25:25.410457Z","caller":"traceutil/trace.go:171","msg":"trace[456945131] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"495.917025ms","start":"2026-03-08T02:25:24.914518Z","end":"2026-03-08T02:25:25.410435Z","steps":["trace[456945131] 'process raft request' (duration: 495.582738ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:25.41051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:24.914488Z","time spent":"495.995716ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T02:25:25.410672Z","caller":"traceutil/trace.go:171","msg":"trace[974636785] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"495.577327ms","start":"2026-03-08T02:25:24.915084Z","end":"2026-03-08T02:25:25.410662Z","steps":["trace[974636785] 'process raft request' (duration: 495.220139ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:25.410721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:24.915069Z","time spent":"495.627209ms","remote":"127.0.0.1:58496","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-03-08T02:25:25.411481Z","caller":"traceutil/trace.go:171","msg":"trace[1604257670] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"496.046859ms","start":"2026-03-08T02:25:24.915401Z","end":"2026-03-08T02:25:25.411447Z","steps":["trace[1604257670] 'process raft request' (duration: 495.145839ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:25.411942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:24.915381Z","time spent":"496.189812ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T02:25:25.447846Z","caller":"traceutil/trace.go:171","msg":"trace[364076456] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"440.405ms","start":"2026-03-08T02:25:25.007411Z","end":"2026-03-08T02:25:25.447816Z","steps":["trace[364076456] 'process raft request' (duration: 440.217835ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:25.44801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:25.007378Z","time spent":"440.549573ms","remote":"127.0.0.1:58212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T02:25:27.329971Z","caller":"traceutil/trace.go:171","msg":"trace[1357278691] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"411.046269ms","start":"2026-03-08T02:25:26.9189Z","end":"2026-03-08T02:25:27.329947Z","steps":["trace[1357278691] 'process raft request' (duration: 410.815444ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:25:27.330073Z","caller":"traceutil/trace.go:171","msg":"trace[285479081] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1002; }","duration":"298.160636ms","start":"2026-03-08T02:25:27.03171Z","end":"2026-03-08T02:25:27.32987Z","steps":["trace[285479081] 'read index received' (duration: 298.018693ms)","trace[285479081] 'applied index is now lower than readState.Index' (duration: 140.853µs)"],"step_count":2} {"level":"warn","ts":"2026-03-08T02:25:27.330121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:26.918828Z","time spent":"411.215013ms","remote":"127.0.0.1:58496","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-03-08T02:25:27.33014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.399591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-08T02:25:27.330239Z","caller":"traceutil/trace.go:171","msg":"trace[1906476324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:954; }","duration":"298.542565ms","start":"2026-03-08T02:25:27.031672Z","end":"2026-03-08T02:25:27.330215Z","steps":["trace[1906476324] 'agreement among raft nodes before linearized reading' (duration: 298.370941ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:27.568959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.891119ms","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-03-08T02:25:27.569169Z","caller":"traceutil/trace.go:171","msg":"trace[364925231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:954; }","duration":"154.125795ms","start":"2026-03-08T02:25:27.415016Z","end":"2026-03-08T02:25:27.569142Z","steps":["trace[364925231] 'range keys from in-memory index tree' (duration: 153.708956ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:27.569259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.94398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-08T02:25:27.569528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.545916ms","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":"warn","ts":"2026-03-08T02:25:27.569553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.272011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-08T02:25:27.569582Z","caller":"traceutil/trace.go:171","msg":"trace[1851687473] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:954; }","duration":"155.629658ms","start":"2026-03-08T02:25:27.413937Z","end":"2026-03-08T02:25:27.569567Z","steps":["trace[1851687473] 'range keys from in-memory index tree' (duration: 155.424024ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:25:27.569605Z","caller":"traceutil/trace.go:171","msg":"trace[694169493] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:954; }","duration":"155.362863ms","start":"2026-03-08T02:25:27.414227Z","end":"2026-03-08T02:25:27.56959Z","steps":["trace[694169493] 'range keys from in-memory index tree' (duration: 155.067255ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:25:27.569414Z","caller":"traceutil/trace.go:171","msg":"trace[1458211606] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:954; }","duration":"154.302749ms","start":"2026-03-08T02:25:27.41506Z","end":"2026-03-08T02:25:27.569363Z","steps":["trace[1458211606] 'range keys from in-memory index tree' (duration: 153.770287ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:25:27.740209Z","caller":"traceutil/trace.go:171","msg":"trace[699064597] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"166.039997ms","start":"2026-03-08T02:25:27.57415Z","end":"2026-03-08T02:25:27.74019Z","steps":["trace[699064597] 'process raft request' (duration: 165.894764ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:25:28.321592Z","caller":"traceutil/trace.go:171","msg":"trace[522784867] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"746.899499ms","start":"2026-03-08T02:25:27.574669Z","end":"2026-03-08T02:25:28.321568Z","steps":["trace[522784867] 'process raft request' (duration: 746.814487ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:28.32176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:27.574652Z","time spent":"747.019411ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T02:25:28.321993Z","caller":"traceutil/trace.go:171","msg":"trace[122728671] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"747.066123ms","start":"2026-03-08T02:25:27.57488Z","end":"2026-03-08T02:25:28.321946Z","steps":["trace[122728671] 'process raft request' (duration: 746.656453ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:28.322161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:27.574867Z","time spent":"747.208476ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T02:25:28.321963Z","caller":"traceutil/trace.go:171","msg":"trace[1578160888] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1004; }","duration":"273.595503ms","start":"2026-03-08T02:25:28.048314Z","end":"2026-03-08T02:25:28.321909Z","steps":["trace[1578160888] 'read index received' (duration: 272.962179ms)","trace[1578160888] 'applied index is now lower than readState.Index' (duration: 631.964µs)"],"step_count":2} {"level":"info","ts":"2026-03-08T02:25:28.322278Z","caller":"traceutil/trace.go:171","msg":"trace[1321104054] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"747.620695ms","start":"2026-03-08T02:25:27.574531Z","end":"2026-03-08T02:25:28.322152Z","steps":["trace[1321104054] 'process raft request' (duration: 746.779206ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:28.322291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.87128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-08T02:25:28.322449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:27.574509Z","time spent":"747.86155ms","remote":"127.0.0.1:58496","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-03-08T02:25:28.322457Z","caller":"traceutil/trace.go:171","msg":"trace[1319437808] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:958; }","duration":"274.165466ms","start":"2026-03-08T02:25:28.048274Z","end":"2026-03-08T02:25:28.322439Z","steps":["trace[1319437808] 'agreement among raft nodes before linearized reading' (duration: 273.771338ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:29.070042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.158066ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-08T02:25:29.070178Z","caller":"traceutil/trace.go:171","msg":"trace[767410024] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:959; }","duration":"216.283378ms","start":"2026-03-08T02:25:28.853855Z","end":"2026-03-08T02:25:29.070139Z","steps":["trace[767410024] 'range keys from in-memory index tree' (duration: 216.140725ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:25:31.10541Z","caller":"traceutil/trace.go:171","msg":"trace[1857342010] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"518.360031ms","start":"2026-03-08T02:25:30.587011Z","end":"2026-03-08T02:25:31.105371Z","steps":["trace[1857342010] 'process raft request' (duration: 518.145336ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:31.105577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:30.586773Z","time spent":"518.720669ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-08T02:25:31.7542Z","caller":"traceutil/trace.go:171","msg":"trace[871498123] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"642.486213ms","start":"2026-03-08T02:25:31.111675Z","end":"2026-03-08T02:25:31.754161Z","steps":["trace[871498123] 'process raft request' (duration: 618.024422ms)","trace[871498123] 'compare' (duration: 23.580231ms)"],"step_count":2} {"level":"warn","ts":"2026-03-08T02:25:31.754325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:31.111641Z","time spent":"642.630366ms","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":868,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-08T02:25:32.218339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.987967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-08T02:25:32.218486Z","caller":"traceutil/trace.go:171","msg":"trace[2071068371] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1017; }","duration":"855.304164ms","start":"2026-03-08T02:25:31.363168Z","end":"2026-03-08T02:25:32.218472Z","steps":["trace[2071068371] 'read index received' (duration: 366.585958ms)","trace[2071068371] 'applied index is now lower than readState.Index' (duration: 488.717346ms)"],"step_count":2} {"level":"warn","ts":"2026-03-08T02:25:32.218819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.658082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-08T02:25:32.218894Z","caller":"traceutil/trace.go:171","msg":"trace[154420397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:970; }","duration":"855.784745ms","start":"2026-03-08T02:25:31.363093Z","end":"2026-03-08T02:25:32.218877Z","steps":["trace[154420397] 'agreement among raft nodes before linearized reading' (duration: 855.518728ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T02:25:32.218921Z","caller":"traceutil/trace.go:171","msg":"trace[354961300] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"1.103769342s","start":"2026-03-08T02:25:31.115135Z","end":"2026-03-08T02:25:32.218904Z","steps":["trace[354961300] 'process raft request' (duration: 793.14659ms)","trace[354961300] 'compare' (duration: 309.680411ms)"],"step_count":2} {"level":"info","ts":"2026-03-08T02:25:32.218929Z","caller":"traceutil/trace.go:171","msg":"trace[1991838164] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"760.368307ms","start":"2026-03-08T02:25:31.458547Z","end":"2026-03-08T02:25:32.218915Z","steps":["trace[1991838164] 'process raft request' (duration: 759.873666ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:32.218941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:31.363066Z","time spent":"855.866677ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-08T02:25:32.218992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.508721ms","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-03-08T02:25:32.219028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:31.458517Z","time spent":"760.448499ms","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":779,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-08T02:25:32.219064Z","caller":"traceutil/trace.go:171","msg":"trace[28986927] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:970; }","duration":"368.616203ms","start":"2026-03-08T02:25:31.850434Z","end":"2026-03-08T02:25:32.21905Z","steps":["trace[28986927] 'agreement among raft nodes before linearized reading' (duration: 368.430999ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:32.219101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:31.850415Z","time spent":"368.679854ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-08T02:25:32.218992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.535387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-08T02:25:32.219188Z","caller":"traceutil/trace.go:171","msg":"trace[520481591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:970; }","duration":"189.781113ms","start":"2026-03-08T02:25:32.029393Z","end":"2026-03-08T02:25:32.219174Z","steps":["trace[520481591] 'agreement among raft nodes before linearized reading' (duration: 189.540758ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:25:32.219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T02:25:31.115115Z","time spent":"1.103849053s","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T02:26:08.638561Z","caller":"traceutil/trace.go:171","msg":"trace[1351739885] linearizableReadLoop","detail":"{readStateIndex:1200; appliedIndex:1198; }","duration":"104.770424ms","start":"2026-03-08T02:26:08.53374Z","end":"2026-03-08T02:26:08.63851Z","steps":["trace[1351739885] 'read index received' (duration: 48.45631ms)","trace[1351739885] 'applied index is now lower than readState.Index' (duration: 56.312824ms)"],"step_count":2} {"level":"info","ts":"2026-03-08T02:26:08.638898Z","caller":"traceutil/trace.go:171","msg":"trace[2118922909] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"111.611575ms","start":"2026-03-08T02:26:08.527271Z","end":"2026-03-08T02:26:08.638883Z","steps":["trace[2118922909] 'process raft request' (duration: 111.070313ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T02:26:08.639061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.300445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-03-08T02:26:08.63912Z","caller":"traceutil/trace.go:171","msg":"trace[835714516] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1143; }","duration":"105.404688ms","start":"2026-03-08T02:26:08.533701Z","end":"2026-03-08T02:26:08.639106Z","steps":["trace[835714516] 'agreement among raft nodes before linearized reading' (duration: 105.237484ms)"],"step_count":1}