{"level":"warn","ts":"2026-04-27T19:38:38.942234Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T19:38:38.942311Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.169: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.19.213.169:2380","--initial-cluster=instance=https://199.19.213.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.169: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-04-27T19:38:38.942376Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T19:38:38.942385Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.169:2380"]} {"level":"info","ts":"2026-04-27T19:38:38.942402Z","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-04-27T19:38:38.943091Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.169:2379"]} {"level":"info","ts":"2026-04-27T19:38:38.943174Z","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.19.213.169:2380"],"listen-peer-urls":["https://199.19.213.169:2380"],"advertise-client-urls":["https://199.19.213.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.169: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-04-27T19:38:38.94776Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.230454ms"} {"level":"info","ts":"2026-04-27T19:38:38.955237Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a742f9f5aa7326e5","cluster-id":"a6b466adbadbbcd5"} {"level":"info","ts":"2026-04-27T19:38:38.955319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 switched to configuration voters=()"} {"level":"info","ts":"2026-04-27T19:38:38.955357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became follower at term 0"} {"level":"info","ts":"2026-04-27T19:38:38.955374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a742f9f5aa7326e5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-27T19:38:38.955384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became follower at term 1"} {"level":"info","ts":"2026-04-27T19:38:38.955442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 switched to configuration voters=(12052470386318845669)"} {"level":"warn","ts":"2026-04-27T19:38:38.962756Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-27T19:38:38.96464Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-27T19:38:38.968201Z","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-04-27T19:38:38.971693Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a742f9f5aa7326e5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-27T19:38:38.971963Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a742f9f5aa7326e5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-27T19:38:38.972148Z","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-04-27T19:38:38.972275Z","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-04-27T19:38:38.972286Z","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-04-27T19:38:38.97745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 switched to configuration voters=(12052470386318845669)"} {"level":"info","ts":"2026-04-27T19:38:38.977537Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a6b466adbadbbcd5","local-member-id":"a742f9f5aa7326e5","added-peer-id":"a742f9f5aa7326e5","added-peer-peer-urls":["https://199.19.213.169:2380"]} {"level":"info","ts":"2026-04-27T19:38:38.978148Z","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-04-27T19:38:38.97825Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.169:2380"} {"level":"info","ts":"2026-04-27T19:38:38.978274Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.169:2380"} {"level":"info","ts":"2026-04-27T19:38:38.978315Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a742f9f5aa7326e5","initial-advertise-peer-urls":["https://199.19.213.169:2380"],"listen-peer-urls":["https://199.19.213.169:2380"],"advertise-client-urls":["https://199.19.213.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-27T19:38:38.978375Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-27T19:38:39.756104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 is starting a new election at term 1"} {"level":"info","ts":"2026-04-27T19:38:39.756159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-27T19:38:39.756197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 received MsgPreVoteResp from a742f9f5aa7326e5 at term 1"} {"level":"info","ts":"2026-04-27T19:38:39.756216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became candidate at term 2"} {"level":"info","ts":"2026-04-27T19:38:39.756228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 received MsgVoteResp from a742f9f5aa7326e5 at term 2"} {"level":"info","ts":"2026-04-27T19:38:39.75625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became leader at term 2"} {"level":"info","ts":"2026-04-27T19:38:39.756264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a742f9f5aa7326e5 elected leader a742f9f5aa7326e5 at term 2"} {"level":"info","ts":"2026-04-27T19:38:39.758657Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a742f9f5aa7326e5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.169:2379]}","request-path":"/0/members/a742f9f5aa7326e5/attributes","cluster-id":"a6b466adbadbbcd5","publish-timeout":"7s"} {"level":"info","ts":"2026-04-27T19:38:39.758658Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T19:38:39.758699Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T19:38:39.759557Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T19:38:39.760012Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a6b466adbadbbcd5","local-member-id":"a742f9f5aa7326e5","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T19:38:39.760107Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T19:38:39.760136Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T19:38:39.761357Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.169:2379"} {"level":"info","ts":"2026-04-27T19:38:39.762285Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-27T19:38:39.762346Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-27T19:38:39.763365Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-27T19:38:43.157426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.416024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:113"} {"level":"info","ts":"2026-04-27T19:38:43.15756Z","caller":"traceutil/trace.go:171","msg":"trace[1884874307] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:192; }","duration":"128.601295ms","start":"2026-04-27T19:38:43.028934Z","end":"2026-04-27T19:38:43.157535Z","steps":["trace[1884874307] 'range keys from in-memory index tree' (duration: 128.276183ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:43.359654Z","caller":"traceutil/trace.go:171","msg":"trace[47043991] linearizableReadLoop","detail":"{readStateIndex:204; appliedIndex:203; }","duration":"122.464489ms","start":"2026-04-27T19:38:43.237158Z","end":"2026-04-27T19:38:43.359622Z","steps":["trace[47043991] 'read index received' (duration: 19.682613ms)","trace[47043991] 'applied index is now lower than readState.Index' (duration: 102.780676ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:38:43.359764Z","caller":"traceutil/trace.go:171","msg":"trace[1398279218] transaction","detail":"{read_only:false; response_revision:198; number_of_response:1; }","duration":"142.18651ms","start":"2026-04-27T19:38:43.217551Z","end":"2026-04-27T19:38:43.359737Z","steps":["trace[1398279218] 'process raft request' (duration: 64.199477ms)","trace[1398279218] 'compare' (duration: 77.665961ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:38:43.359889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.72218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:38:43.359962Z","caller":"traceutil/trace.go:171","msg":"trace[1811714322] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:198; }","duration":"122.824701ms","start":"2026-04-27T19:38:43.237109Z","end":"2026-04-27T19:38:43.359934Z","steps":["trace[1811714322] 'agreement among raft nodes before linearized reading' (duration: 122.63986ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:43.367107Z","caller":"traceutil/trace.go:171","msg":"trace[1913524188] transaction","detail":"{read_only:false; response_revision:199; number_of_response:1; }","duration":"108.926903ms","start":"2026-04-27T19:38:43.258152Z","end":"2026-04-27T19:38:43.367079Z","steps":["trace[1913524188] 'process raft request' (duration: 108.800812ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:43.554447Z","caller":"traceutil/trace.go:171","msg":"trace[91132661] linearizableReadLoop","detail":"{readStateIndex:212; appliedIndex:211; }","duration":"152.484651ms","start":"2026-04-27T19:38:43.401908Z","end":"2026-04-27T19:38:43.554392Z","steps":["trace[91132661] 'read index received' (duration: 68.743583ms)","trace[91132661] 'applied index is now lower than readState.Index' (duration: 83.739958ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:38:43.554571Z","caller":"traceutil/trace.go:171","msg":"trace[2020201009] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"153.892631ms","start":"2026-04-27T19:38:43.400665Z","end":"2026-04-27T19:38:43.554558Z","steps":["trace[2020201009] 'process raft request' (duration: 70.056282ms)","trace[2020201009] 'compare' (duration: 83.568237ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:38:43.55482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.880634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:38:43.554881Z","caller":"traceutil/trace.go:171","msg":"trace[683194445] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:0; response_revision:205; }","duration":"152.985275ms","start":"2026-04-27T19:38:43.40188Z","end":"2026-04-27T19:38:43.554866Z","steps":["trace[683194445] 'agreement among raft nodes before linearized reading' (duration: 152.809523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:38:43.554952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.454126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:38:43.555286Z","caller":"traceutil/trace.go:171","msg":"trace[1117141127] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:0; response_revision:205; }","duration":"145.89326ms","start":"2026-04-27T19:38:43.409374Z","end":"2026-04-27T19:38:43.555267Z","steps":["trace[1117141127] 'agreement among raft nodes before linearized reading' (duration: 145.307285ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:43.7463Z","caller":"traceutil/trace.go:171","msg":"trace[1013872903] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"102.612025ms","start":"2026-04-27T19:38:43.643652Z","end":"2026-04-27T19:38:43.746264Z","steps":["trace[1013872903] 'process raft request' (duration: 39.97095ms)","trace[1013872903] 'compare' (duration: 62.484254ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:38:44.22174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.476694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:38:44.221945Z","caller":"traceutil/trace.go:171","msg":"trace[107687949] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"112.353595ms","start":"2026-04-27T19:38:44.109569Z","end":"2026-04-27T19:38:44.221923Z","steps":["trace[107687949] 'process raft request' (duration: 112.279174ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:44.221985Z","caller":"traceutil/trace.go:171","msg":"trace[1669580204] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"136.454561ms","start":"2026-04-27T19:38:44.0855Z","end":"2026-04-27T19:38:44.221955Z","steps":["trace[1669580204] 'process raft request' (duration: 26.116901ms)","trace[1669580204] 'compare' (duration: 109.364793ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:38:44.471658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.019823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:38:44.47177Z","caller":"traceutil/trace.go:171","msg":"trace[903176075] linearizableReadLoop","detail":"{readStateIndex:255; appliedIndex:254; }","duration":"161.902606ms","start":"2026-04-27T19:38:44.309849Z","end":"2026-04-27T19:38:44.471751Z","steps":["trace[903176075] 'read index received' (duration: 32.640322ms)","trace[903176075] 'applied index is now lower than readState.Index' (duration: 129.260874ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:38:44.471816Z","caller":"traceutil/trace.go:171","msg":"trace[132030238] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"162.165668ms","start":"2026-04-27T19:38:44.309627Z","end":"2026-04-27T19:38:44.471792Z","steps":["trace[132030238] 'process raft request' (duration: 32.927823ms)","trace[132030238] 'compare' (duration: 128.897102ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:38:44.471847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.998067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:38:44.47188Z","caller":"traceutil/trace.go:171","msg":"trace[1557950671] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:0; response_revision:248; }","duration":"162.051427ms","start":"2026-04-27T19:38:44.30982Z","end":"2026-04-27T19:38:44.471871Z","steps":["trace[1557950671] 'agreement among raft nodes before linearized reading' (duration: 161.977567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:38:44.737571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.312586ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:38:44.737916Z","caller":"traceutil/trace.go:171","msg":"trace[1371307404] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"178.949478ms","start":"2026-04-27T19:38:44.558943Z","end":"2026-04-27T19:38:44.737892Z","steps":["trace[1371307404] 'process raft request' (duration: 178.826407ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:44.737985Z","caller":"traceutil/trace.go:171","msg":"trace[1468956221] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"185.515907ms","start":"2026-04-27T19:38:44.552439Z","end":"2026-04-27T19:38:44.737955Z","steps":["trace[1468956221] 'process raft request' (duration: 77.754848ms)","trace[1468956221] 'compare' (duration: 107.156635ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:38:44.738072Z","caller":"traceutil/trace.go:171","msg":"trace[776427533] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"185.113435ms","start":"2026-04-27T19:38:44.55291Z","end":"2026-04-27T19:38:44.738023Z","steps":["trace[776427533] 'process raft request' (duration: 184.817653ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:44.737952Z","caller":"traceutil/trace.go:171","msg":"trace[223101247] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"185.361887ms","start":"2026-04-27T19:38:44.552529Z","end":"2026-04-27T19:38:44.737891Z","steps":["trace[223101247] 'process raft request' (duration: 185.145615ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:44.938276Z","caller":"traceutil/trace.go:171","msg":"trace[1982690073] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"123.705013ms","start":"2026-04-27T19:38:44.814545Z","end":"2026-04-27T19:38:44.93825Z","steps":["trace[1982690073] 'process raft request' (duration: 123.665472ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:44.938441Z","caller":"traceutil/trace.go:171","msg":"trace[1236412809] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"124.533239ms","start":"2026-04-27T19:38:44.81387Z","end":"2026-04-27T19:38:44.938404Z","steps":["trace[1236412809] 'process raft request' (duration: 124.309837ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:44.938526Z","caller":"traceutil/trace.go:171","msg":"trace[1591696723] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"139.238882ms","start":"2026-04-27T19:38:44.799201Z","end":"2026-04-27T19:38:44.93844Z","steps":["trace[1591696723] 'process raft request' (duration: 82.293073ms)","trace[1591696723] 'compare' (duration: 56.460794ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:38:44.938988Z","caller":"traceutil/trace.go:171","msg":"trace[1633880048] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"137.590719ms","start":"2026-04-27T19:38:44.800805Z","end":"2026-04-27T19:38:44.938396Z","steps":["trace[1633880048] 'process raft request' (duration: 137.316437ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:45.173136Z","caller":"traceutil/trace.go:171","msg":"trace[223399246] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"100.885483ms","start":"2026-04-27T19:38:45.072223Z","end":"2026-04-27T19:38:45.173108Z","steps":["trace[223399246] 'process raft request' (duration: 87.741503ms)","trace[223399246] 'compare' (duration: 13.007959ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:38:45.49176Z","caller":"traceutil/trace.go:171","msg":"trace[482861731] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"111.567865ms","start":"2026-04-27T19:38:45.380163Z","end":"2026-04-27T19:38:45.49173Z","steps":["trace[482861731] 'process raft request' (duration: 47.189452ms)","trace[482861731] 'compare' (duration: 64.03389ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:38:45.492198Z","caller":"traceutil/trace.go:171","msg":"trace[1250183590] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"111.372822ms","start":"2026-04-27T19:38:45.380807Z","end":"2026-04-27T19:38:45.49218Z","steps":["trace[1250183590] 'process raft request' (duration: 110.824428ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:45.492235Z","caller":"traceutil/trace.go:171","msg":"trace[908702261] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"110.031342ms","start":"2026-04-27T19:38:45.38219Z","end":"2026-04-27T19:38:45.492221Z","steps":["trace[908702261] 'process raft request' (duration: 109.923331ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:45.492366Z","caller":"traceutil/trace.go:171","msg":"trace[118408310] linearizableReadLoop","detail":"{readStateIndex:297; appliedIndex:295; }","duration":"110.296434ms","start":"2026-04-27T19:38:45.382033Z","end":"2026-04-27T19:38:45.49233Z","steps":["trace[118408310] 'read index received' (duration: 45.330297ms)","trace[118408310] 'applied index is now lower than readState.Index' (duration: 64.962757ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:38:45.49252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.467535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-27T19:38:45.49257Z","caller":"traceutil/trace.go:171","msg":"trace[1336283290] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:291; }","duration":"110.553056ms","start":"2026-04-27T19:38:45.382004Z","end":"2026-04-27T19:38:45.492557Z","steps":["trace[1336283290] 'agreement among raft nodes before linearized reading' (duration: 110.439505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:38:45.521003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.028052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:38:45.521007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.810284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:38:45.521091Z","caller":"traceutil/trace.go:171","msg":"trace[1612788532] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:291; }","duration":"136.120103ms","start":"2026-04-27T19:38:45.384945Z","end":"2026-04-27T19:38:45.521065Z","steps":["trace[1612788532] 'agreement among raft nodes before linearized reading' (duration: 136.001992ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:45.521103Z","caller":"traceutil/trace.go:171","msg":"trace[1734643106] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:0; response_revision:291; }","duration":"112.933195ms","start":"2026-04-27T19:38:45.408148Z","end":"2026-04-27T19:38:45.521081Z","steps":["trace[1734643106] 'agreement among raft nodes before linearized reading' (duration: 112.756164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:38:45.520992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.469479ms","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-04-27T19:38:45.521243Z","caller":"traceutil/trace.go:171","msg":"trace[788793273] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:291; }","duration":"117.760172ms","start":"2026-04-27T19:38:45.403458Z","end":"2026-04-27T19:38:45.521219Z","steps":["trace[788793273] 'agreement among raft nodes before linearized reading' (duration: 117.45347ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:45.621728Z","caller":"traceutil/trace.go:171","msg":"trace[2127779927] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"126.360788ms","start":"2026-04-27T19:38:45.49534Z","end":"2026-04-27T19:38:45.621701Z","steps":["trace[2127779927] 'process raft request' (duration: 126.214966ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:45.621936Z","caller":"traceutil/trace.go:171","msg":"trace[1287681713] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"127.019153ms","start":"2026-04-27T19:38:45.494905Z","end":"2026-04-27T19:38:45.621924Z","steps":["trace[1287681713] 'process raft request' (duration: 106.097262ms)","trace[1287681713] 'compare' (duration: 20.416466ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:38:45.622009Z","caller":"traceutil/trace.go:171","msg":"trace[782833229] transaction","detail":"{read_only:false; number_of_response:0; response_revision:293; }","duration":"124.776055ms","start":"2026-04-27T19:38:45.497211Z","end":"2026-04-27T19:38:45.621987Z","steps":["trace[782833229] 'process raft request' (duration: 124.389672ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:45.622186Z","caller":"traceutil/trace.go:171","msg":"trace[655050768] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"123.737528ms","start":"2026-04-27T19:38:45.498438Z","end":"2026-04-27T19:38:45.622175Z","steps":["trace[655050768] 'process raft request' (duration: 123.271334ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:45.622276Z","caller":"traceutil/trace.go:171","msg":"trace[184251976] linearizableReadLoop","detail":"{readStateIndex:306; appliedIndex:299; }","duration":"101.363355ms","start":"2026-04-27T19:38:45.520889Z","end":"2026-04-27T19:38:45.622253Z","steps":["trace[184251976] 'read index received' (duration: 80.125262ms)","trace[184251976] 'applied index is now lower than readState.Index' (duration: 21.235893ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:38:45.622291Z","caller":"traceutil/trace.go:171","msg":"trace[1944657798] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"124.306622ms","start":"2026-04-27T19:38:45.497944Z","end":"2026-04-27T19:38:45.622251Z","steps":["trace[1944657798] 'process raft request' (duration: 123.697298ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:45.622312Z","caller":"traceutil/trace.go:171","msg":"trace[983708790] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"124.273002ms","start":"2026-04-27T19:38:45.498022Z","end":"2026-04-27T19:38:45.622295Z","steps":["trace[983708790] 'process raft request' (duration: 123.661898ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:45.622372Z","caller":"traceutil/trace.go:171","msg":"trace[1142024857] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"123.277604ms","start":"2026-04-27T19:38:45.499064Z","end":"2026-04-27T19:38:45.622341Z","steps":["trace[1142024857] 'process raft request' (duration: 122.669819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:38:45.622444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.775268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-04-27T19:38:45.622447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.245457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-04-27T19:38:45.62248Z","caller":"traceutil/trace.go:171","msg":"trace[1651002474] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:298; }","duration":"127.866709ms","start":"2026-04-27T19:38:45.494602Z","end":"2026-04-27T19:38:45.622468Z","steps":["trace[1651002474] 'agreement among raft nodes before linearized reading' (duration: 127.751988ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:45.622484Z","caller":"traceutil/trace.go:171","msg":"trace[377359376] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:298; }","duration":"100.307738ms","start":"2026-04-27T19:38:45.522165Z","end":"2026-04-27T19:38:45.622473Z","steps":["trace[377359376] 'agreement among raft nodes before linearized reading' (duration: 100.233587ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:45.870328Z","caller":"traceutil/trace.go:171","msg":"trace[2111219960] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"213.301182ms","start":"2026-04-27T19:38:45.656994Z","end":"2026-04-27T19:38:45.870296Z","steps":["trace[2111219960] 'process raft request' (duration: 213.173421ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:45.870335Z","caller":"traceutil/trace.go:171","msg":"trace[1802285925] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:312; }","duration":"188.934136ms","start":"2026-04-27T19:38:45.681376Z","end":"2026-04-27T19:38:45.87031Z","steps":["trace[1802285925] 'read index received' (duration: 108.053507ms)","trace[1802285925] 'applied index is now lower than readState.Index' (duration: 80.878449ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:38:45.870743Z","caller":"traceutil/trace.go:171","msg":"trace[1606577223] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"219.283278ms","start":"2026-04-27T19:38:45.650981Z","end":"2026-04-27T19:38:45.870264Z","steps":["trace[1606577223] 'process raft request' (duration: 138.432689ms)","trace[1606577223] 'compare' (duration: 80.526437ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:38:45.870796Z","caller":"traceutil/trace.go:171","msg":"trace[1158021925] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"185.036977ms","start":"2026-04-27T19:38:45.68573Z","end":"2026-04-27T19:38:45.870767Z","steps":["trace[1158021925] 'process raft request' (duration: 184.517413ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:45.870879Z","caller":"traceutil/trace.go:171","msg":"trace[2115425463] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"184.686863ms","start":"2026-04-27T19:38:45.686183Z","end":"2026-04-27T19:38:45.87087Z","steps":["trace[2115425463] 'process raft request' (duration: 184.508161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:38:45.871168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.653829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-27T19:38:45.871251Z","caller":"traceutil/trace.go:171","msg":"trace[1051533094] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:307; }","duration":"220.84376ms","start":"2026-04-27T19:38:45.650385Z","end":"2026-04-27T19:38:45.871228Z","steps":["trace[1051533094] 'agreement among raft nodes before linearized reading' (duration: 220.352796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:38:46.117768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.22454ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:38:46.117983Z","caller":"traceutil/trace.go:171","msg":"trace[1987415599] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"236.348085ms","start":"2026-04-27T19:38:45.881612Z","end":"2026-04-27T19:38:46.11796Z","steps":["trace[1987415599] 'process raft request' (duration: 236.273164ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:46.118244Z","caller":"traceutil/trace.go:171","msg":"trace[39736886] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"237.729705ms","start":"2026-04-27T19:38:45.88043Z","end":"2026-04-27T19:38:46.11816Z","steps":["trace[39736886] 'process raft request' (duration: 106.038722ms)","trace[39736886] 'compare' (duration: 131.033008ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:38:46.334464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.082352ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:38:46.334641Z","caller":"traceutil/trace.go:171","msg":"trace[1416580591] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"131.869574ms","start":"2026-04-27T19:38:46.202735Z","end":"2026-04-27T19:38:46.334604Z","steps":["trace[1416580591] 'process raft request' (duration: 31.5877ms)","trace[1416580591] 'compare' (duration: 99.960751ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:38:46.334748Z","caller":"traceutil/trace.go:171","msg":"trace[1162913913] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"130.373192ms","start":"2026-04-27T19:38:46.204358Z","end":"2026-04-27T19:38:46.334731Z","steps":["trace[1162913913] 'process raft request' (duration: 130.19555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:38:46.743013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.660757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-04-27T19:38:46.743101Z","caller":"traceutil/trace.go:171","msg":"trace[1488193996] transaction","detail":"{read_only:false; number_of_response:0; response_revision:320; }","duration":"332.20839ms","start":"2026-04-27T19:38:46.410879Z","end":"2026-04-27T19:38:46.743087Z","steps":["trace[1488193996] 'process raft request' (duration: 114.419222ms)","trace[1488193996] 'compare' (duration: 217.614637ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:38:46.743151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:38:46.410862Z","time spent":"332.27323ms","remote":"127.0.0.1:38726","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:38:46.788153Z","caller":"traceutil/trace.go:171","msg":"trace[1343491910] linearizableReadLoop","detail":"{readStateIndex:334; appliedIndex:329; }","duration":"329.072436ms","start":"2026-04-27T19:38:46.459062Z","end":"2026-04-27T19:38:46.788134Z","steps":["trace[1343491910] 'read index received' (duration: 66.246525ms)","trace[1343491910] 'applied index is now lower than readState.Index' (duration: 262.825291ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:38:46.788161Z","caller":"traceutil/trace.go:171","msg":"trace[629196429] transaction","detail":"{read_only:false; number_of_response:0; response_revision:320; }","duration":"376.156964ms","start":"2026-04-27T19:38:46.411982Z","end":"2026-04-27T19:38:46.788139Z","steps":["trace[629196429] 'process raft request' (duration: 376.072153ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:46.788236Z","caller":"traceutil/trace.go:171","msg":"trace[442137085] transaction","detail":"{read_only:false; number_of_response:0; response_revision:320; }","duration":"375.617289ms","start":"2026-04-27T19:38:46.412613Z","end":"2026-04-27T19:38:46.78823Z","steps":["trace[442137085] 'process raft request' (duration: 375.455618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:38:46.788265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:38:46.411954Z","time spent":"376.264674ms","remote":"127.0.0.1:38726","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T19:38:46.788434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:38:46.412263Z","time spent":"375.989573ms","remote":"127.0.0.1:38726","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T19:38:46.788547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.047635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"warn","ts":"2026-04-27T19:38:46.788541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.448718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-27T19:38:46.788604Z","caller":"traceutil/trace.go:171","msg":"trace[538514654] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:321; }","duration":"279.114235ms","start":"2026-04-27T19:38:46.509474Z","end":"2026-04-27T19:38:46.788588Z","steps":["trace[538514654] 'agreement among raft nodes before linearized reading' (duration: 278.986444ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:46.788622Z","caller":"traceutil/trace.go:171","msg":"trace[567024103] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:321; }","duration":"329.543759ms","start":"2026-04-27T19:38:46.459056Z","end":"2026-04-27T19:38:46.7886Z","steps":["trace[567024103] 'agreement among raft nodes before linearized reading' (duration: 329.327037ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:46.788649Z","caller":"traceutil/trace.go:171","msg":"trace[1754963659] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"353.276389ms","start":"2026-04-27T19:38:46.435363Z","end":"2026-04-27T19:38:46.788639Z","steps":["trace[1754963659] 'process raft request' (duration: 352.722215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:38:46.788663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:38:46.459042Z","time spent":"329.613369ms","remote":"127.0.0.1:38674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-27T19:38:46.788696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:38:46.435343Z","time spent":"353.32575ms","remote":"127.0.0.1:38726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:38:46.789331Z","caller":"traceutil/trace.go:171","msg":"trace[493648985] transaction","detail":"{read_only:false; number_of_response:0; response_revision:320; }","duration":"377.905807ms","start":"2026-04-27T19:38:46.411397Z","end":"2026-04-27T19:38:46.789302Z","steps":["trace[493648985] 'process raft request' (duration: 376.599278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:38:46.790385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:38:46.411381Z","time spent":"378.078058ms","remote":"127.0.0.1:38726","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:38:47.010219Z","caller":"traceutil/trace.go:171","msg":"trace[107886978] linearizableReadLoop","detail":"{readStateIndex:336; appliedIndex:335; }","duration":"171.999239ms","start":"2026-04-27T19:38:46.838156Z","end":"2026-04-27T19:38:47.010155Z","steps":["trace[107886978] 'read index received' (duration: 138.312762ms)","trace[107886978] 'applied index is now lower than readState.Index' (duration: 33.685457ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:38:47.010727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.544993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:38:47.010774Z","caller":"traceutil/trace.go:171","msg":"trace[90499677] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:0; response_revision:323; }","duration":"172.641463ms","start":"2026-04-27T19:38:46.838119Z","end":"2026-04-27T19:38:47.01076Z","steps":["trace[90499677] 'agreement among raft nodes before linearized reading' (duration: 172.539322ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:47.011856Z","caller":"traceutil/trace.go:171","msg":"trace[2145127794] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"213.705597ms","start":"2026-04-27T19:38:46.796873Z","end":"2026-04-27T19:38:47.010579Z","steps":["trace[2145127794] 'process raft request' (duration: 179.648328ms)","trace[2145127794] 'compare' (duration: 33.519175ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:38:47.236912Z","caller":"traceutil/trace.go:171","msg":"trace[1553397942] linearizableReadLoop","detail":"{readStateIndex:341; appliedIndex:340; }","duration":"138.601969ms","start":"2026-04-27T19:38:47.098276Z","end":"2026-04-27T19:38:47.236878Z","steps":["trace[1553397942] 'read index received' (duration: 112.545382ms)","trace[1553397942] 'applied index is now lower than readState.Index' (duration: 26.055487ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:38:47.236997Z","caller":"traceutil/trace.go:171","msg":"trace[1072195504] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"150.917024ms","start":"2026-04-27T19:38:47.08604Z","end":"2026-04-27T19:38:47.236957Z","steps":["trace[1072195504] 'process raft request' (duration: 124.870846ms)","trace[1072195504] 'compare' (duration: 25.867086ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:38:47.237071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.774381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-04-27T19:38:47.237161Z","caller":"traceutil/trace.go:171","msg":"trace[1118113233] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:328; }","duration":"138.926692ms","start":"2026-04-27T19:38:47.098219Z","end":"2026-04-27T19:38:47.237145Z","steps":["trace[1118113233] 'agreement among raft nodes before linearized reading' (duration: 138.762951ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:47.76076Z","caller":"traceutil/trace.go:171","msg":"trace[2084676667] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"118.269115ms","start":"2026-04-27T19:38:47.642462Z","end":"2026-04-27T19:38:47.760731Z","steps":["trace[2084676667] 'process raft request' (duration: 48.814909ms)","trace[2084676667] 'compare' (duration: 69.196724ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:38:48.852305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.785313ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:38:48.852782Z","caller":"traceutil/trace.go:171","msg":"trace[292718356] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"280.897716ms","start":"2026-04-27T19:38:48.57186Z","end":"2026-04-27T19:38:48.852758Z","steps":["trace[292718356] 'process raft request' (duration: 95.512099ms)","trace[292718356] 'compare' (duration: 184.584081ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:38:48.852898Z","caller":"traceutil/trace.go:171","msg":"trace[27885228] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"267.096882ms","start":"2026-04-27T19:38:48.585631Z","end":"2026-04-27T19:38:48.852728Z","steps":["trace[27885228] 'process raft request' (duration: 266.941891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:38:48.85302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.470665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-27T19:38:48.853094Z","caller":"traceutil/trace.go:171","msg":"trace[2118491852] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:366; }","duration":"243.604445ms","start":"2026-04-27T19:38:48.609465Z","end":"2026-04-27T19:38:48.853069Z","steps":["trace[2118491852] 'agreement among raft nodes before linearized reading' (duration: 243.424964ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:48.852687Z","caller":"traceutil/trace.go:171","msg":"trace[1623364050] linearizableReadLoop","detail":"{readStateIndex:379; appliedIndex:377; }","duration":"243.144052ms","start":"2026-04-27T19:38:48.609513Z","end":"2026-04-27T19:38:48.852657Z","steps":["trace[1623364050] 'read index received' (duration: 57.841826ms)","trace[1623364050] 'applied index is now lower than readState.Index' (duration: 185.300756ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:38:48.853699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.077113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-04-27T19:38:48.853727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.817794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-27T19:38:48.85376Z","caller":"traceutil/trace.go:171","msg":"trace[74294706] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:366; }","duration":"194.187513ms","start":"2026-04-27T19:38:48.659553Z","end":"2026-04-27T19:38:48.853741Z","steps":["trace[74294706] 'agreement among raft nodes before linearized reading' (duration: 194.076792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:38:48.853782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.981354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-04-27T19:38:48.853858Z","caller":"traceutil/trace.go:171","msg":"trace[826011734] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:366; }","duration":"144.100966ms","start":"2026-04-27T19:38:48.709739Z","end":"2026-04-27T19:38:48.85384Z","steps":["trace[826011734] 'agreement among raft nodes before linearized reading' (duration: 143.941455ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:48.853912Z","caller":"traceutil/trace.go:171","msg":"trace[460187688] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:366; }","duration":"143.924644ms","start":"2026-04-27T19:38:48.709845Z","end":"2026-04-27T19:38:48.853769Z","steps":["trace[460187688] 'agreement among raft nodes before linearized reading' (duration: 143.789043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:38:49.139496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.190119ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:38:49.139686Z","caller":"traceutil/trace.go:171","msg":"trace[1040951820] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"276.676789ms","start":"2026-04-27T19:38:48.862978Z","end":"2026-04-27T19:38:49.139655Z","steps":["trace[1040951820] 'process raft request' (duration: 159.228648ms)","trace[1040951820] 'compare' (duration: 117.048508ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:38:49.13994Z","caller":"traceutil/trace.go:171","msg":"trace[742052949] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"276.81399ms","start":"2026-04-27T19:38:48.863103Z","end":"2026-04-27T19:38:49.139917Z","steps":["trace[742052949] 'process raft request' (duration: 276.491887ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:49.140021Z","caller":"traceutil/trace.go:171","msg":"trace[1475880318] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"276.070634ms","start":"2026-04-27T19:38:48.863925Z","end":"2026-04-27T19:38:49.139995Z","steps":["trace[1475880318] 'process raft request' (duration: 275.752212ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:49.140138Z","caller":"traceutil/trace.go:171","msg":"trace[1185759999] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"256.81033ms","start":"2026-04-27T19:38:48.883306Z","end":"2026-04-27T19:38:49.140116Z","steps":["trace[1185759999] 'process raft request' (duration: 256.514967ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:49.140135Z","caller":"traceutil/trace.go:171","msg":"trace[981174615] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:380; }","duration":"111.592787ms","start":"2026-04-27T19:38:49.028505Z","end":"2026-04-27T19:38:49.140098Z","steps":["trace[981174615] 'read index received' (duration: 48.3µs)","trace[981174615] 'applied index is now lower than readState.Index' (duration: 111.542837ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:38:49.140099Z","caller":"traceutil/trace.go:171","msg":"trace[1275446857] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"271.553871ms","start":"2026-04-27T19:38:48.868529Z","end":"2026-04-27T19:38:49.140083Z","steps":["trace[1275446857] 'process raft request' (duration: 271.199537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:38:49.140309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.787308ms","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-04-27T19:38:49.14037Z","caller":"traceutil/trace.go:171","msg":"trace[919813599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:372; }","duration":"111.894759ms","start":"2026-04-27T19:38:49.02846Z","end":"2026-04-27T19:38:49.140354Z","steps":["trace[919813599] 'agreement among raft nodes before linearized reading' (duration: 111.750308ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:55.794824Z","caller":"traceutil/trace.go:171","msg":"trace[1270970039] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"542.499028ms","start":"2026-04-27T19:38:55.252295Z","end":"2026-04-27T19:38:55.794794Z","steps":["trace[1270970039] 'process raft request' (duration: 542.262966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:38:55.794988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.974723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-04-27T19:38:55.794769Z","caller":"traceutil/trace.go:171","msg":"trace[910302154] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:420; }","duration":"316.765421ms","start":"2026-04-27T19:38:55.477977Z","end":"2026-04-27T19:38:55.794743Z","steps":["trace[910302154] 'read index received' (duration: 316.56163ms)","trace[910302154] 'applied index is now lower than readState.Index' (duration: 202.771µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:38:55.795373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:38:55.252275Z","time spent":"542.624048ms","remote":"127.0.0.1:38816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:38:55.795483Z","caller":"traceutil/trace.go:171","msg":"trace[118028280] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:406; }","duration":"317.427176ms","start":"2026-04-27T19:38:55.477972Z","end":"2026-04-27T19:38:55.795399Z","steps":["trace[118028280] 'agreement among raft nodes before linearized reading' (duration: 316.909172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:38:55.795671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:38:55.477927Z","time spent":"317.720847ms","remote":"127.0.0.1:38726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6989,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-04-27T19:38:56.78778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.911482ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:38:56.788385Z","caller":"traceutil/trace.go:171","msg":"trace[1856599675] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"982.503504ms","start":"2026-04-27T19:38:55.805859Z","end":"2026-04-27T19:38:56.788362Z","steps":["trace[1856599675] 'process raft request' (duration: 982.02033ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:56.788404Z","caller":"traceutil/trace.go:171","msg":"trace[1540704134] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"1.067965268s","start":"2026-04-27T19:38:55.720374Z","end":"2026-04-27T19:38:56.788339Z","steps":["trace[1540704134] 'process raft request' (duration: 494.39701ms)","trace[1540704134] 'compare' (duration: 572.62466ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:38:56.788477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:38:55.805835Z","time spent":"982.598174ms","remote":"127.0.0.1:38726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:38:56.788588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:38:55.720346Z","time spent":"1.068158189s","remote":"127.0.0.1:38816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:38:56.788573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.232604ms","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-04-27T19:38:56.788594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.213374427s","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-04-27T19:38:56.788656Z","caller":"traceutil/trace.go:171","msg":"trace[1157167833] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:408; }","duration":"996.320585ms","start":"2026-04-27T19:38:55.792314Z","end":"2026-04-27T19:38:56.788635Z","steps":["trace[1157167833] 'agreement among raft nodes before linearized reading' (duration: 996.157674ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:38:56.788662Z","caller":"traceutil/trace.go:171","msg":"trace[1352429787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:408; }","duration":"1.213494068s","start":"2026-04-27T19:38:55.575154Z","end":"2026-04-27T19:38:56.788648Z","steps":["trace[1352429787] 'agreement among raft nodes before linearized reading' (duration: 1.213325917s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:38:56.7887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:38:55.792271Z","time spent":"996.420955ms","remote":"127.0.0.1:38816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T19:38:56.788707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:38:55.575132Z","time spent":"1.213566658s","remote":"127.0.0.1:38816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-27T19:38:56.788412Z","caller":"traceutil/trace.go:171","msg":"trace[286531865] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:421; }","duration":"992.92491ms","start":"2026-04-27T19:38:55.795448Z","end":"2026-04-27T19:38:56.788373Z","steps":["trace[286531865] 'read index received' (duration: 419.336132ms)","trace[286531865] 'applied index is now lower than readState.Index' (duration: 573.583178ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:38:56.788995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.838325ms","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-04-27T19:38:56.789041Z","caller":"traceutil/trace.go:171","msg":"trace[115856365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:408; }","duration":"816.914655ms","start":"2026-04-27T19:38:55.972112Z","end":"2026-04-27T19:38:56.789027Z","steps":["trace[115856365] 'agreement among raft nodes before linearized reading' (duration: 816.842075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:38:56.789076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:38:55.972089Z","time spent":"816.980437ms","remote":"127.0.0.1:38816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T19:39:00.971908Z","caller":"traceutil/trace.go:171","msg":"trace[760690261] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"154.097566ms","start":"2026-04-27T19:39:00.817785Z","end":"2026-04-27T19:39:00.971883Z","steps":["trace[760690261] 'process raft request' (duration: 153.898515ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:00.973647Z","caller":"traceutil/trace.go:171","msg":"trace[413832636] linearizableReadLoop","detail":"{readStateIndex:471; appliedIndex:469; }","duration":"154.437014ms","start":"2026-04-27T19:39:00.819192Z","end":"2026-04-27T19:39:00.973629Z","steps":["trace[413832636] 'read index received' (duration: 152.422978ms)","trace[413832636] 'applied index is now lower than readState.Index' (duration: 2.013416ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:39:00.973937Z","caller":"traceutil/trace.go:171","msg":"trace[882164437] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"155.609206ms","start":"2026-04-27T19:39:00.818303Z","end":"2026-04-27T19:39:00.973912Z","steps":["trace[882164437] 'process raft request' (duration: 155.212215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:00.973952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.572822ms","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-04-27T19:39:00.974272Z","caller":"traceutil/trace.go:171","msg":"trace[1853742837] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:453; }","duration":"155.072037ms","start":"2026-04-27T19:39:00.819148Z","end":"2026-04-27T19:39:00.97422Z","steps":["trace[1853742837] 'agreement among raft nodes before linearized reading' (duration: 154.55102ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:05.161385Z","caller":"traceutil/trace.go:171","msg":"trace[1667094125] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:480; }","duration":"167.17449ms","start":"2026-04-27T19:39:04.994189Z","end":"2026-04-27T19:39:05.161363Z","steps":["trace[1667094125] 'read index received' (duration: 167.039353ms)","trace[1667094125] 'applied index is now lower than readState.Index' (duration: 134.337µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:39:05.161551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.341528ms","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-04-27T19:39:05.161531Z","caller":"traceutil/trace.go:171","msg":"trace[728455731] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"170.110017ms","start":"2026-04-27T19:39:04.991364Z","end":"2026-04-27T19:39:05.161474Z","steps":["trace[728455731] 'process raft request' (duration: 169.875646ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:05.16158Z","caller":"traceutil/trace.go:171","msg":"trace[18192527] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:462; }","duration":"167.412421ms","start":"2026-04-27T19:39:04.994158Z","end":"2026-04-27T19:39:05.161571Z","steps":["trace[18192527] 'agreement among raft nodes before linearized reading' (duration: 167.298016ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:05.163213Z","caller":"traceutil/trace.go:171","msg":"trace[934859063] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"167.569308ms","start":"2026-04-27T19:39:04.995624Z","end":"2026-04-27T19:39:05.163193Z","steps":["trace[934859063] 'process raft request' (duration: 167.499965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:13.529169Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2802819861723344731,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:39:14.020896Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.311543218s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T19:39:14.021291Z","caller":"traceutil/trace.go:171","msg":"trace[313484344] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.31204033s","start":"2026-04-27T19:39:12.709227Z","end":"2026-04-27T19:39:14.021268Z","steps":["trace[313484344] 'process raft request' (duration: 1.311935725s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:14.021393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:12.709206Z","time spent":"1.312134224s","remote":"127.0.0.1:38608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:39:14.038968Z","caller":"traceutil/trace.go:171","msg":"trace[1196953704] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"1.319048672s","start":"2026-04-27T19:39:12.719884Z","end":"2026-04-27T19:39:14.038933Z","steps":["trace[1196953704] 'process raft request' (duration: 1.318922355s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:14.039041Z","caller":"traceutil/trace.go:171","msg":"trace[1106788173] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.318968558s","start":"2026-04-27T19:39:12.720048Z","end":"2026-04-27T19:39:14.039017Z","steps":["trace[1106788173] 'process raft request' (duration: 1.318837233s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:14.039127Z","caller":"traceutil/trace.go:171","msg":"trace[1269867645] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.313760701s","start":"2026-04-27T19:39:12.725328Z","end":"2026-04-27T19:39:14.039089Z","steps":["trace[1269867645] 'process raft request' (duration: 1.313602414s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:14.039168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:12.72004Z","time spent":"1.319075373s","remote":"127.0.0.1:38608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T19:39:14.039281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:12.725308Z","time spent":"1.313899716s","remote":"127.0.0.1:38608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T19:39:14.039278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:12.719855Z","time spent":"1.319196438s","remote":"127.0.0.1:38608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:39:14.039304Z","caller":"traceutil/trace.go:171","msg":"trace[1397561363] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.307307326s","start":"2026-04-27T19:39:12.731985Z","end":"2026-04-27T19:39:14.039293Z","steps":["trace[1397561363] 'process raft request' (duration: 1.306993772s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:14.03937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:12.731969Z","time spent":"1.307368358s","remote":"127.0.0.1:38608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:39:14.039474Z","caller":"traceutil/trace.go:171","msg":"trace[482561895] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"1.30742443s","start":"2026-04-27T19:39:12.732035Z","end":"2026-04-27T19:39:14.039459Z","steps":["trace[482561895] 'process raft request' (duration: 1.306985161s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:14.039505Z","caller":"traceutil/trace.go:171","msg":"trace[1673662217] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"1.30741373s","start":"2026-04-27T19:39:12.73208Z","end":"2026-04-27T19:39:14.039494Z","steps":["trace[1673662217] 'process raft request' (duration: 1.307022502s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:14.039532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:12.732031Z","time spent":"1.307475022s","remote":"127.0.0.1:38608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T19:39:14.039553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:12.732077Z","time spent":"1.307454401s","remote":"127.0.0.1:38608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:39:14.039609Z","caller":"traceutil/trace.go:171","msg":"trace[1942185102] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"1.307474703s","start":"2026-04-27T19:39:12.732113Z","end":"2026-04-27T19:39:14.039588Z","steps":["trace[1942185102] 'process raft request' (duration: 1.307220102s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:14.039672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:12.732109Z","time spent":"1.307536045s","remote":"127.0.0.1:38608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:39:14.039681Z","caller":"traceutil/trace.go:171","msg":"trace[850032481] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.3019381s","start":"2026-04-27T19:39:12.737724Z","end":"2026-04-27T19:39:14.039662Z","steps":["trace[850032481] 'process raft request' (duration: 1.301769153s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:14.039783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:12.737705Z","time spent":"1.302029033s","remote":"127.0.0.1:38608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:39:14.039827Z","caller":"traceutil/trace.go:171","msg":"trace[1426163860] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"1.2919019s","start":"2026-04-27T19:39:12.747916Z","end":"2026-04-27T19:39:14.039818Z","steps":["trace[1426163860] 'process raft request' (duration: 1.291664539s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:14.039862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:12.747895Z","time spent":"1.291952592s","remote":"127.0.0.1:38608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:39:14.039988Z","caller":"traceutil/trace.go:171","msg":"trace[1522474707] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:535; }","duration":"1.011052494s","start":"2026-04-27T19:39:13.028914Z","end":"2026-04-27T19:39:14.039966Z","steps":["trace[1522474707] 'read index received' (duration: 992.352599ms)","trace[1522474707] 'applied index is now lower than readState.Index' (duration: 18.698814ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:39:14.04012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01120021s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-27T19:39:14.040119Z","caller":"traceutil/trace.go:171","msg":"trace[847944298] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"1.171039519s","start":"2026-04-27T19:39:12.869003Z","end":"2026-04-27T19:39:14.040042Z","steps":["trace[847944298] 'process raft request' (duration: 1.170809609s)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:14.040158Z","caller":"traceutil/trace.go:171","msg":"trace[2096565623] range","detail":"{range_begin:/registry/masterleases/199.19.213.169; range_end:; response_count:1; response_revision:526; }","duration":"1.011275044s","start":"2026-04-27T19:39:13.028874Z","end":"2026-04-27T19:39:14.040149Z","steps":["trace[2096565623] 'agreement among raft nodes before linearized reading' (duration: 1.011180269s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:14.040184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:13.028853Z","time spent":"1.011326226s","remote":"127.0.0.1:38596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.169\" "} {"level":"warn","ts":"2026-04-27T19:39:14.040213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:12.868977Z","time spent":"1.171192236s","remote":"127.0.0.1:38608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:39:14.040342Z","caller":"traceutil/trace.go:171","msg":"trace[1278523541] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"1.26779718s","start":"2026-04-27T19:39:12.772533Z","end":"2026-04-27T19:39:14.04033Z","steps":["trace[1278523541] 'process raft request' (duration: 1.267158751s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:14.040397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:12.772518Z","time spent":"1.267856042s","remote":"127.0.0.1:38608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T19:39:14.046721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.175612ms","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-04-27T19:39:14.046779Z","caller":"traceutil/trace.go:171","msg":"trace[870785154] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:528; }","duration":"826.371371ms","start":"2026-04-27T19:39:13.220392Z","end":"2026-04-27T19:39:14.046764Z","steps":["trace[870785154] 'agreement among raft nodes before linearized reading' (duration: 825.845107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:14.046808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:13.220377Z","time spent":"826.423643ms","remote":"127.0.0.1:38816","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-04-27T19:39:14.047151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.715936ms","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-04-27T19:39:14.04717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.738587ms","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-04-27T19:39:14.047184Z","caller":"traceutil/trace.go:171","msg":"trace[1438547297] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:528; }","duration":"826.766119ms","start":"2026-04-27T19:39:13.220409Z","end":"2026-04-27T19:39:14.047176Z","steps":["trace[1438547297] 'agreement among raft nodes before linearized reading' (duration: 826.709836ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:14.047194Z","caller":"traceutil/trace.go:171","msg":"trace[645960871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:528; }","duration":"826.794559ms","start":"2026-04-27T19:39:13.220392Z","end":"2026-04-27T19:39:14.047187Z","steps":["trace[645960871] 'agreement among raft nodes before linearized reading' (duration: 826.746227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:14.047209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:13.220381Z","time spent":"826.825031ms","remote":"127.0.0.1:38816","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-04-27T19:39:14.047209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:13.220405Z","time spent":"826.79844ms","remote":"127.0.0.1:38816","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-04-27T19:39:15.48553Z","caller":"traceutil/trace.go:171","msg":"trace[1833955709] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"433.800314ms","start":"2026-04-27T19:39:15.051701Z","end":"2026-04-27T19:39:15.485502Z","steps":["trace[1833955709] 'process raft request' (duration: 427.279697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:15.485714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:15.051684Z","time spent":"433.92563ms","remote":"127.0.0.1:38608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:39:15.491612Z","caller":"traceutil/trace.go:171","msg":"trace[1939265962] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"394.22927ms","start":"2026-04-27T19:39:15.097357Z","end":"2026-04-27T19:39:15.491586Z","steps":["trace[1939265962] 'process raft request' (duration: 392.809107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:15.491714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:15.097331Z","time spent":"394.336124ms","remote":"127.0.0.1:38608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:39:15.491742Z","caller":"traceutil/trace.go:171","msg":"trace[700273820] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:613; }","duration":"170.020509ms","start":"2026-04-27T19:39:15.321699Z","end":"2026-04-27T19:39:15.491719Z","steps":["trace[700273820] 'read index received' (duration: 157.32636ms)","trace[700273820] 'applied index is now lower than readState.Index' (duration: 12.693059ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:39:15.491824Z","caller":"traceutil/trace.go:171","msg":"trace[409653702] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"296.854923ms","start":"2026-04-27T19:39:15.194955Z","end":"2026-04-27T19:39:15.49181Z","steps":["trace[409653702] 'process raft request' (duration: 295.688632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:15.49185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.138475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:39:15.491884Z","caller":"traceutil/trace.go:171","msg":"trace[513166420] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"170.210018ms","start":"2026-04-27T19:39:15.321662Z","end":"2026-04-27T19:39:15.491872Z","steps":["trace[513166420] 'agreement among raft nodes before linearized reading' (duration: 170.141175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:20.602104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.677914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:39:20.602228Z","caller":"traceutil/trace.go:171","msg":"trace[1331242396] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"481.927848ms","start":"2026-04-27T19:39:20.120287Z","end":"2026-04-27T19:39:20.602215Z","steps":["trace[1331242396] 'process raft request' (duration: 481.886726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:20.602267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:20.120255Z","time spent":"481.998301ms","remote":"127.0.0.1:38894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:39:20.602536Z","caller":"traceutil/trace.go:171","msg":"trace[1425167629] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"496.468083ms","start":"2026-04-27T19:39:20.106023Z","end":"2026-04-27T19:39:20.602492Z","steps":["trace[1425167629] 'read index received' (duration: 52.042µs)","trace[1425167629] 'applied index is now lower than readState.Index' (duration: 496.41036ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:39:20.60258Z","caller":"traceutil/trace.go:171","msg":"trace[593552720] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"514.834057ms","start":"2026-04-27T19:39:20.087718Z","end":"2026-04-27T19:39:20.602552Z","steps":["trace[593552720] 'compare' (duration: 510.547069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:20.602742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.712064ms","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-04-27T19:39:20.602768Z","caller":"traceutil/trace.go:171","msg":"trace[1773815254] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:619; }","duration":"496.775777ms","start":"2026-04-27T19:39:20.105985Z","end":"2026-04-27T19:39:20.60276Z","steps":["trace[1773815254] 'agreement among raft nodes before linearized reading' (duration: 496.659952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:20.602785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:20.105965Z","time spent":"496.816419ms","remote":"127.0.0.1:38816","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-04-27T19:39:20.602779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:20.087693Z","time spent":"515.017376ms","remote":"127.0.0.1:38644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T19:39:20.60297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.446944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-27T19:39:20.60299Z","caller":"traceutil/trace.go:171","msg":"trace[686614450] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:619; }","duration":"430.495887ms","start":"2026-04-27T19:39:20.172489Z","end":"2026-04-27T19:39:20.602985Z","steps":["trace[686614450] 'agreement among raft nodes before linearized reading' (duration: 430.455535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:20.603003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:20.172471Z","time spent":"430.528778ms","remote":"127.0.0.1:38674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-27T19:39:20.603085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.26169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:39:20.603103Z","caller":"traceutil/trace.go:171","msg":"trace[1305638991] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"280.324453ms","start":"2026-04-27T19:39:20.322774Z","end":"2026-04-27T19:39:20.603098Z","steps":["trace[1305638991] 'agreement among raft nodes before linearized reading' (duration: 280.298882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:20.603086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.096893ms","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-04-27T19:39:20.603229Z","caller":"traceutil/trace.go:171","msg":"trace[2009579109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:619; }","duration":"493.265409ms","start":"2026-04-27T19:39:20.109948Z","end":"2026-04-27T19:39:20.603213Z","steps":["trace[2009579109] 'agreement among raft nodes before linearized reading' (duration: 492.946026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:20.603268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.1215ms","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-04-27T19:39:20.603352Z","caller":"traceutil/trace.go:171","msg":"trace[1174446787] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:619; }","duration":"485.234345ms","start":"2026-04-27T19:39:20.118095Z","end":"2026-04-27T19:39:20.603329Z","steps":["trace[1174446787] 'agreement among raft nodes before linearized reading' (duration: 485.013476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:20.603302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.325652ms","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-04-27T19:39:20.603397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:20.118076Z","time spent":"485.312799ms","remote":"127.0.0.1:38816","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-04-27T19:39:20.60331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:20.109934Z","time spent":"493.361184ms","remote":"127.0.0.1:38816","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-04-27T19:39:20.603512Z","caller":"traceutil/trace.go:171","msg":"trace[511338822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:619; }","duration":"493.445878ms","start":"2026-04-27T19:39:20.10997Z","end":"2026-04-27T19:39:20.603416Z","steps":["trace[511338822] 'agreement among raft nodes before linearized reading' (duration: 493.298312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:20.603546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:20.109931Z","time spent":"493.609084ms","remote":"127.0.0.1:38816","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-04-27T19:39:21.771538Z","caller":"traceutil/trace.go:171","msg":"trace[1607976245] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"168.399833ms","start":"2026-04-27T19:39:21.603114Z","end":"2026-04-27T19:39:21.771513Z","steps":["trace[1607976245] 'read index received' (duration: 167.436923ms)","trace[1607976245] 'applied index is now lower than readState.Index' (duration: 961.94µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:39:21.771707Z","caller":"traceutil/trace.go:171","msg":"trace[7853243] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"746.964881ms","start":"2026-04-27T19:39:21.024704Z","end":"2026-04-27T19:39:21.771669Z","steps":["trace[7853243] 'process raft request' (duration: 746.181829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:21.771841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.713045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-clfhs\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-27T19:39:21.771887Z","caller":"traceutil/trace.go:171","msg":"trace[1240076630] range","detail":"{range_begin:/registry/pods/kube-system/cilium-clfhs; range_end:; response_count:1; response_revision:625; }","duration":"168.81327ms","start":"2026-04-27T19:39:21.603061Z","end":"2026-04-27T19:39:21.771874Z","steps":["trace[1240076630] 'agreement among raft nodes before linearized reading' (duration: 168.551489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:21.771904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:21.024673Z","time spent":"747.125469ms","remote":"127.0.0.1:38662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:39:22.349983Z","caller":"traceutil/trace.go:171","msg":"trace[606795765] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"561.781547ms","start":"2026-04-27T19:39:21.788177Z","end":"2026-04-27T19:39:22.349958Z","steps":["trace[606795765] 'process raft request' (duration: 561.584199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:22.35012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:21.788155Z","time spent":"561.889832ms","remote":"127.0.0.1:38726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:39:24.114525Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.764667652s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-27T19:39:24.321554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000241095s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-27T19:39:24.321664Z","caller":"traceutil/trace.go:171","msg":"trace[1784494731] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000407041s","start":"2026-04-27T19:39:22.321242Z","end":"2026-04-27T19:39:24.321649Z","steps":["trace[1784494731] 'agreement among raft nodes before linearized reading' (duration: 2.000286096s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:24.321739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:22.321213Z","time spent":"2.000519446s","remote":"127.0.0.1:38568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/27 19:39:24 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-27T19:39:25.58046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.191958059s","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-04-27T19:39:25.580557Z","caller":"traceutil/trace.go:171","msg":"trace[866749488] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:626; }","duration":"2.192058433s","start":"2026-04-27T19:39:23.388478Z","end":"2026-04-27T19:39:25.580537Z","steps":["trace[866749488] 'range keys from in-memory index tree' (duration: 2.191943449s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:25.582151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.467381595s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:39:25.582266Z","caller":"traceutil/trace.go:171","msg":"trace[424163583] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:651; }","duration":"3.260947214s","start":"2026-04-27T19:39:22.321306Z","end":"2026-04-27T19:39:25.582253Z","steps":["trace[424163583] 'read index received' (duration: 28.470969ms)","trace[424163583] 'applied index is now lower than readState.Index' (duration: 3.232475375s)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:39:25.582352Z","caller":"traceutil/trace.go:171","msg":"trace[1339476274] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"3.749017989s","start":"2026-04-27T19:39:21.833325Z","end":"2026-04-27T19:39:25.582343Z","steps":["trace[1339476274] 'process raft request' (duration: 2.281407295s)","trace[1339476274] 'compare' (duration: 1.46697399s)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:39:25.58239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:21.833286Z","time spent":"3.749085502s","remote":"127.0.0.1:39026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:39:25.58255Z","caller":"traceutil/trace.go:171","msg":"trace[114641596] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"3.305002767s","start":"2026-04-27T19:39:22.277509Z","end":"2026-04-27T19:39:25.582512Z","steps":["trace[114641596] 'process raft request' (duration: 3.304693475s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:25.58264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.260774872s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:39:25.582677Z","caller":"traceutil/trace.go:171","msg":"trace[211828775] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"1.260807614s","start":"2026-04-27T19:39:24.321858Z","end":"2026-04-27T19:39:25.582665Z","steps":["trace[211828775] 'agreement among raft nodes before linearized reading' (duration: 1.260756552s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:25.582697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:24.321809Z","time spent":"1.260883856s","remote":"127.0.0.1:38582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T19:39:25.582826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.96533682s","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-04-27T19:39:25.582835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.960509594s","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-04-27T19:39:25.582878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.587005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:39:25.5829Z","caller":"traceutil/trace.go:171","msg":"trace[1134090634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:628; }","duration":"2.965442384s","start":"2026-04-27T19:39:22.61744Z","end":"2026-04-27T19:39:25.582883Z","steps":["trace[1134090634] 'agreement among raft nodes before linearized reading' (duration: 2.965231296s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:25.582894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.544622401s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-27T19:39:25.582915Z","caller":"traceutil/trace.go:171","msg":"trace[1943310914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"2.960600418s","start":"2026-04-27T19:39:22.622295Z","end":"2026-04-27T19:39:25.582895Z","steps":["trace[1943310914] 'agreement among raft nodes before linearized reading' (duration: 2.960348828s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:25.582947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:22.617431Z","time spent":"2.965504096s","remote":"127.0.0.1:38816","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-04-27T19:39:25.582964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:22.622287Z","time spent":"2.9606635s","remote":"127.0.0.1:38816","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-04-27T19:39:25.582954Z","caller":"traceutil/trace.go:171","msg":"trace[1986133168] range","detail":"{range_begin:/registry/masterleases/199.19.213.169; range_end:; response_count:1; response_revision:628; }","duration":"2.544718845s","start":"2026-04-27T19:39:23.038217Z","end":"2026-04-27T19:39:25.582936Z","steps":["trace[1986133168] 'agreement among raft nodes before linearized reading' (duration: 2.544576909s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:25.583037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:23.038198Z","time spent":"2.544831729s","remote":"127.0.0.1:38596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.169\" "} {"level":"info","ts":"2026-04-27T19:39:25.582906Z","caller":"traceutil/trace.go:171","msg":"trace[1154742465] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"807.656388ms","start":"2026-04-27T19:39:24.775242Z","end":"2026-04-27T19:39:25.582899Z","steps":["trace[1154742465] 'agreement among raft nodes before linearized reading' (duration: 807.613566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:25.58284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:22.277485Z","time spent":"3.305260127s","remote":"127.0.0.1:38816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:39:25.58314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.9658451s","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-04-27T19:39:25.583173Z","caller":"traceutil/trace.go:171","msg":"trace[1123834313] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"2.965979435s","start":"2026-04-27T19:39:22.617185Z","end":"2026-04-27T19:39:25.583165Z","steps":["trace[1123834313] 'agreement among raft nodes before linearized reading' (duration: 2.965893692s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:25.583193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:22.617174Z","time spent":"2.966014846s","remote":"127.0.0.1:38816","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-04-27T19:39:25.583097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:24.775214Z","time spent":"807.873816ms","remote":"127.0.0.1:38568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T19:39:25.58258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.969568943s","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-04-27T19:39:25.584732Z","caller":"traceutil/trace.go:171","msg":"trace[1904973381] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"2.971746739s","start":"2026-04-27T19:39:22.612962Z","end":"2026-04-27T19:39:25.584709Z","steps":["trace[1904973381] 'agreement among raft nodes before linearized reading' (duration: 2.969567743s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:25.584872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:22.612938Z","time spent":"2.971913845s","remote":"127.0.0.1:38816","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-04-27T19:39:26.175401Z","caller":"traceutil/trace.go:171","msg":"trace[273501603] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"136.134479ms","start":"2026-04-27T19:39:26.0392Z","end":"2026-04-27T19:39:26.175334Z","steps":["trace[273501603] 'process raft request' (duration: 133.971535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:26.920771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.547924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:39:26.921012Z","caller":"traceutil/trace.go:171","msg":"trace[713839985] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"529.595243ms","start":"2026-04-27T19:39:26.391385Z","end":"2026-04-27T19:39:26.92098Z","steps":["trace[713839985] 'process raft request' (duration: 58.764828ms)","trace[713839985] 'compare' (duration: 470.382328ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:39:26.921085Z","caller":"traceutil/trace.go:171","msg":"trace[200752176] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"297.444226ms","start":"2026-04-27T19:39:26.62362Z","end":"2026-04-27T19:39:26.921064Z","steps":["trace[200752176] 'process raft request' (duration: 297.407705ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:26.9211Z","caller":"traceutil/trace.go:171","msg":"trace[680324070] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"528.113606ms","start":"2026-04-27T19:39:26.392963Z","end":"2026-04-27T19:39:26.921077Z","steps":["trace[680324070] 'process raft request' (duration: 527.96035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:26.921153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:26.391357Z","time spent":"529.731168ms","remote":"127.0.0.1:38644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:39:26.921132Z","caller":"traceutil/trace.go:171","msg":"trace[2124137137] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"528.910207ms","start":"2026-04-27T19:39:26.392189Z","end":"2026-04-27T19:39:26.921099Z","steps":["trace[2124137137] 'process raft request' (duration: 528.693348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:26.921198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:26.392953Z","time spent":"528.190468ms","remote":"127.0.0.1:38794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:39:26.921279Z","caller":"traceutil/trace.go:171","msg":"trace[1879229098] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:668; }","duration":"299.900422ms","start":"2026-04-27T19:39:26.621373Z","end":"2026-04-27T19:39:26.921273Z","steps":["trace[1879229098] 'read index received' (duration: 179.381532ms)","trace[1879229098] 'applied index is now lower than readState.Index' (duration: 120.51763ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:39:26.921277Z","caller":"traceutil/trace.go:171","msg":"trace[1924144845] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"526.088326ms","start":"2026-04-27T19:39:26.395179Z","end":"2026-04-27T19:39:26.921268Z","steps":["trace[1924144845] 'process raft request' (duration: 525.802935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:26.921282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:26.392165Z","time spent":"529.042761ms","remote":"127.0.0.1:38644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T19:39:26.921322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:26.395171Z","time spent":"526.134989ms","remote":"127.0.0.1:38726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:39:26.921426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.058909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-clfhs\" ","response":"range_response_count:1 size:17247"} {"level":"info","ts":"2026-04-27T19:39:26.921448Z","caller":"traceutil/trace.go:171","msg":"trace[1206645560] range","detail":"{range_begin:/registry/pods/kube-system/cilium-clfhs; range_end:; response_count:1; response_revision:646; }","duration":"300.09749ms","start":"2026-04-27T19:39:26.621344Z","end":"2026-04-27T19:39:26.921442Z","steps":["trace[1206645560] 'agreement among raft nodes before linearized reading' (duration: 300.015397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:26.921463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:26.621329Z","time spent":"300.130492ms","remote":"127.0.0.1:38726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17271,"request content":"key:\"/registry/pods/kube-system/cilium-clfhs\" "} {"level":"info","ts":"2026-04-27T19:39:27.116439Z","caller":"traceutil/trace.go:171","msg":"trace[1065212255] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"180.32767ms","start":"2026-04-27T19:39:26.936089Z","end":"2026-04-27T19:39:27.116417Z","steps":["trace[1065212255] 'process raft request' (duration: 146.772009ms)","trace[1065212255] 'compare' (duration: 33.457757ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:39:27.741319Z","caller":"traceutil/trace.go:171","msg":"trace[1157266546] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"142.552047ms","start":"2026-04-27T19:39:27.598744Z","end":"2026-04-27T19:39:27.741297Z","steps":["trace[1157266546] 'process raft request' (duration: 62.301858ms)","trace[1157266546] 'compare' (duration: 80.098103ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:39:27.741435Z","caller":"traceutil/trace.go:171","msg":"trace[1988631879] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:676; }","duration":"139.203788ms","start":"2026-04-27T19:39:27.602203Z","end":"2026-04-27T19:39:27.741407Z","steps":["trace[1988631879] 'read index received' (duration: 58.852405ms)","trace[1988631879] 'applied index is now lower than readState.Index' (duration: 80.349773ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:39:27.741509Z","caller":"traceutil/trace.go:171","msg":"trace[1139031324] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"142.342359ms","start":"2026-04-27T19:39:27.599145Z","end":"2026-04-27T19:39:27.741488Z","steps":["trace[1139031324] 'process raft request' (duration: 142.118981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:27.741715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.261261ms","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-04-27T19:39:27.74174Z","caller":"traceutil/trace.go:171","msg":"trace[1679597786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:651; }","duration":"139.308013ms","start":"2026-04-27T19:39:27.602425Z","end":"2026-04-27T19:39:27.741733Z","steps":["trace[1679597786] 'agreement among raft nodes before linearized reading' (duration: 139.210359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:27.741751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.533781ms","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-04-27T19:39:27.741935Z","caller":"traceutil/trace.go:171","msg":"trace[1812874967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:651; }","duration":"139.74764ms","start":"2026-04-27T19:39:27.602175Z","end":"2026-04-27T19:39:27.741923Z","steps":["trace[1812874967] 'agreement among raft nodes before linearized reading' (duration: 139.362975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:28.944991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004816041s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:39:28.945211Z","caller":"traceutil/trace.go:171","msg":"trace[2016592539] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"1.198720335s","start":"2026-04-27T19:39:27.746459Z","end":"2026-04-27T19:39:28.94518Z","steps":["trace[2016592539] 'process raft request' (duration: 193.625413ms)","trace[2016592539] 'compare' (duration: 1.004370654s)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:39:28.945297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:27.746437Z","time spent":"1.19882539s","remote":"127.0.0.1:38816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:39:28.945343Z","caller":"traceutil/trace.go:171","msg":"trace[299024217] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:679; }","duration":"114.147296ms","start":"2026-04-27T19:39:28.831166Z","end":"2026-04-27T19:39:28.945313Z","steps":["trace[299024217] 'read index received' (duration: 29.811µs)","trace[299024217] 'applied index is now lower than readState.Index' (duration: 114.115705ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:39:28.945389Z","caller":"traceutil/trace.go:171","msg":"trace[1707607063] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"1.118418025s","start":"2026-04-27T19:39:27.826929Z","end":"2026-04-27T19:39:28.945347Z","steps":["trace[1707607063] 'process raft request' (duration: 1.118172515s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:28.945539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.355904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-clfhs\" ","response":"range_response_count:1 size:17524"} {"level":"warn","ts":"2026-04-27T19:39:28.945556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:27.826897Z","time spent":"1.118576651s","remote":"127.0.0.1:38644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:39:28.945594Z","caller":"traceutil/trace.go:171","msg":"trace[885731962] range","detail":"{range_begin:/registry/pods/kube-system/cilium-clfhs; range_end:; response_count:1; response_revision:654; }","duration":"114.445528ms","start":"2026-04-27T19:39:28.831134Z","end":"2026-04-27T19:39:28.945579Z","steps":["trace[885731962] 'agreement among raft nodes before linearized reading' (duration: 114.263161ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:29.377303Z","caller":"traceutil/trace.go:171","msg":"trace[1904688781] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"417.410006ms","start":"2026-04-27T19:39:28.959874Z","end":"2026-04-27T19:39:29.377284Z","steps":["trace[1904688781] 'process raft request' (duration: 417.278341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:29.377415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:28.959841Z","time spent":"417.519121ms","remote":"127.0.0.1:38726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17693,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:39:29.559538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.51724ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-27T19:39:29.55965Z","caller":"traceutil/trace.go:171","msg":"trace[393327706] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:681; }","duration":"239.971677ms","start":"2026-04-27T19:39:29.319657Z","end":"2026-04-27T19:39:29.559629Z","steps":["trace[393327706] 'read index received' (duration: 57.481525ms)","trace[393327706] 'applied index is now lower than readState.Index' (duration: 182.488622ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:39:29.55981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.141513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:39:29.559846Z","caller":"traceutil/trace.go:171","msg":"trace[500170761] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"240.215716ms","start":"2026-04-27T19:39:29.31962Z","end":"2026-04-27T19:39:29.559836Z","steps":["trace[500170761] 'agreement among raft nodes before linearized reading' (duration: 240.06539ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:29.816473Z","caller":"traceutil/trace.go:171","msg":"trace[41463244] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"120.706655ms","start":"2026-04-27T19:39:29.695739Z","end":"2026-04-27T19:39:29.816445Z","steps":["trace[41463244] 'process raft request' (duration: 120.223647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:30.004089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.695812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:39:30.004358Z","caller":"traceutil/trace.go:171","msg":"trace[795874256] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"184.104843ms","start":"2026-04-27T19:39:29.820211Z","end":"2026-04-27T19:39:30.004315Z","steps":["trace[795874256] 'process raft request' (duration: 78.984034ms)","trace[795874256] 'compare' (duration: 104.565917ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:39:30.004603Z","caller":"traceutil/trace.go:171","msg":"trace[484175164] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"183.324404ms","start":"2026-04-27T19:39:29.821254Z","end":"2026-04-27T19:39:30.004578Z","steps":["trace[484175164] 'process raft request' (duration: 183.151677ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:30.004772Z","caller":"traceutil/trace.go:171","msg":"trace[1144480015] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:684; }","duration":"137.379533ms","start":"2026-04-27T19:39:29.867367Z","end":"2026-04-27T19:39:30.004747Z","steps":["trace[1144480015] 'read index received' (duration: 31.840159ms)","trace[1144480015] 'applied index is now lower than readState.Index' (duration: 105.538264ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:39:30.004812Z","caller":"traceutil/trace.go:171","msg":"trace[1069690692] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"183.862704ms","start":"2026-04-27T19:39:29.820937Z","end":"2026-04-27T19:39:30.0048Z","steps":["trace[1069690692] 'process raft request' (duration: 183.258981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:30.005008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.625582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-clfhs\" ","response":"range_response_count:1 size:17708"} {"level":"info","ts":"2026-04-27T19:39:30.005017Z","caller":"traceutil/trace.go:171","msg":"trace[1141377079] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"136.76679ms","start":"2026-04-27T19:39:29.868232Z","end":"2026-04-27T19:39:30.004998Z","steps":["trace[1141377079] 'process raft request' (duration: 136.21841ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:30.00507Z","caller":"traceutil/trace.go:171","msg":"trace[723231513] range","detail":"{range_begin:/registry/pods/kube-system/cilium-clfhs; range_end:; response_count:1; response_revision:660; }","duration":"137.698864ms","start":"2026-04-27T19:39:29.867334Z","end":"2026-04-27T19:39:30.005033Z","steps":["trace[723231513] 'agreement among raft nodes before linearized reading' (duration: 137.506937ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:30.143246Z","caller":"traceutil/trace.go:171","msg":"trace[1587925943] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"123.652596ms","start":"2026-04-27T19:39:30.019565Z","end":"2026-04-27T19:39:30.143217Z","steps":["trace[1587925943] 'process raft request' (duration: 123.48328ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:30.722336Z","caller":"traceutil/trace.go:171","msg":"trace[1156785412] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"159.052335ms","start":"2026-04-27T19:39:30.563249Z","end":"2026-04-27T19:39:30.722301Z","steps":["trace[1156785412] 'process raft request' (duration: 158.872899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:30.879551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.034355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:39:30.879659Z","caller":"traceutil/trace.go:171","msg":"trace[979001329] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:662; }","duration":"140.183731ms","start":"2026-04-27T19:39:30.73945Z","end":"2026-04-27T19:39:30.879634Z","steps":["trace[979001329] 'range keys from in-memory index tree' (duration: 139.906561ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:31.029974Z","caller":"traceutil/trace.go:171","msg":"trace[617887587] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"113.956254ms","start":"2026-04-27T19:39:30.915984Z","end":"2026-04-27T19:39:31.02994Z","steps":["trace[617887587] 'process raft request' (duration: 113.739046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:31.030014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.383045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:39:31.030106Z","caller":"traceutil/trace.go:171","msg":"trace[1762451927] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:667; }","duration":"116.472688ms","start":"2026-04-27T19:39:30.913605Z","end":"2026-04-27T19:39:31.030078Z","steps":["trace[1762451927] 'agreement among raft nodes before linearized reading' (duration: 116.311863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:31.030236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.268992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:39:31.030269Z","caller":"traceutil/trace.go:171","msg":"trace[1557256843] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:667; }","duration":"115.312794ms","start":"2026-04-27T19:39:30.914946Z","end":"2026-04-27T19:39:31.030259Z","steps":["trace[1557256843] 'agreement among raft nodes before linearized reading' (duration: 115.221601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:31.030282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.297456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:39:31.03028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.592622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:39:31.030293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.327641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:39:31.030316Z","caller":"traceutil/trace.go:171","msg":"trace[738759642] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:667; }","duration":"114.349179ms","start":"2026-04-27T19:39:30.915958Z","end":"2026-04-27T19:39:31.030307Z","steps":["trace[738759642] 'agreement among raft nodes before linearized reading' (duration: 114.294257ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:31.030329Z","caller":"traceutil/trace.go:171","msg":"trace[2002855459] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"112.419236ms","start":"2026-04-27T19:39:30.917903Z","end":"2026-04-27T19:39:31.030323Z","steps":["trace[2002855459] 'process raft request' (duration: 111.955879ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:31.030333Z","caller":"traceutil/trace.go:171","msg":"trace[995348666] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:667; }","duration":"116.670995ms","start":"2026-04-27T19:39:30.913644Z","end":"2026-04-27T19:39:31.030315Z","steps":["trace[995348666] 'agreement among raft nodes before linearized reading' (duration: 116.599812ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:31.030353Z","caller":"traceutil/trace.go:171","msg":"trace[1407307305] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:667; }","duration":"113.388994ms","start":"2026-04-27T19:39:30.916943Z","end":"2026-04-27T19:39:31.030332Z","steps":["trace[1407307305] 'agreement among raft nodes before linearized reading' (duration: 113.317791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:31.030138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.644212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:39:31.030544Z","caller":"traceutil/trace.go:171","msg":"trace[332728863] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:667; }","duration":"114.090519ms","start":"2026-04-27T19:39:30.916439Z","end":"2026-04-27T19:39:31.03053Z","steps":["trace[332728863] 'agreement among raft nodes before linearized reading' (duration: 113.526548ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:31.178793Z","caller":"traceutil/trace.go:171","msg":"trace[1641740720] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"123.719598ms","start":"2026-04-27T19:39:31.055051Z","end":"2026-04-27T19:39:31.178771Z","steps":["trace[1641740720] 'process raft request' (duration: 75.175098ms)","trace[1641740720] 'compare' (duration: 48.441166ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:39:31.304296Z","caller":"traceutil/trace.go:171","msg":"trace[1845195036] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"105.704745ms","start":"2026-04-27T19:39:31.198562Z","end":"2026-04-27T19:39:31.304266Z","steps":["trace[1845195036] 'process raft request' (duration: 100.001335ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:31.832728Z","caller":"traceutil/trace.go:171","msg":"trace[2088960401] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"120.004582ms","start":"2026-04-27T19:39:31.712686Z","end":"2026-04-27T19:39:31.832691Z","steps":["trace[2088960401] 'process raft request' (duration: 119.788444ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:32.001375Z","caller":"traceutil/trace.go:171","msg":"trace[1013276160] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"103.760963ms","start":"2026-04-27T19:39:31.897589Z","end":"2026-04-27T19:39:32.00135Z","steps":["trace[1013276160] 'process raft request' (duration: 102.379123ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:32.004931Z","caller":"traceutil/trace.go:171","msg":"trace[738768564] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"101.802771ms","start":"2026-04-27T19:39:31.903085Z","end":"2026-04-27T19:39:32.004888Z","steps":["trace[738768564] 'process raft request' (duration: 101.374655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:33.268343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.209559ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-04-27T19:39:33.26854Z","caller":"traceutil/trace.go:171","msg":"trace[1158508474] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:741; }","duration":"175.006931ms","start":"2026-04-27T19:39:33.093508Z","end":"2026-04-27T19:39:33.268515Z","steps":["trace[1158508474] 'read index received' (duration: 180.836µs)","trace[1158508474] 'applied index is now lower than readState.Index' (duration: 174.824045ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:39:33.268769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.260591ms","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-04-27T19:39:33.268834Z","caller":"traceutil/trace.go:171","msg":"trace[1359500972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:713; }","duration":"175.389825ms","start":"2026-04-27T19:39:33.093433Z","end":"2026-04-27T19:39:33.268823Z","steps":["trace[1359500972] 'agreement among raft nodes before linearized reading' (duration: 175.146546ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:33.272474Z","caller":"traceutil/trace.go:171","msg":"trace[99117031] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"104.2894ms","start":"2026-04-27T19:39:33.168174Z","end":"2026-04-27T19:39:33.272464Z","steps":["trace[99117031] 'process raft request' (duration: 104.151135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:43.601246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.563264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T19:39:43.601273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.633446ms","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-04-27T19:39:43.60136Z","caller":"traceutil/trace.go:171","msg":"trace[1530460416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:785; }","duration":"276.732499ms","start":"2026-04-27T19:39:43.324603Z","end":"2026-04-27T19:39:43.601336Z","steps":["trace[1530460416] 'range keys from in-memory index tree' (duration: 276.495681ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:43.601373Z","caller":"traceutil/trace.go:171","msg":"trace[1220074253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:785; }","duration":"252.77547ms","start":"2026-04-27T19:39:43.348577Z","end":"2026-04-27T19:39:43.601353Z","steps":["trace[1220074253] 'range keys from in-memory index tree' (duration: 252.49006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:43.601524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.484204ms","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-04-27T19:39:43.601804Z","caller":"traceutil/trace.go:171","msg":"trace[277741764] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:785; }","duration":"213.911028ms","start":"2026-04-27T19:39:43.387786Z","end":"2026-04-27T19:39:43.601697Z","steps":["trace[277741764] 'range keys from in-memory index tree' (duration: 213.469154ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:43.778873Z","caller":"traceutil/trace.go:171","msg":"trace[1611687750] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"172.997499ms","start":"2026-04-27T19:39:43.605847Z","end":"2026-04-27T19:39:43.778844Z","steps":["trace[1611687750] 'process raft request' (duration: 172.813622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:45.587676Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2802819861723345324,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:39:46.088292Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2802819861723345324,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:39:46.588516Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2802819861723345324,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T19:39:46.67078Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.694598525s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T19:39:46.671157Z","caller":"traceutil/trace.go:171","msg":"trace[1866143960] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"1.69506013s","start":"2026-04-27T19:39:44.976071Z","end":"2026-04-27T19:39:46.671131Z","steps":["trace[1866143960] 'process raft request' (duration: 1.694908675s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:46.671325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:44.976053Z","time spent":"1.695161883s","remote":"127.0.0.1:38726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4857,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T19:39:46.677768Z","caller":"traceutil/trace.go:171","msg":"trace[801130073] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:830; }","duration":"1.590715596s","start":"2026-04-27T19:39:45.087005Z","end":"2026-04-27T19:39:46.677721Z","steps":["trace[801130073] 'read index received' (duration: 1.583968674s)","trace[801130073] 'applied index is now lower than readState.Index' (duration: 6.745932ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:39:46.677814Z","caller":"traceutil/trace.go:171","msg":"trace[481511680] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"882.187095ms","start":"2026-04-27T19:39:45.795599Z","end":"2026-04-27T19:39:46.677787Z","steps":["trace[481511680] 'process raft request' (duration: 881.944748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:46.677944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:45.795583Z","time spent":"882.291368ms","remote":"127.0.0.1:38816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:39:46.677969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.590948563s","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-04-27T19:39:46.67874Z","caller":"traceutil/trace.go:171","msg":"trace[1378072349] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:799; }","duration":"1.591765771s","start":"2026-04-27T19:39:45.086961Z","end":"2026-04-27T19:39:46.678727Z","steps":["trace[1378072349] 'agreement among raft nodes before linearized reading' (duration: 1.590879842s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:46.678866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:45.086943Z","time spent":"1.591818743s","remote":"127.0.0.1:38816","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-04-27T19:39:46.682714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.363244805s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:39:46.682756Z","caller":"traceutil/trace.go:171","msg":"trace[403541295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"1.36336401s","start":"2026-04-27T19:39:45.319381Z","end":"2026-04-27T19:39:46.682745Z","steps":["trace[403541295] 'agreement among raft nodes before linearized reading' (duration: 1.363289217s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:46.682776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:45.319361Z","time spent":"1.363409931s","remote":"127.0.0.1:38582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T19:39:46.682878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.693772ms","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-04-27T19:39:46.682937Z","caller":"traceutil/trace.go:171","msg":"trace[1913619911] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:802; }","duration":"510.789355ms","start":"2026-04-27T19:39:46.172128Z","end":"2026-04-27T19:39:46.682917Z","steps":["trace[1913619911] 'agreement among raft nodes before linearized reading' (duration: 510.668861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:46.682975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:46.172108Z","time spent":"510.857988ms","remote":"127.0.0.1:38816","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-04-27T19:39:46.68346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.20331ms","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-04-27T19:39:46.683522Z","caller":"traceutil/trace.go:171","msg":"trace[2047645427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:802; }","duration":"598.330823ms","start":"2026-04-27T19:39:46.085175Z","end":"2026-04-27T19:39:46.683506Z","steps":["trace[2047645427] 'agreement among raft nodes before linearized reading' (duration: 598.181849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:46.684467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.49946ms","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-04-27T19:39:46.684518Z","caller":"traceutil/trace.go:171","msg":"trace[2075704233] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:802; }","duration":"585.574003ms","start":"2026-04-27T19:39:46.098932Z","end":"2026-04-27T19:39:46.684506Z","steps":["trace[2075704233] 'agreement among raft nodes before linearized reading' (duration: 585.455649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:46.684548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:46.098919Z","time spent":"585.620994ms","remote":"127.0.0.1:38816","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-04-27T19:39:46.684673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.869224ms","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-04-27T19:39:46.684782Z","caller":"traceutil/trace.go:171","msg":"trace[1286677200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:802; }","duration":"899.06458ms","start":"2026-04-27T19:39:45.785687Z","end":"2026-04-27T19:39:46.684751Z","steps":["trace[1286677200] 'agreement among raft nodes before linearized reading' (duration: 898.72099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:46.684856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:45.785668Z","time spent":"899.160043ms","remote":"127.0.0.1:38816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T19:39:46.685112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:39:46.085156Z","time spent":"598.402506ms","remote":"127.0.0.1:38816","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-04-27T19:39:57.741711Z","caller":"traceutil/trace.go:171","msg":"trace[650580335] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"170.068368ms","start":"2026-04-27T19:39:57.571604Z","end":"2026-04-27T19:39:57.741672Z","steps":["trace[650580335] 'process raft request' (duration: 143.34644ms)","trace[650580335] 'compare' (duration: 26.415389ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:39:57.741756Z","caller":"traceutil/trace.go:171","msg":"trace[484092192] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:977; }","duration":"132.067331ms","start":"2026-04-27T19:39:57.609638Z","end":"2026-04-27T19:39:57.741705Z","steps":["trace[484092192] 'read index received' (duration: 5.621579ms)","trace[484092192] 'applied index is now lower than readState.Index' (duration: 126.444582ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:39:57.741745Z","caller":"traceutil/trace.go:171","msg":"trace[1935774648] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"169.77272ms","start":"2026-04-27T19:39:57.571932Z","end":"2026-04-27T19:39:57.741705Z","steps":["trace[1935774648] 'process raft request' (duration: 169.628326ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:57.741848Z","caller":"traceutil/trace.go:171","msg":"trace[1111732796] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"143.761142ms","start":"2026-04-27T19:39:57.598059Z","end":"2026-04-27T19:39:57.74182Z","steps":["trace[1111732796] 'process raft request' (duration: 143.577547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:39:57.741982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.321468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-04-27T19:39:57.742559Z","caller":"traceutil/trace.go:171","msg":"trace[347046191] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:945; }","duration":"132.955875ms","start":"2026-04-27T19:39:57.609587Z","end":"2026-04-27T19:39:57.742543Z","steps":["trace[347046191] 'agreement among raft nodes before linearized reading' (duration: 132.201254ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:58.016239Z","caller":"traceutil/trace.go:171","msg":"trace[1808709739] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"170.047864ms","start":"2026-04-27T19:39:57.846164Z","end":"2026-04-27T19:39:58.016212Z","steps":["trace[1808709739] 'process raft request' (duration: 168.455589ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:39:58.016324Z","caller":"traceutil/trace.go:171","msg":"trace[1583997940] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"170.050554ms","start":"2026-04-27T19:39:57.84624Z","end":"2026-04-27T19:39:58.016291Z","steps":["trace[1583997940] 'process raft request' (duration: 169.814727ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:40:00.980284Z","caller":"traceutil/trace.go:171","msg":"trace[1851090684] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1006; }","duration":"212.786372ms","start":"2026-04-27T19:40:00.767479Z","end":"2026-04-27T19:40:00.980265Z","steps":["trace[1851090684] 'read index received' (duration: 212.640948ms)","trace[1851090684] 'applied index is now lower than readState.Index' (duration: 144.894µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:40:00.980372Z","caller":"traceutil/trace.go:171","msg":"trace[1445203840] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"219.818776ms","start":"2026-04-27T19:40:00.760524Z","end":"2026-04-27T19:40:00.980342Z","steps":["trace[1445203840] 'process raft request' (duration: 219.639212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:40:00.98046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.967527ms","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-04-27T19:40:00.980485Z","caller":"traceutil/trace.go:171","msg":"trace[798276731] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:971; }","duration":"213.028749ms","start":"2026-04-27T19:40:00.767449Z","end":"2026-04-27T19:40:00.980478Z","steps":["trace[798276731] 'agreement among raft nodes before linearized reading' (duration: 212.894716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:40:00.980569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.381871ms","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-04-27T19:40:00.980571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.045134ms","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-04-27T19:40:00.98059Z","caller":"traceutil/trace.go:171","msg":"trace[248615602] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:971; }","duration":"155.432183ms","start":"2026-04-27T19:40:00.825152Z","end":"2026-04-27T19:40:00.980584Z","steps":["trace[248615602] 'agreement among raft nodes before linearized reading' (duration: 155.386282ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:40:00.980604Z","caller":"traceutil/trace.go:171","msg":"trace[867948676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:971; }","duration":"154.091975ms","start":"2026-04-27T19:40:00.826501Z","end":"2026-04-27T19:40:00.980593Z","steps":["trace[867948676] 'agreement among raft nodes before linearized reading' (duration: 154.000133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:40:00.980793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.06131ms","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-04-27T19:40:00.980916Z","caller":"traceutil/trace.go:171","msg":"trace[2095119346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:971; }","duration":"213.192114ms","start":"2026-04-27T19:40:00.767711Z","end":"2026-04-27T19:40:00.980903Z","steps":["trace[2095119346] 'agreement among raft nodes before linearized reading' (duration: 212.732921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:40:01.186505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.469705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:40:01.186646Z","caller":"traceutil/trace.go:171","msg":"trace[1237088277] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1008; }","duration":"138.021988ms","start":"2026-04-27T19:40:01.04861Z","end":"2026-04-27T19:40:01.186632Z","steps":["trace[1237088277] 'read index received' (duration: 27.502861ms)","trace[1237088277] 'applied index is now lower than readState.Index' (duration: 110.518217ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:40:01.186757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.150923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-679c578f5-2n9gm\" ","response":"range_response_count:1 size:3611"} {"level":"info","ts":"2026-04-27T19:40:01.186779Z","caller":"traceutil/trace.go:171","msg":"trace[518248370] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-679c578f5-2n9gm; range_end:; response_count:1; response_revision:975; }","duration":"138.222534ms","start":"2026-04-27T19:40:01.048548Z","end":"2026-04-27T19:40:01.18677Z","steps":["trace[518248370] 'agreement among raft nodes before linearized reading' (duration: 138.120241ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:40:01.1869Z","caller":"traceutil/trace.go:171","msg":"trace[519705362] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"200.275483ms","start":"2026-04-27T19:40:00.986618Z","end":"2026-04-27T19:40:01.186894Z","steps":["trace[519705362] 'process raft request' (duration: 199.986865ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:40:01.186931Z","caller":"traceutil/trace.go:171","msg":"trace[917055704] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"200.752966ms","start":"2026-04-27T19:40:00.986146Z","end":"2026-04-27T19:40:01.186899Z","steps":["trace[917055704] 'process raft request' (duration: 200.431887ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:40:01.187176Z","caller":"traceutil/trace.go:171","msg":"trace[1433864764] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"201.089225ms","start":"2026-04-27T19:40:00.98608Z","end":"2026-04-27T19:40:01.187169Z","steps":["trace[1433864764] 'process raft request' (duration: 89.892509ms)","trace[1433864764] 'compare' (duration: 110.165697ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:40:13.268721Z","caller":"traceutil/trace.go:171","msg":"trace[1560061285] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"123.918246ms","start":"2026-04-27T19:40:13.144776Z","end":"2026-04-27T19:40:13.268695Z","steps":["trace[1560061285] 'process raft request' (duration: 89.627563ms)","trace[1560061285] 'compare' (duration: 34.123759ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:40:13.269191Z","caller":"traceutil/trace.go:171","msg":"trace[1828143348] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1054; }","duration":"119.477206ms","start":"2026-04-27T19:40:13.149698Z","end":"2026-04-27T19:40:13.269175Z","steps":["trace[1828143348] 'read index received' (duration: 84.63563ms)","trace[1828143348] 'applied index is now lower than readState.Index' (duration: 34.841166ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:40:13.269197Z","caller":"traceutil/trace.go:171","msg":"trace[369534399] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"123.610859ms","start":"2026-04-27T19:40:13.145558Z","end":"2026-04-27T19:40:13.269169Z","steps":["trace[369534399] 'process raft request' (duration: 123.096286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:40:13.269577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.882477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-27T19:40:13.269615Z","caller":"traceutil/trace.go:171","msg":"trace[605637873] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1018; }","duration":"119.960429ms","start":"2026-04-27T19:40:13.149639Z","end":"2026-04-27T19:40:13.2696Z","steps":["trace[605637873] 'agreement among raft nodes before linearized reading' (duration: 119.620331ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:40:13.445919Z","caller":"traceutil/trace.go:171","msg":"trace[1898483339] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1058; }","duration":"172.348936ms","start":"2026-04-27T19:40:13.273548Z","end":"2026-04-27T19:40:13.445897Z","steps":["trace[1898483339] 'read index received' (duration: 91.348795ms)","trace[1898483339] 'applied index is now lower than readState.Index' (duration: 80.999361ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:40:13.44607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.499841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-27T19:40:13.446109Z","caller":"traceutil/trace.go:171","msg":"trace[1576419488] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1021; }","duration":"172.563242ms","start":"2026-04-27T19:40:13.273536Z","end":"2026-04-27T19:40:13.446099Z","steps":["trace[1576419488] 'agreement among raft nodes before linearized reading' (duration: 172.450539ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:40:13.446411Z","caller":"traceutil/trace.go:171","msg":"trace[22420066] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"173.079794ms","start":"2026-04-27T19:40:13.273317Z","end":"2026-04-27T19:40:13.446397Z","steps":["trace[22420066] 'process raft request' (duration: 141.170489ms)","trace[22420066] 'compare' (duration: 31.257849ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:40:13.446587Z","caller":"traceutil/trace.go:171","msg":"trace[485872273] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"173.195897ms","start":"2026-04-27T19:40:13.27333Z","end":"2026-04-27T19:40:13.446526Z","steps":["trace[485872273] 'process raft request' (duration: 172.523311ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:40:19.446288Z","caller":"traceutil/trace.go:171","msg":"trace[1837976907] linearizableReadLoop","detail":"{readStateIndex:1077; appliedIndex:1076; }","duration":"148.152196ms","start":"2026-04-27T19:40:19.29811Z","end":"2026-04-27T19:40:19.446262Z","steps":["trace[1837976907] 'read index received' (duration: 147.947672ms)","trace[1837976907] 'applied index is now lower than readState.Index' (duration: 203.494µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:40:19.446349Z","caller":"traceutil/trace.go:171","msg":"trace[485179155] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"152.015138ms","start":"2026-04-27T19:40:19.29431Z","end":"2026-04-27T19:40:19.446325Z","steps":["trace[485179155] 'process raft request' (duration: 151.807343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:40:19.446511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.380651ms","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-04-27T19:40:19.446565Z","caller":"traceutil/trace.go:171","msg":"trace[277166587] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1037; }","duration":"148.478724ms","start":"2026-04-27T19:40:19.298075Z","end":"2026-04-27T19:40:19.446554Z","steps":["trace[277166587] 'agreement among raft nodes before linearized reading' (duration: 148.32205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:40:19.446617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.400943ms","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":"warn","ts":"2026-04-27T19:40:19.446669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.420521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:40:19.446702Z","caller":"traceutil/trace.go:171","msg":"trace[2118562822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1037; }","duration":"124.483163ms","start":"2026-04-27T19:40:19.322208Z","end":"2026-04-27T19:40:19.446691Z","steps":["trace[2118562822] 'agreement among raft nodes before linearized reading' (duration: 124.433282ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:40:19.446707Z","caller":"traceutil/trace.go:171","msg":"trace[458646948] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1037; }","duration":"148.510975ms","start":"2026-04-27T19:40:19.298175Z","end":"2026-04-27T19:40:19.446686Z","steps":["trace[458646948] 'agreement among raft nodes before linearized reading' (duration: 148.246499ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:40:19.588313Z","caller":"traceutil/trace.go:171","msg":"trace[1015186635] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"112.970263ms","start":"2026-04-27T19:40:19.47531Z","end":"2026-04-27T19:40:19.588281Z","steps":["trace[1015186635] 'process raft request' (duration: 90.614429ms)","trace[1015186635] 'compare' (duration: 22.023926ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:40:19.589277Z","caller":"traceutil/trace.go:171","msg":"trace[511364106] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"111.557951ms","start":"2026-04-27T19:40:19.47743Z","end":"2026-04-27T19:40:19.588988Z","steps":["trace[511364106] 'process raft request' (duration: 111.064758ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:40:29.781296Z","caller":"traceutil/trace.go:171","msg":"trace[1677053088] linearizableReadLoop","detail":"{readStateIndex:1120; appliedIndex:1117; }","duration":"136.345774ms","start":"2026-04-27T19:40:29.644927Z","end":"2026-04-27T19:40:29.781272Z","steps":["trace[1677053088] 'read index received' (duration: 333.617µs)","trace[1677053088] 'applied index is now lower than readState.Index' (duration: 136.011167ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:40:29.781409Z","caller":"traceutil/trace.go:171","msg":"trace[604817476] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"142.810734ms","start":"2026-04-27T19:40:29.638588Z","end":"2026-04-27T19:40:29.781399Z","steps":["trace[604817476] 'process raft request' (duration: 98.960743ms)","trace[604817476] 'compare' (duration: 43.559614ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:40:29.781544Z","caller":"traceutil/trace.go:171","msg":"trace[772111129] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"142.804234ms","start":"2026-04-27T19:40:29.638718Z","end":"2026-04-27T19:40:29.781522Z","steps":["trace[772111129] 'process raft request' (duration: 142.509298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:40:29.78162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.684762ms","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-04-27T19:40:29.781651Z","caller":"traceutil/trace.go:171","msg":"trace[1594597340] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1077; }","duration":"136.759073ms","start":"2026-04-27T19:40:29.644884Z","end":"2026-04-27T19:40:29.781643Z","steps":["trace[1594597340] 'agreement among raft nodes before linearized reading' (duration: 136.6335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:40:29.781752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.862621ms","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-04-27T19:40:29.78182Z","caller":"traceutil/trace.go:171","msg":"trace[772296473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1077; }","duration":"133.972663ms","start":"2026-04-27T19:40:29.647833Z","end":"2026-04-27T19:40:29.781806Z","steps":["trace[772296473] 'agreement among raft nodes before linearized reading' (duration: 133.778229ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:40:35.768169Z","caller":"traceutil/trace.go:171","msg":"trace[825431969] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1136; }","duration":"102.018896ms","start":"2026-04-27T19:40:35.666125Z","end":"2026-04-27T19:40:35.768144Z","steps":["trace[825431969] 'read index received' (duration: 101.823482ms)","trace[825431969] 'applied index is now lower than readState.Index' (duration: 194.504µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:40:35.768315Z","caller":"traceutil/trace.go:171","msg":"trace[1381333889] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"109.670175ms","start":"2026-04-27T19:40:35.658611Z","end":"2026-04-27T19:40:35.768281Z","steps":["trace[1381333889] 'process raft request' (duration: 109.3962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:40:35.768378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.235431ms","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-04-27T19:40:35.768423Z","caller":"traceutil/trace.go:171","msg":"trace[1945856010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1093; }","duration":"102.325282ms","start":"2026-04-27T19:40:35.666086Z","end":"2026-04-27T19:40:35.768412Z","steps":["trace[1945856010] 'agreement among raft nodes before linearized reading' (duration: 102.143318ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:40:37.948047Z","caller":"traceutil/trace.go:171","msg":"trace[1541000791] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"174.988919ms","start":"2026-04-27T19:40:37.773031Z","end":"2026-04-27T19:40:37.94802Z","steps":["trace[1541000791] 'process raft request' (duration: 174.832256ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:40:37.949615Z","caller":"traceutil/trace.go:171","msg":"trace[1673700375] linearizableReadLoop","detail":"{readStateIndex:1144; appliedIndex:1142; }","duration":"146.57224ms","start":"2026-04-27T19:40:37.803019Z","end":"2026-04-27T19:40:37.949591Z","steps":["trace[1673700375] 'read index received' (duration: 144.894975ms)","trace[1673700375] 'applied index is now lower than readState.Index' (duration: 1.676335ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:40:37.949746Z","caller":"traceutil/trace.go:171","msg":"trace[268146199] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"175.695735ms","start":"2026-04-27T19:40:37.774029Z","end":"2026-04-27T19:40:37.949725Z","steps":["trace[268146199] 'process raft request' (duration: 175.44873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:40:37.949772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.497462ms","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-04-27T19:40:37.949841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.829664ms","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-04-27T19:40:37.949946Z","caller":"traceutil/trace.go:171","msg":"trace[1192433715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1100; }","duration":"146.975138ms","start":"2026-04-27T19:40:37.80295Z","end":"2026-04-27T19:40:37.949925Z","steps":["trace[1192433715] 'agreement among raft nodes before linearized reading' (duration: 146.756663ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:40:37.949947Z","caller":"traceutil/trace.go:171","msg":"trace[1913208560] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1100; }","duration":"111.679915ms","start":"2026-04-27T19:40:37.838219Z","end":"2026-04-27T19:40:37.949899Z","steps":["trace[1913208560] 'agreement among raft nodes before linearized reading' (duration: 111.500301ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:40:48.379883Z","caller":"traceutil/trace.go:171","msg":"trace[1970613066] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"152.273148ms","start":"2026-04-27T19:40:48.227591Z","end":"2026-04-27T19:40:48.379864Z","steps":["trace[1970613066] 'process raft request' (duration: 152.169056ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:40:48.386641Z","caller":"traceutil/trace.go:171","msg":"trace[1590358267] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"157.063669ms","start":"2026-04-27T19:40:48.229564Z","end":"2026-04-27T19:40:48.386628Z","steps":["trace[1590358267] 'process raft request' (duration: 157.025038ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:40:48.386677Z","caller":"traceutil/trace.go:171","msg":"trace[653725697] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"157.298743ms","start":"2026-04-27T19:40:48.229344Z","end":"2026-04-27T19:40:48.386642Z","steps":["trace[653725697] 'process raft request' (duration: 157.190811ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:40:48.387125Z","caller":"traceutil/trace.go:171","msg":"trace[820899214] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"157.479348ms","start":"2026-04-27T19:40:48.229635Z","end":"2026-04-27T19:40:48.387115Z","steps":["trace[820899214] 'process raft request' (duration: 156.975548ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:40:48.428109Z","caller":"traceutil/trace.go:171","msg":"trace[545347311] linearizableReadLoop","detail":"{readStateIndex:1183; appliedIndex:1179; }","duration":"109.079496ms","start":"2026-04-27T19:40:48.319008Z","end":"2026-04-27T19:40:48.428088Z","steps":["trace[545347311] 'read index received' (duration: 60.683015ms)","trace[545347311] 'applied index is now lower than readState.Index' (duration: 48.395301ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:40:48.428213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.193288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:40:48.428233Z","caller":"traceutil/trace.go:171","msg":"trace[1955075086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1138; }","duration":"109.27774ms","start":"2026-04-27T19:40:48.31895Z","end":"2026-04-27T19:40:48.428228Z","steps":["trace[1955075086] 'agreement among raft nodes before linearized reading' (duration: 109.208589ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:10.629262Z","caller":"traceutil/trace.go:171","msg":"trace[349534101] linearizableReadLoop","detail":"{readStateIndex:1259; appliedIndex:1258; }","duration":"108.627167ms","start":"2026-04-27T19:41:10.520608Z","end":"2026-04-27T19:41:10.629235Z","steps":["trace[349534101] 'read index received' (duration: 107.389007ms)","trace[349534101] 'applied index is now lower than readState.Index' (duration: 1.23714ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:41:10.629377Z","caller":"traceutil/trace.go:171","msg":"trace[59513171] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"108.77888ms","start":"2026-04-27T19:41:10.520562Z","end":"2026-04-27T19:41:10.629341Z","steps":["trace[59513171] 'process raft request' (duration: 108.455695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:10.629547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.917312ms","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-04-27T19:41:10.62963Z","caller":"traceutil/trace.go:171","msg":"trace[1893928420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1208; }","duration":"109.051714ms","start":"2026-04-27T19:41:10.520558Z","end":"2026-04-27T19:41:10.62961Z","steps":["trace[1893928420] 'agreement among raft nodes before linearized reading' (duration: 108.81057ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:10.88908Z","caller":"traceutil/trace.go:171","msg":"trace[251759598] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"254.98163ms","start":"2026-04-27T19:41:10.634069Z","end":"2026-04-27T19:41:10.88905Z","steps":["trace[251759598] 'process raft request' (duration: 170.650951ms)","trace[251759598] 'compare' (duration: 83.75565ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:41:10.889209Z","caller":"traceutil/trace.go:171","msg":"trace[937261651] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"255.023802ms","start":"2026-04-27T19:41:10.634162Z","end":"2026-04-27T19:41:10.889186Z","steps":["trace[937261651] 'process raft request' (duration: 254.724657ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:10.889352Z","caller":"traceutil/trace.go:171","msg":"trace[1883126302] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"255.083082ms","start":"2026-04-27T19:41:10.634091Z","end":"2026-04-27T19:41:10.889174Z","steps":["trace[1883126302] 'process raft request' (duration: 254.680826ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:10.889581Z","caller":"traceutil/trace.go:171","msg":"trace[1056378739] linearizableReadLoop","detail":"{readStateIndex:1262; appliedIndex:1259; }","duration":"144.99293ms","start":"2026-04-27T19:41:10.74408Z","end":"2026-04-27T19:41:10.889073Z","steps":["trace[1056378739] 'read index received' (duration: 60.654931ms)","trace[1056378739] 'applied index is now lower than readState.Index' (duration: 84.336949ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:41:10.890115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.013076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329948"} {"level":"info","ts":"2026-04-27T19:41:10.890382Z","caller":"traceutil/trace.go:171","msg":"trace[734840033] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1211; }","duration":"146.325813ms","start":"2026-04-27T19:41:10.744031Z","end":"2026-04-27T19:41:10.890357Z","steps":["trace[734840033] 'agreement among raft nodes before linearized reading' (duration: 145.346126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:11.283487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.618725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:41:11.283572Z","caller":"traceutil/trace.go:171","msg":"trace[716893605] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1211; }","duration":"146.776437ms","start":"2026-04-27T19:41:11.136776Z","end":"2026-04-27T19:41:11.283552Z","steps":["trace[716893605] 'count revisions from in-memory index tree' (duration: 146.539074ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:16.885625Z","caller":"traceutil/trace.go:171","msg":"trace[1538270818] linearizableReadLoop","detail":"{readStateIndex:1279; appliedIndex:1278; }","duration":"164.688422ms","start":"2026-04-27T19:41:16.720912Z","end":"2026-04-27T19:41:16.8856Z","steps":["trace[1538270818] 'read index received' (duration: 164.5415ms)","trace[1538270818] 'applied index is now lower than readState.Index' (duration: 145.802µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:41:16.885695Z","caller":"traceutil/trace.go:171","msg":"trace[1177866757] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"173.005898ms","start":"2026-04-27T19:41:16.71265Z","end":"2026-04-27T19:41:16.885656Z","steps":["trace[1177866757] 'process raft request' (duration: 172.810505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:16.885813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.894625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5548"} {"level":"info","ts":"2026-04-27T19:41:16.885853Z","caller":"traceutil/trace.go:171","msg":"trace[1865303857] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1227; }","duration":"164.995817ms","start":"2026-04-27T19:41:16.720847Z","end":"2026-04-27T19:41:16.885843Z","steps":["trace[1865303857] 'agreement among raft nodes before linearized reading' (duration: 164.846975ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:19.772665Z","caller":"traceutil/trace.go:171","msg":"trace[3574134] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"104.336202ms","start":"2026-04-27T19:41:19.668308Z","end":"2026-04-27T19:41:19.772644Z","steps":["trace[3574134] 'process raft request' (duration: 104.21997ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:20.438904Z","caller":"traceutil/trace.go:171","msg":"trace[692858012] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"134.120692ms","start":"2026-04-27T19:41:20.304752Z","end":"2026-04-27T19:41:20.438872Z","steps":["trace[692858012] 'process raft request' (duration: 133.98823ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:22.549886Z","caller":"traceutil/trace.go:171","msg":"trace[907323973] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"105.422578ms","start":"2026-04-27T19:41:22.444427Z","end":"2026-04-27T19:41:22.549849Z","steps":["trace[907323973] 'process raft request' (duration: 105.265025ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:22.707322Z","caller":"traceutil/trace.go:171","msg":"trace[703396152] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"152.958404ms","start":"2026-04-27T19:41:22.554323Z","end":"2026-04-27T19:41:22.707281Z","steps":["trace[703396152] 'process raft request' (duration: 67.24141ms)","trace[703396152] 'compare' (duration: 85.556312ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:41:23.249831Z","caller":"traceutil/trace.go:171","msg":"trace[1326202069] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"141.462661ms","start":"2026-04-27T19:41:23.108342Z","end":"2026-04-27T19:41:23.249805Z","steps":["trace[1326202069] 'process raft request' (duration: 138.969162ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:23.249951Z","caller":"traceutil/trace.go:171","msg":"trace[2060264706] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"140.084389ms","start":"2026-04-27T19:41:23.109844Z","end":"2026-04-27T19:41:23.249928Z","steps":["trace[2060264706] 'process raft request' (duration: 139.890615ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:23.250094Z","caller":"traceutil/trace.go:171","msg":"trace[1528827450] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1250; }","duration":"100.004824ms","start":"2026-04-27T19:41:23.150083Z","end":"2026-04-27T19:41:23.250088Z","steps":["trace[1528827450] 'agreement among raft nodes before linearized reading' (duration: 99.983514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:24.85936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.874483ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T19:41:24.859496Z","caller":"traceutil/trace.go:171","msg":"trace[764985546] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"120.237146ms","start":"2026-04-27T19:41:24.739241Z","end":"2026-04-27T19:41:24.859478Z","steps":["trace[764985546] 'compare' (duration: 109.744221ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:25.027549Z","caller":"traceutil/trace.go:171","msg":"trace[262834031] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"286.665259ms","start":"2026-04-27T19:41:24.740846Z","end":"2026-04-27T19:41:25.027511Z","steps":["trace[262834031] 'process raft request' (duration: 237.586337ms)","trace[262834031] 'compare' (duration: 48.910509ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:41:25.755105Z","caller":"traceutil/trace.go:171","msg":"trace[386360110] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"371.736848ms","start":"2026-04-27T19:41:25.383348Z","end":"2026-04-27T19:41:25.755085Z","steps":["trace[386360110] 'process raft request' (duration: 371.627777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:25.755203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:25.383331Z","time spent":"371.83007ms","remote":"127.0.0.1:38726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1475,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T19:41:25.901068Z","caller":"traceutil/trace.go:171","msg":"trace[1363078361] linearizableReadLoop","detail":"{readStateIndex:1316; appliedIndex:1315; }","duration":"137.903405ms","start":"2026-04-27T19:41:25.763135Z","end":"2026-04-27T19:41:25.901038Z","steps":["trace[1363078361] 'read index received' (duration: 52.048206ms)","trace[1363078361] 'applied index is now lower than readState.Index' (duration: 85.853589ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:41:25.901117Z","caller":"traceutil/trace.go:171","msg":"trace[134053134] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"138.18082ms","start":"2026-04-27T19:41:25.762914Z","end":"2026-04-27T19:41:25.901095Z","steps":["trace[134053134] 'process raft request' (duration: 52.322541ms)","trace[134053134] 'compare' (duration: 85.567545ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:41:25.901327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.18143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1490"} {"level":"info","ts":"2026-04-27T19:41:25.901379Z","caller":"traceutil/trace.go:171","msg":"trace[1599596621] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1261; }","duration":"138.292451ms","start":"2026-04-27T19:41:25.763073Z","end":"2026-04-27T19:41:25.901365Z","steps":["trace[1599596621] 'agreement among raft nodes before linearized reading' (duration: 138.074568ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:26.151717Z","caller":"traceutil/trace.go:171","msg":"trace[1086915583] linearizableReadLoop","detail":"{readStateIndex:1319; appliedIndex:1318; }","duration":"234.30848ms","start":"2026-04-27T19:41:25.917382Z","end":"2026-04-27T19:41:26.15169Z","steps":["trace[1086915583] 'read index received' (duration: 232.432831ms)","trace[1086915583] 'applied index is now lower than readState.Index' (duration: 1.874579ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:41:26.151752Z","caller":"traceutil/trace.go:171","msg":"trace[718522158] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"235.392477ms","start":"2026-04-27T19:41:25.916315Z","end":"2026-04-27T19:41:26.151707Z","steps":["trace[718522158] 'process raft request' (duration: 233.475338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:26.151884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.455622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T19:41:26.15225Z","caller":"traceutil/trace.go:171","msg":"trace[2089309919] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:1264; }","duration":"234.876879ms","start":"2026-04-27T19:41:25.917343Z","end":"2026-04-27T19:41:26.15222Z","steps":["trace[2089309919] 'agreement among raft nodes before linearized reading' (duration: 234.424231ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:26.355471Z","caller":"traceutil/trace.go:171","msg":"trace[1731790429] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"195.892004ms","start":"2026-04-27T19:41:26.159492Z","end":"2026-04-27T19:41:26.355384Z","steps":["trace[1731790429] 'process raft request' (duration: 129.964713ms)","trace[1731790429] 'compare' (duration: 65.733148ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:41:31.443517Z","caller":"traceutil/trace.go:171","msg":"trace[1840510560] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"143.826624ms","start":"2026-04-27T19:41:31.29966Z","end":"2026-04-27T19:41:31.443486Z","steps":["trace[1840510560] 'process raft request' (duration: 143.600961ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:32.759687Z","caller":"traceutil/trace.go:171","msg":"trace[1513946590] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"155.211929ms","start":"2026-04-27T19:41:32.604448Z","end":"2026-04-27T19:41:32.75966Z","steps":["trace[1513946590] 'process raft request' (duration: 155.058636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:33.269556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.592924ms","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-04-27T19:41:33.269672Z","caller":"traceutil/trace.go:171","msg":"trace[1059894195] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1310; }","duration":"139.751395ms","start":"2026-04-27T19:41:33.129892Z","end":"2026-04-27T19:41:33.269644Z","steps":["trace[1059894195] 'range keys from in-memory index tree' (duration: 139.36408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:33.506285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.389551ms","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-04-27T19:41:33.50642Z","caller":"traceutil/trace.go:171","msg":"trace[351520844] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1311; }","duration":"118.550803ms","start":"2026-04-27T19:41:33.387848Z","end":"2026-04-27T19:41:33.506399Z","steps":["trace[351520844] 'range keys from in-memory index tree' (duration: 118.35235ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:33.506745Z","caller":"traceutil/trace.go:171","msg":"trace[1343645312] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"158.123735ms","start":"2026-04-27T19:41:33.348593Z","end":"2026-04-27T19:41:33.506716Z","steps":["trace[1343645312] 'process raft request' (duration: 112.273798ms)","trace[1343645312] 'compare' (duration: 45.360779ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:41:33.506983Z","caller":"traceutil/trace.go:171","msg":"trace[1335018640] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"157.663977ms","start":"2026-04-27T19:41:33.349294Z","end":"2026-04-27T19:41:33.506958Z","steps":["trace[1335018640] 'process raft request' (duration: 157.314462ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:35.526964Z","caller":"traceutil/trace.go:171","msg":"trace[29051919] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"174.082268ms","start":"2026-04-27T19:41:35.352846Z","end":"2026-04-27T19:41:35.526928Z","steps":["trace[29051919] 'process raft request' (duration: 173.55345ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:36.944405Z","caller":"traceutil/trace.go:171","msg":"trace[1578379216] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"131.859602ms","start":"2026-04-27T19:41:36.812516Z","end":"2026-04-27T19:41:36.944376Z","steps":["trace[1578379216] 'process raft request' (duration: 131.435026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:38.055507Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2802819861723346559,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T19:41:38.286467Z","caller":"traceutil/trace.go:171","msg":"trace[1636548257] linearizableReadLoop","detail":"{readStateIndex:1380; appliedIndex:1379; }","duration":"731.749506ms","start":"2026-04-27T19:41:37.554691Z","end":"2026-04-27T19:41:38.28644Z","steps":["trace[1636548257] 'read index received' (duration: 731.555344ms)","trace[1636548257] 'applied index is now lower than readState.Index' (duration: 193.202µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T19:41:38.286558Z","caller":"traceutil/trace.go:171","msg":"trace[1043133517] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"752.197053ms","start":"2026-04-27T19:41:37.534327Z","end":"2026-04-27T19:41:38.286524Z","steps":["trace[1043133517] 'process raft request' (duration: 751.977079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:38.286696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:37.534306Z","time spent":"752.318174ms","remote":"127.0.0.1:38816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T19:41:38.286864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.66105ms","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-04-27T19:41:38.286907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.725484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T19:41:38.28693Z","caller":"traceutil/trace.go:171","msg":"trace[1012230675] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1323; }","duration":"720.790582ms","start":"2026-04-27T19:41:37.566122Z","end":"2026-04-27T19:41:38.286912Z","steps":["trace[1012230675] 'agreement among raft nodes before linearized reading' (duration: 720.580829ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:38.286974Z","caller":"traceutil/trace.go:171","msg":"trace[2090905770] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1323; }","duration":"369.843456ms","start":"2026-04-27T19:41:37.917104Z","end":"2026-04-27T19:41:38.286947Z","steps":["trace[2090905770] 'agreement among raft nodes before linearized reading' (duration: 369.733024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:38.286985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:37.566098Z","time spent":"720.873313ms","remote":"127.0.0.1:38816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T19:41:38.286978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.756538ms","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-04-27T19:41:38.287021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:37.917082Z","time spent":"369.922477ms","remote":"127.0.0.1:33426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":5,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:41:38.287069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.60806ms","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-04-27T19:41:38.28707Z","caller":"traceutil/trace.go:171","msg":"trace[431317811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1323; }","duration":"655.856689ms","start":"2026-04-27T19:41:37.631184Z","end":"2026-04-27T19:41:38.28704Z","steps":["trace[431317811] 'agreement among raft nodes before linearized reading' (duration: 655.590725ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:38.287111Z","caller":"traceutil/trace.go:171","msg":"trace[525179049] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1323; }","duration":"658.643831ms","start":"2026-04-27T19:41:37.628451Z","end":"2026-04-27T19:41:38.287095Z","steps":["trace[525179049] 'agreement among raft nodes before linearized reading' (duration: 658.538409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:38.28713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:37.631146Z","time spent":"655.970461ms","remote":"127.0.0.1:38816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T19:41:38.287142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:37.628404Z","time spent":"658.731472ms","remote":"127.0.0.1:38816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T19:41:38.28723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.93255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T19:41:38.287279Z","caller":"traceutil/trace.go:171","msg":"trace[415922732] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1323; }","duration":"676.019951ms","start":"2026-04-27T19:41:37.611244Z","end":"2026-04-27T19:41:38.287264Z","steps":["trace[415922732] 'agreement among raft nodes before linearized reading' (duration: 675.93916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:38.287324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:37.611223Z","time spent":"676.088442ms","remote":"127.0.0.1:38716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-27T19:41:38.287402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.70507ms","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-04-27T19:41:38.287527Z","caller":"traceutil/trace.go:171","msg":"trace[946406458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1323; }","duration":"732.849563ms","start":"2026-04-27T19:41:37.554659Z","end":"2026-04-27T19:41:38.287508Z","steps":["trace[946406458] 'agreement among raft nodes before linearized reading' (duration: 731.948129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:38.287589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T19:41:37.554643Z","time spent":"732.929015ms","remote":"127.0.0.1:38816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-27T19:41:39.1773Z","caller":"traceutil/trace.go:171","msg":"trace[75565814] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"227.79182ms","start":"2026-04-27T19:41:38.949482Z","end":"2026-04-27T19:41:39.177274Z","steps":["trace[75565814] 'process raft request' (duration: 227.536217ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:52.55183Z","caller":"traceutil/trace.go:171","msg":"trace[1094466441] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"113.331924ms","start":"2026-04-27T19:41:52.438458Z","end":"2026-04-27T19:41:52.55179Z","steps":["trace[1094466441] 'process raft request' (duration: 68.026239ms)","trace[1094466441] 'compare' (duration: 44.91835ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:41:55.540062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.777974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:basic-user\" ","response":"range_response_count:1 size:678"} {"level":"info","ts":"2026-04-27T19:41:55.540145Z","caller":"traceutil/trace.go:171","msg":"trace[1961609905] range","detail":"{range_begin:/registry/clusterroles/system:basic-user; range_end:; response_count:1; response_revision:1393; }","duration":"105.869535ms","start":"2026-04-27T19:41:55.434257Z","end":"2026-04-27T19:41:55.540126Z","steps":["trace[1961609905] 'range keys from in-memory index tree' (duration: 105.698973ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:56.68504Z","caller":"traceutil/trace.go:171","msg":"trace[789788360] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"165.48436ms","start":"2026-04-27T19:41:56.519519Z","end":"2026-04-27T19:41:56.685004Z","steps":["trace[789788360] 'process raft request' (duration: 163.042755ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:56.754547Z","caller":"traceutil/trace.go:171","msg":"trace[41913444] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"234.551393ms","start":"2026-04-27T19:41:56.519965Z","end":"2026-04-27T19:41:56.754517Z","steps":["trace[41913444] 'process raft request' (duration: 234.38319ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:56.754757Z","caller":"traceutil/trace.go:171","msg":"trace[828345711] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"234.604344ms","start":"2026-04-27T19:41:56.520129Z","end":"2026-04-27T19:41:56.754733Z","steps":["trace[828345711] 'process raft request' (duration: 234.34699ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:56.754764Z","caller":"traceutil/trace.go:171","msg":"trace[1427733681] linearizableReadLoop","detail":"{readStateIndex:1458; appliedIndex:1455; }","duration":"190.461671ms","start":"2026-04-27T19:41:56.564251Z","end":"2026-04-27T19:41:56.754713Z","steps":["trace[1427733681] 'read index received' (duration: 118.319565ms)","trace[1427733681] 'applied index is now lower than readState.Index' (duration: 72.139886ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T19:41:56.755088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.813246ms","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-04-27T19:41:56.755139Z","caller":"traceutil/trace.go:171","msg":"trace[2098286357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1397; }","duration":"190.904128ms","start":"2026-04-27T19:41:56.564219Z","end":"2026-04-27T19:41:56.755123Z","steps":["trace[2098286357] 'agreement among raft nodes before linearized reading' (duration: 190.695025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T19:41:56.755133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.926469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:deployment-controller\" ","response":"range_response_count:1 size:913"} {"level":"warn","ts":"2026-04-27T19:41:56.755133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.890462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:disruption-controller\" ","response":"range_response_count:1 size:972"} {"level":"info","ts":"2026-04-27T19:41:56.755193Z","caller":"traceutil/trace.go:171","msg":"trace[1146255119] range","detail":"{range_begin:/registry/clusterroles/system:controller:deployment-controller; range_end:; response_count:1; response_revision:1397; }","duration":"164.02793ms","start":"2026-04-27T19:41:56.591146Z","end":"2026-04-27T19:41:56.755174Z","steps":["trace[1146255119] 'agreement among raft nodes before linearized reading' (duration: 163.792457ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T19:41:56.755248Z","caller":"traceutil/trace.go:171","msg":"trace[2062511750] range","detail":"{range_begin:/registry/clusterroles/system:controller:disruption-controller; range_end:; response_count:1; response_revision:1397; }","duration":"133.019534ms","start":"2026-04-27T19:41:56.622206Z","end":"2026-04-27T19:41:56.755226Z","steps":["trace[2062511750] 'agreement among raft nodes before linearized reading' (duration: 132.800841ms)"],"step_count":1}