{"level":"warn","ts":"2026-04-21T18:13:59.677728Z","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-21T18:13:59.677836Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.175: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.175:2380","--initial-cluster=instance=https://199.19.213.175:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.175:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.175: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-21T18:13:59.677896Z","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-21T18:13:59.677908Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.175:2380"]} {"level":"info","ts":"2026-04-21T18:13:59.67793Z","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-21T18:13:59.678492Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.175:2379"]} {"level":"info","ts":"2026-04-21T18:13:59.678557Z","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.175:2380"],"listen-peer-urls":["https://199.19.213.175:2380"],"advertise-client-urls":["https://199.19.213.175:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.175:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.175: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-21T18:13:59.681963Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.234663ms"} {"level":"info","ts":"2026-04-21T18:13:59.689136Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"223c7693b78843e1","cluster-id":"b697af90308fdc01"} {"level":"info","ts":"2026-04-21T18:13:59.689228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-21T18:13:59.689266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 became follower at term 0"} {"level":"info","ts":"2026-04-21T18:13:59.689285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 223c7693b78843e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-21T18:13:59.689309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 became follower at term 1"} {"level":"info","ts":"2026-04-21T18:13:59.689362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 switched to configuration voters=(2466977072703620065)"} {"level":"warn","ts":"2026-04-21T18:13:59.69665Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-21T18:13:59.697854Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-21T18:13:59.698866Z","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-21T18:13:59.700807Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"223c7693b78843e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-21T18:13:59.701059Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"223c7693b78843e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-21T18:13:59.701757Z","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-21T18:13:59.701868Z","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-21T18:13:59.702134Z","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-21T18:13:59.703255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 switched to configuration voters=(2466977072703620065)"} {"level":"info","ts":"2026-04-21T18:13:59.707083Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b697af90308fdc01","local-member-id":"223c7693b78843e1","added-peer-id":"223c7693b78843e1","added-peer-peer-urls":["https://199.19.213.175:2380"]} {"level":"info","ts":"2026-04-21T18:13:59.709452Z","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-21T18:13:59.709824Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.175:2380"} {"level":"info","ts":"2026-04-21T18:13:59.709859Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.175:2380"} {"level":"info","ts":"2026-04-21T18:13:59.710106Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"223c7693b78843e1","initial-advertise-peer-urls":["https://199.19.213.175:2380"],"listen-peer-urls":["https://199.19.213.175:2380"],"advertise-client-urls":["https://199.19.213.175:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.175:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-21T18:13:59.71016Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-21T18:13:59.989908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-21T18:13:59.989966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-21T18:13:59.989989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 received MsgPreVoteResp from 223c7693b78843e1 at term 1"} {"level":"info","ts":"2026-04-21T18:13:59.990008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 became candidate at term 2"} {"level":"info","ts":"2026-04-21T18:13:59.99002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 received MsgVoteResp from 223c7693b78843e1 at term 2"} {"level":"info","ts":"2026-04-21T18:13:59.990036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 became leader at term 2"} {"level":"info","ts":"2026-04-21T18:13:59.99005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 223c7693b78843e1 elected leader 223c7693b78843e1 at term 2"} {"level":"info","ts":"2026-04-21T18:13:59.99087Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T18:13:59.992261Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b697af90308fdc01","local-member-id":"223c7693b78843e1","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T18:13:59.992359Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T18:13:59.992433Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T18:13:59.992478Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"223c7693b78843e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.175:2379]}","request-path":"/0/members/223c7693b78843e1/attributes","cluster-id":"b697af90308fdc01","publish-timeout":"7s"} {"level":"info","ts":"2026-04-21T18:13:59.99259Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T18:13:59.992542Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T18:13:59.995135Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-21T18:13:59.995206Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-21T18:13:59.99653Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.175:2379"} {"level":"info","ts":"2026-04-21T18:13:59.997911Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-21T18:14:02.62944Z","caller":"traceutil/trace.go:171","msg":"trace[415637320] linearizableReadLoop","detail":"{readStateIndex:74; appliedIndex:73; }","duration":"175.676442ms","start":"2026-04-21T18:14:02.453733Z","end":"2026-04-21T18:14:02.62941Z","steps":["trace[415637320] 'read index received' (duration: 175.460726ms)","trace[415637320] 'applied index is now lower than readState.Index' (duration: 214.576µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:14:02.629502Z","caller":"traceutil/trace.go:171","msg":"trace[284792780] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"181.963103ms","start":"2026-04-21T18:14:02.447495Z","end":"2026-04-21T18:14:02.629458Z","steps":["trace[284792780] 'process raft request' (duration: 181.742326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:02.629713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.919398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-21T18:14:02.629702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.94044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-21T18:14:02.629773Z","caller":"traceutil/trace.go:171","msg":"trace[1884895531] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:70; }","duration":"176.097504ms","start":"2026-04-21T18:14:02.453663Z","end":"2026-04-21T18:14:02.629761Z","steps":["trace[1884895531] 'agreement among raft nodes before linearized reading' (duration: 176.014471ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:02.629793Z","caller":"traceutil/trace.go:171","msg":"trace[683914718] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:70; }","duration":"176.107434ms","start":"2026-04-21T18:14:02.453669Z","end":"2026-04-21T18:14:02.629776Z","steps":["trace[683914718] 'agreement among raft nodes before linearized reading' (duration: 175.928928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:02.630009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.970868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-21T18:14:02.630098Z","caller":"traceutil/trace.go:171","msg":"trace[577557735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:70; }","duration":"153.115722ms","start":"2026-04-21T18:14:02.47696Z","end":"2026-04-21T18:14:02.630075Z","steps":["trace[577557735] 'agreement among raft nodes before linearized reading' (duration: 152.920506ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:02.845604Z","caller":"traceutil/trace.go:171","msg":"trace[1320214488] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"130.222121ms","start":"2026-04-21T18:14:02.715339Z","end":"2026-04-21T18:14:02.845561Z","steps":["trace[1320214488] 'process raft request' (duration: 40.384342ms)","trace[1320214488] 'compare' (duration: 89.650234ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:14:05.392909Z","caller":"traceutil/trace.go:171","msg":"trace[720237907] linearizableReadLoop","detail":"{readStateIndex:233; appliedIndex:232; }","duration":"300.375075ms","start":"2026-04-21T18:14:05.092507Z","end":"2026-04-21T18:14:05.392882Z","steps":["trace[720237907] 'read index received' (duration: 300.273872ms)","trace[720237907] 'applied index is now lower than readState.Index' (duration: 100.103µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:14:05.392965Z","caller":"traceutil/trace.go:171","msg":"trace[1299835892] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"325.851968ms","start":"2026-04-21T18:14:05.067081Z","end":"2026-04-21T18:14:05.392933Z","steps":["trace[1299835892] 'process raft request' (duration: 325.684804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:05.393026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.505518ms","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-21T18:14:05.393056Z","caller":"traceutil/trace.go:171","msg":"trace[1019804315] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:227; }","duration":"300.586101ms","start":"2026-04-21T18:14:05.092459Z","end":"2026-04-21T18:14:05.393045Z","steps":["trace[1019804315] 'agreement among raft nodes before linearized reading' (duration: 300.52143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:05.39308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:05.092434Z","time spent":"300.640063ms","remote":"127.0.0.1:54676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-04-21T18:14:05.393445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:05.067066Z","time spent":"325.973922ms","remote":"127.0.0.1:54652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4003,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:14:07.549941Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.279919768s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-21T18:14:07.583684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.017652513s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:14:07.583817Z","caller":"traceutil/trace.go:171","msg":"trace[62671528] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:0; response_revision:234; }","duration":"2.017832838s","start":"2026-04-21T18:14:05.565961Z","end":"2026-04-21T18:14:07.583793Z","steps":["trace[62671528] 'range keys from in-memory index tree' (duration: 2.017569281s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:07.583869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:05.565943Z","time spent":"2.01791075s","remote":"127.0.0.1:54676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-04-21T18:14:07.584083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.69169082s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:14:07.584302Z","caller":"traceutil/trace.go:171","msg":"trace[635854637] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"2.01787968s","start":"2026-04-21T18:14:05.566382Z","end":"2026-04-21T18:14:07.584262Z","steps":["trace[635854637] 'process raft request' (duration: 325.687005ms)","trace[635854637] 'compare' (duration: 1.691256768s)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:14:07.584431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:05.566342Z","time spent":"2.018026244s","remote":"127.0.0.1:54850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":526,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:14:07.584807Z","caller":"traceutil/trace.go:171","msg":"trace[1110271104] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"1.314891076s","start":"2026-04-21T18:14:06.269905Z","end":"2026-04-21T18:14:07.584796Z","steps":["trace[1110271104] 'process raft request' (duration: 1.31432531s)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:07.584845Z","caller":"traceutil/trace.go:171","msg":"trace[765186755] linearizableReadLoop","detail":"{readStateIndex:241; appliedIndex:240; }","duration":"1.85997626s","start":"2026-04-21T18:14:05.724837Z","end":"2026-04-21T18:14:07.584814Z","steps":["trace[765186755] 'read index received' (duration: 167.217438ms)","trace[765186755] 'applied index is now lower than readState.Index' (duration: 1.692755442s)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:14:07.584861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:06.269877Z","time spent":"1.314956937s","remote":"127.0.0.1:54528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:14:07.585151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.86031922s","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-21T18:14:07.585183Z","caller":"traceutil/trace.go:171","msg":"trace[527708701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:237; }","duration":"1.860393641s","start":"2026-04-21T18:14:05.724778Z","end":"2026-04-21T18:14:07.585172Z","steps":["trace[527708701] 'agreement among raft nodes before linearized reading' (duration: 1.860224407s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:07.585207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:05.724748Z","time spent":"1.860452535s","remote":"127.0.0.1:54740","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-21T18:14:07.584347Z","caller":"traceutil/trace.go:171","msg":"trace[863706486] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"171.153071ms","start":"2026-04-21T18:14:07.413177Z","end":"2026-04-21T18:14:07.58433Z","steps":["trace[863706486] 'process raft request' (duration: 171.11482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:07.585205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.466894494s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:14:07.585318Z","caller":"traceutil/trace.go:171","msg":"trace[1976320508] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:237; }","duration":"1.46705661s","start":"2026-04-21T18:14:06.11825Z","end":"2026-04-21T18:14:07.585307Z","steps":["trace[1976320508] 'agreement among raft nodes before linearized reading' (duration: 1.466911375s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:07.585352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:06.118222Z","time spent":"1.46711687s","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-21T18:14:07.585423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.601236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"warn","ts":"2026-04-21T18:14:07.585468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.949404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-21T18:14:07.585477Z","caller":"traceutil/trace.go:171","msg":"trace[72278938] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:237; }","duration":"178.667108ms","start":"2026-04-21T18:14:07.406783Z","end":"2026-04-21T18:14:07.58545Z","steps":["trace[72278938] 'agreement among raft nodes before linearized reading' (duration: 178.446411ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:07.58549Z","caller":"traceutil/trace.go:171","msg":"trace[1312236145] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:237; }","duration":"500.000606ms","start":"2026-04-21T18:14:07.085483Z","end":"2026-04-21T18:14:07.585483Z","steps":["trace[1312236145] 'agreement among raft nodes before linearized reading' (duration: 499.950065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:07.585514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:07.085462Z","time spent":"500.045177ms","remote":"127.0.0.1:54740","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-21T18:14:07.585746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16860962s","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-21T18:14:07.585815Z","caller":"traceutil/trace.go:171","msg":"trace[128420496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:237; }","duration":"1.168864687s","start":"2026-04-21T18:14:06.416934Z","end":"2026-04-21T18:14:07.585799Z","steps":["trace[128420496] 'agreement among raft nodes before linearized reading' (duration: 1.16860144s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:07.585856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:06.416913Z","time spent":"1.16893245s","remote":"127.0.0.1:54740","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-21T18:14:08.331387Z","caller":"traceutil/trace.go:171","msg":"trace[215564782] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"679.534428ms","start":"2026-04-21T18:14:07.651825Z","end":"2026-04-21T18:14:08.331359Z","steps":["trace[215564782] 'process raft request' (duration: 662.022043ms)","trace[215564782] 'compare' (duration: 16.870347ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:14:08.331549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:07.651818Z","time spent":"679.641581ms","remote":"127.0.0.1:54528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:14:08.722671Z","caller":"traceutil/trace.go:171","msg":"trace[1124823956] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"105.154519ms","start":"2026-04-21T18:14:08.617481Z","end":"2026-04-21T18:14:08.722636Z","steps":["trace[1124823956] 'process raft request' (duration: 104.966803ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:08.827313Z","caller":"traceutil/trace.go:171","msg":"trace[1128417353] linearizableReadLoop","detail":"{readStateIndex:308; appliedIndex:306; }","duration":"135.205786ms","start":"2026-04-21T18:14:08.692067Z","end":"2026-04-21T18:14:08.827273Z","steps":["trace[1128417353] 'read index received' (duration: 30.381695ms)","trace[1128417353] 'applied index is now lower than readState.Index' (duration: 104.822901ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:14:08.827343Z","caller":"traceutil/trace.go:171","msg":"trace[318692501] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"185.056791ms","start":"2026-04-21T18:14:08.642259Z","end":"2026-04-21T18:14:08.827316Z","steps":["trace[318692501] 'process raft request' (duration: 184.490555ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:08.827389Z","caller":"traceutil/trace.go:171","msg":"trace[1507008134] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"131.710395ms","start":"2026-04-21T18:14:08.695656Z","end":"2026-04-21T18:14:08.827366Z","steps":["trace[1507008134] 'process raft request' (duration: 131.54924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:08.827504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.424462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-21T18:14:08.827602Z","caller":"traceutil/trace.go:171","msg":"trace[1977440898] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:303; }","duration":"135.505864ms","start":"2026-04-21T18:14:08.692024Z","end":"2026-04-21T18:14:08.82753Z","steps":["trace[1977440898] 'agreement among raft nodes before linearized reading' (duration: 135.389471ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:08.858869Z","caller":"traceutil/trace.go:171","msg":"trace[1769789431] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"132.942011ms","start":"2026-04-21T18:14:08.725884Z","end":"2026-04-21T18:14:08.858826Z","steps":["trace[1769789431] 'process raft request' (duration: 132.703625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:08.859301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.755009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:14:08.859371Z","caller":"traceutil/trace.go:171","msg":"trace[1305240284] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:307; }","duration":"163.852381ms","start":"2026-04-21T18:14:08.695501Z","end":"2026-04-21T18:14:08.859354Z","steps":["trace[1305240284] 'agreement among raft nodes before linearized reading' (duration: 163.714617ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:09.64235Z","caller":"traceutil/trace.go:171","msg":"trace[1343021924] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"106.037284ms","start":"2026-04-21T18:14:09.536229Z","end":"2026-04-21T18:14:09.642266Z","steps":["trace[1343021924] 'process raft request' (duration: 105.630673ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:09.799156Z","caller":"traceutil/trace.go:171","msg":"trace[997335825] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"151.246657ms","start":"2026-04-21T18:14:09.647881Z","end":"2026-04-21T18:14:09.799127Z","steps":["trace[997335825] 'process raft request' (duration: 94.630146ms)","trace[997335825] 'compare' (duration: 56.474417ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:14:10.084818Z","caller":"traceutil/trace.go:171","msg":"trace[1662284637] linearizableReadLoop","detail":"{readStateIndex:342; appliedIndex:338; }","duration":"424.807079ms","start":"2026-04-21T18:14:09.659984Z","end":"2026-04-21T18:14:10.084791Z","steps":["trace[1662284637] 'read index received' (duration: 82.710543ms)","trace[1662284637] 'applied index is now lower than readState.Index' (duration: 342.095106ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:14:10.084901Z","caller":"traceutil/trace.go:171","msg":"trace[1276551271] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"435.289382ms","start":"2026-04-21T18:14:09.649588Z","end":"2026-04-21T18:14:10.084878Z","steps":["trace[1276551271] 'process raft request' (duration: 435.000854ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:10.085002Z","caller":"traceutil/trace.go:171","msg":"trace[558149305] transaction","detail":"{read_only:false; number_of_response:0; response_revision:333; }","duration":"356.81537ms","start":"2026-04-21T18:14:09.728177Z","end":"2026-04-21T18:14:10.084992Z","steps":["trace[558149305] 'process raft request' (duration: 356.45678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:10.085073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:09.649584Z","time spent":"435.392865ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:14:10.085094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:09.728141Z","time spent":"356.898613ms","remote":"127.0.0.1:54662","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:14:10.085172Z","caller":"traceutil/trace.go:171","msg":"trace[559622409] transaction","detail":"{read_only:false; number_of_response:0; response_revision:333; }","duration":"356.790659ms","start":"2026-04-21T18:14:09.728368Z","end":"2026-04-21T18:14:10.085159Z","steps":["trace[559622409] 'process raft request' (duration: 356.323246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:10.085928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:09.728336Z","time spent":"357.551471ms","remote":"127.0.0.1:54662","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:14:10.085185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.197071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-21T18:14:10.086049Z","caller":"traceutil/trace.go:171","msg":"trace[1274998683] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:333; }","duration":"426.083585ms","start":"2026-04-21T18:14:09.659955Z","end":"2026-04-21T18:14:10.086038Z","steps":["trace[1274998683] 'agreement among raft nodes before linearized reading' (duration: 425.165719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:10.086076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:09.659937Z","time spent":"426.129828ms","remote":"127.0.0.1:54676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-04-21T18:14:10.085231Z","caller":"traceutil/trace.go:171","msg":"trace[818713063] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"435.712062ms","start":"2026-04-21T18:14:09.649506Z","end":"2026-04-21T18:14:10.085218Z","steps":["trace[818713063] 'process raft request' (duration: 378.303798ms)","trace[818713063] 'compare' (duration: 56.587421ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:14:10.086197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:09.649485Z","time spent":"436.685162ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:14:10.085243Z","caller":"traceutil/trace.go:171","msg":"trace[313502538] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"435.690514ms","start":"2026-04-21T18:14:09.649541Z","end":"2026-04-21T18:14:10.085232Z","steps":["trace[313502538] 'process raft request' (duration: 435.004483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:10.086327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:09.649519Z","time spent":"436.751553ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:14:10.085312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.096927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-21T18:14:10.085335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.295531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-04-21T18:14:10.086516Z","caller":"traceutil/trace.go:171","msg":"trace[440311402] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:333; }","duration":"351.315242ms","start":"2026-04-21T18:14:09.735181Z","end":"2026-04-21T18:14:10.086496Z","steps":["trace[440311402] 'agreement among raft nodes before linearized reading' (duration: 350.103138ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:10.086561Z","caller":"traceutil/trace.go:171","msg":"trace[976192914] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:333; }","duration":"251.561377ms","start":"2026-04-21T18:14:09.834988Z","end":"2026-04-21T18:14:10.086549Z","steps":["trace[976192914] 'agreement among raft nodes before linearized reading' (duration: 250.27619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:10.086578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:09.735168Z","time spent":"351.393455ms","remote":"127.0.0.1:54676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-04-21T18:14:10.085396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.789888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-04-21T18:14:10.085408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.542229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-21T18:14:10.08682Z","caller":"traceutil/trace.go:171","msg":"trace[416474754] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:333; }","duration":"377.987861ms","start":"2026-04-21T18:14:09.708822Z","end":"2026-04-21T18:14:10.086809Z","steps":["trace[416474754] 'agreement among raft nodes before linearized reading' (duration: 376.447276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:10.085469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.328581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-04-21T18:14:10.086935Z","caller":"traceutil/trace.go:171","msg":"trace[1072286290] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:333; }","duration":"101.804603ms","start":"2026-04-21T18:14:09.985108Z","end":"2026-04-21T18:14:10.086913Z","steps":["trace[1072286290] 'agreement among raft nodes before linearized reading' (duration: 100.342271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:10.085499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.94711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-04-21T18:14:10.085484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.98131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"warn","ts":"2026-04-21T18:14:10.085525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.826546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-04-21T18:14:10.085513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.042757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-04-21T18:14:10.085166Z","caller":"traceutil/trace.go:171","msg":"trace[1874390380] transaction","detail":"{read_only:false; number_of_response:0; response_revision:333; }","duration":"356.650746ms","start":"2026-04-21T18:14:09.72849Z","end":"2026-04-21T18:14:10.08514Z","steps":["trace[1874390380] 'process raft request' (duration: 356.272684ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:10.086709Z","caller":"traceutil/trace.go:171","msg":"trace[1826035156] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:333; }","duration":"301.123176ms","start":"2026-04-21T18:14:09.785566Z","end":"2026-04-21T18:14:10.08669Z","steps":["trace[1826035156] 'agreement among raft nodes before linearized reading' (duration: 299.765167ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:10.087007Z","caller":"traceutil/trace.go:171","msg":"trace[1034887875] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:333; }","duration":"371.523655ms","start":"2026-04-21T18:14:09.715471Z","end":"2026-04-21T18:14:10.086995Z","steps":["trace[1034887875] 'agreement among raft nodes before linearized reading' (duration: 369.986581ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:10.086693Z","caller":"traceutil/trace.go:171","msg":"trace[583223239] transaction","detail":"{read_only:false; number_of_response:0; response_revision:333; }","duration":"358.218021ms","start":"2026-04-21T18:14:09.72846Z","end":"2026-04-21T18:14:10.086678Z","steps":["trace[583223239] 'process raft request' (duration: 356.263245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:10.087254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:09.715459Z","time spent":"371.782482ms","remote":"127.0.0.1:54676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-04-21T18:14:10.087016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:09.785547Z","time spent":"301.451575ms","remote":"127.0.0.1:54676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"info","ts":"2026-04-21T18:14:10.087045Z","caller":"traceutil/trace.go:171","msg":"trace[1052768922] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:333; }","duration":"201.384262ms","start":"2026-04-21T18:14:09.885652Z","end":"2026-04-21T18:14:10.087036Z","steps":["trace[1052768922] 'agreement among raft nodes before linearized reading' (duration: 199.856798ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:10.087056Z","caller":"traceutil/trace.go:171","msg":"trace[1648194691] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:333; }","duration":"358.624952ms","start":"2026-04-21T18:14:09.728424Z","end":"2026-04-21T18:14:10.087049Z","steps":["trace[1648194691] 'agreement among raft nodes before linearized reading' (duration: 357.028737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:10.087537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:09.728419Z","time spent":"359.034734ms","remote":"127.0.0.1:54662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2943,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-04-21T18:14:10.087095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:09.728482Z","time spent":"358.582462ms","remote":"127.0.0.1:54662","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:14:10.0876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:09.72844Z","time spent":"358.818077ms","remote":"127.0.0.1:54662","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:14:10.086899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:09.708758Z","time spent":"378.126914ms","remote":"127.0.0.1:54676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"info","ts":"2026-04-21T18:14:10.087164Z","caller":"traceutil/trace.go:171","msg":"trace[683305278] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:333; }","duration":"151.602007ms","start":"2026-04-21T18:14:09.935548Z","end":"2026-04-21T18:14:10.08715Z","steps":["trace[683305278] 'agreement among raft nodes before linearized reading' (duration: 149.93617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:10.396353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.494263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:14:10.396504Z","caller":"traceutil/trace.go:171","msg":"trace[1922277533] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"301.237139ms","start":"2026-04-21T18:14:10.095218Z","end":"2026-04-21T18:14:10.396455Z","steps":["trace[1922277533] 'process raft request' (duration: 189.557551ms)","trace[1922277533] 'compare' (duration: 111.323448ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:14:10.396501Z","caller":"traceutil/trace.go:171","msg":"trace[1716678649] linearizableReadLoop","detail":"{readStateIndex:347; appliedIndex:346; }","duration":"261.274388ms","start":"2026-04-21T18:14:10.135169Z","end":"2026-04-21T18:14:10.396444Z","steps":["trace[1716678649] 'read index received' (duration: 149.57345ms)","trace[1716678649] 'applied index is now lower than readState.Index' (duration: 111.699238ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:14:10.396602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.431773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-21T18:14:10.396654Z","caller":"traceutil/trace.go:171","msg":"trace[1072987906] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:334; }","duration":"261.511505ms","start":"2026-04-21T18:14:10.135133Z","end":"2026-04-21T18:14:10.396644Z","steps":["trace[1072987906] 'agreement among raft nodes before linearized reading' (duration: 261.423032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:10.396664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.969809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-21T18:14:10.396716Z","caller":"traceutil/trace.go:171","msg":"trace[202147282] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:334; }","duration":"210.022331ms","start":"2026-04-21T18:14:10.18668Z","end":"2026-04-21T18:14:10.396702Z","steps":["trace[202147282] 'agreement among raft nodes before linearized reading' (duration: 209.916088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:10.396833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:10.095206Z","time spent":"301.328181ms","remote":"127.0.0.1:54662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:14:10.396905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.794941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-04-21T18:14:10.396955Z","caller":"traceutil/trace.go:171","msg":"trace[570294878] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:334; }","duration":"110.881945ms","start":"2026-04-21T18:14:10.286061Z","end":"2026-04-21T18:14:10.396943Z","steps":["trace[570294878] 'agreement among raft nodes before linearized reading' (duration: 110.812813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:10.397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.999497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-21T18:14:10.397043Z","caller":"traceutil/trace.go:171","msg":"trace[1001856909] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:334; }","duration":"162.065289ms","start":"2026-04-21T18:14:10.234968Z","end":"2026-04-21T18:14:10.397034Z","steps":["trace[1001856909] 'agreement among raft nodes before linearized reading' (duration: 161.996277ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:10.643925Z","caller":"traceutil/trace.go:171","msg":"trace[1784972884] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"178.307337ms","start":"2026-04-21T18:14:10.465577Z","end":"2026-04-21T18:14:10.643884Z","steps":["trace[1784972884] 'process raft request' (duration: 123.597291ms)","trace[1784972884] 'compare' (duration: 54.514941ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:14:10.644038Z","caller":"traceutil/trace.go:171","msg":"trace[1234441666] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"177.938556ms","start":"2026-04-21T18:14:10.46608Z","end":"2026-04-21T18:14:10.644019Z","steps":["trace[1234441666] 'process raft request' (duration: 177.71519ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:10.644119Z","caller":"traceutil/trace.go:171","msg":"trace[450569684] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"145.05939ms","start":"2026-04-21T18:14:10.499042Z","end":"2026-04-21T18:14:10.644101Z","steps":["trace[450569684] 'process raft request' (duration: 145.028109ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:10.644418Z","caller":"traceutil/trace.go:171","msg":"trace[498867630] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"151.150326ms","start":"2026-04-21T18:14:10.493259Z","end":"2026-04-21T18:14:10.644409Z","steps":["trace[498867630] 'process raft request' (duration: 150.745184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:10.644619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.832216ms","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-21T18:14:10.644644Z","caller":"traceutil/trace.go:171","msg":"trace[1943684400] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:346; }","duration":"158.900039ms","start":"2026-04-21T18:14:10.485737Z","end":"2026-04-21T18:14:10.644637Z","steps":["trace[1943684400] 'agreement among raft nodes before linearized reading' (duration: 158.824707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:10.644783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.088277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"info","ts":"2026-04-21T18:14:10.644801Z","caller":"traceutil/trace.go:171","msg":"trace[1346624492] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:346; }","duration":"182.142367ms","start":"2026-04-21T18:14:10.462654Z","end":"2026-04-21T18:14:10.644797Z","steps":["trace[1346624492] 'agreement among raft nodes before linearized reading' (duration: 182.107587ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:10.64831Z","caller":"traceutil/trace.go:171","msg":"trace[570639165] linearizableReadLoop","detail":"{readStateIndex:357; appliedIndex:355; }","duration":"154.878083ms","start":"2026-04-21T18:14:10.489586Z","end":"2026-04-21T18:14:10.644464Z","steps":["trace[570639165] 'read index received' (duration: 99.500797ms)","trace[570639165] 'applied index is now lower than readState.Index' (duration: 55.376016ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:14:10.650855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.154419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-04-21T18:14:10.650916Z","caller":"traceutil/trace.go:171","msg":"trace[1520112090] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:347; }","duration":"114.261742ms","start":"2026-04-21T18:14:10.536638Z","end":"2026-04-21T18:14:10.6509Z","steps":["trace[1520112090] 'agreement among raft nodes before linearized reading' (duration: 114.144219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:24.462178Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4891364054905720024,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T18:14:24.579283Z","caller":"traceutil/trace.go:171","msg":"trace[1542755837] linearizableReadLoop","detail":"{readStateIndex:473; appliedIndex:472; }","duration":"617.875433ms","start":"2026-04-21T18:14:23.961381Z","end":"2026-04-21T18:14:24.579256Z","steps":["trace[1542755837] 'read index received' (duration: 617.632865ms)","trace[1542755837] 'applied index is now lower than readState.Index' (duration: 241.678µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:14:24.579346Z","caller":"traceutil/trace.go:171","msg":"trace[1664456827] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"689.605589ms","start":"2026-04-21T18:14:23.889701Z","end":"2026-04-21T18:14:24.579307Z","steps":["trace[1664456827] 'process raft request' (duration: 689.419673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:24.579471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.074257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.175\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-21T18:14:24.579605Z","caller":"traceutil/trace.go:171","msg":"trace[1736686385] range","detail":"{range_begin:/registry/masterleases/199.19.213.175; range_end:; response_count:1; response_revision:457; }","duration":"618.224911ms","start":"2026-04-21T18:14:23.961321Z","end":"2026-04-21T18:14:24.579546Z","steps":["trace[1736686385] 'agreement among raft nodes before linearized reading' (duration: 618.064997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:24.579541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:23.889674Z","time spent":"689.749473ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:14:24.579624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.85679ms","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-21T18:14:24.579648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.67271ms","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-21T18:14:24.579679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:23.961299Z","time spent":"618.357296ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.175\" "} {"level":"info","ts":"2026-04-21T18:14:24.579695Z","caller":"traceutil/trace.go:171","msg":"trace[394079131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:457; }","duration":"383.982293ms","start":"2026-04-21T18:14:24.195696Z","end":"2026-04-21T18:14:24.579679Z","steps":["trace[394079131] 'agreement among raft nodes before linearized reading' (duration: 383.817439ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:24.57971Z","caller":"traceutil/trace.go:171","msg":"trace[1154766502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:457; }","duration":"370.773923ms","start":"2026-04-21T18:14:24.20892Z","end":"2026-04-21T18:14:24.579694Z","steps":["trace[1154766502] 'agreement among raft nodes before linearized reading' (duration: 370.6576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:24.579756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:24.208898Z","time spent":"370.844824ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-21T18:14:24.579744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.258068ms","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-21T18:14:24.579756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:24.195676Z","time spent":"384.067675ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-21T18:14:24.5799Z","caller":"traceutil/trace.go:171","msg":"trace[1860419534] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:457; }","duration":"371.446842ms","start":"2026-04-21T18:14:24.208427Z","end":"2026-04-21T18:14:24.579874Z","steps":["trace[1860419534] 'agreement among raft nodes before linearized reading' (duration: 371.198345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:24.580307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:24.208416Z","time spent":"371.874115ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-21T18:14:24.708621Z","caller":"traceutil/trace.go:171","msg":"trace[787491823] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"124.258199ms","start":"2026-04-21T18:14:24.584317Z","end":"2026-04-21T18:14:24.708575Z","steps":["trace[787491823] 'process raft request' (duration: 124.098845ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:24.708788Z","caller":"traceutil/trace.go:171","msg":"trace[284605996] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"124.418485ms","start":"2026-04-21T18:14:24.584359Z","end":"2026-04-21T18:14:24.708778Z","steps":["trace[284605996] 'process raft request' (duration: 124.179348ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:24.709025Z","caller":"traceutil/trace.go:171","msg":"trace[484016472] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"124.58973ms","start":"2026-04-21T18:14:24.584401Z","end":"2026-04-21T18:14:24.708991Z","steps":["trace[484016472] 'process raft request' (duration: 124.339403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:35.254498Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4891364054905720156,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T18:14:35.674315Z","caller":"traceutil/trace.go:171","msg":"trace[271916165] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"986.012677ms","start":"2026-04-21T18:14:34.688282Z","end":"2026-04-21T18:14:35.674294Z","steps":["trace[271916165] 'process raft request' (duration: 985.905574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:35.674434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:34.688257Z","time spent":"986.118971ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:14:35.696476Z","caller":"traceutil/trace.go:171","msg":"trace[1721074767] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"996.22097ms","start":"2026-04-21T18:14:34.700172Z","end":"2026-04-21T18:14:35.696393Z","steps":["trace[1721074767] 'process raft request' (duration: 996.063806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:35.696742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:34.700145Z","time spent":"996.483398ms","remote":"127.0.0.1:54502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:14:35.696885Z","caller":"traceutil/trace.go:171","msg":"trace[780486600] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:535; }","duration":"942.685423ms","start":"2026-04-21T18:14:34.75414Z","end":"2026-04-21T18:14:35.696825Z","steps":["trace[780486600] 'read index received' (duration: 920.220256ms)","trace[780486600] 'applied index is now lower than readState.Index' (duration: 22.464216ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:14:35.696999Z","caller":"traceutil/trace.go:171","msg":"trace[359523810] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"994.292913ms","start":"2026-04-21T18:14:34.702694Z","end":"2026-04-21T18:14:35.696986Z","steps":["trace[359523810] 'process raft request' (duration: 993.600663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:35.69709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:34.702657Z","time spent":"994.368665ms","remote":"127.0.0.1:54502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:14:35.697133Z","caller":"traceutil/trace.go:171","msg":"trace[846272028] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"949.470934ms","start":"2026-04-21T18:14:34.747631Z","end":"2026-04-21T18:14:35.697102Z","steps":["trace[846272028] 'process raft request' (duration: 948.917587ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:35.697209Z","caller":"traceutil/trace.go:171","msg":"trace[1020095441] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"938.231761ms","start":"2026-04-21T18:14:34.758968Z","end":"2026-04-21T18:14:35.6972Z","steps":["trace[1020095441] 'process raft request' (duration: 937.624202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:35.697251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:34.758958Z","time spent":"938.272272ms","remote":"127.0.0.1:54502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:14:35.69726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:34.747618Z","time spent":"949.582587ms","remote":"127.0.0.1:54502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:14:35.697319Z","caller":"traceutil/trace.go:171","msg":"trace[45172766] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"919.529626ms","start":"2026-04-21T18:14:34.777782Z","end":"2026-04-21T18:14:35.697312Z","steps":["trace[45172766] 'process raft request' (duration: 918.863906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:35.697354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:34.777765Z","time spent":"919.573446ms","remote":"127.0.0.1:54502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:14:35.697379Z","caller":"traceutil/trace.go:171","msg":"trace[604792632] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"973.222978ms","start":"2026-04-21T18:14:34.724145Z","end":"2026-04-21T18:14:35.697368Z","steps":["trace[604792632] 'process raft request' (duration: 972.187507ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:35.697418Z","caller":"traceutil/trace.go:171","msg":"trace[2126579848] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"837.719821ms","start":"2026-04-21T18:14:34.85969Z","end":"2026-04-21T18:14:35.69741Z","steps":["trace[2126579848] 'process raft request' (duration: 837.02048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:35.697428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:34.724113Z","time spent":"973.289031ms","remote":"127.0.0.1:54502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:14:35.697453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:34.859647Z","time spent":"837.789422ms","remote":"127.0.0.1:54502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:14:35.69751Z","caller":"traceutil/trace.go:171","msg":"trace[549068496] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.005371912s","start":"2026-04-21T18:14:34.692117Z","end":"2026-04-21T18:14:35.697488Z","steps":["trace[549068496] 'process raft request' (duration: 1.003949399s)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:35.697566Z","caller":"traceutil/trace.go:171","msg":"trace[371671178] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"962.058327ms","start":"2026-04-21T18:14:34.735498Z","end":"2026-04-21T18:14:35.697556Z","steps":["trace[371671178] 'process raft request' (duration: 960.887162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:35.697581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.446745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-21T18:14:35.697611Z","caller":"traceutil/trace.go:171","msg":"trace[310928039] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:528; }","duration":"943.515277ms","start":"2026-04-21T18:14:34.754087Z","end":"2026-04-21T18:14:35.697602Z","steps":["trace[310928039] 'agreement among raft nodes before linearized reading' (duration: 943.422044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:35.69761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:34.735473Z","time spent":"962.11705ms","remote":"127.0.0.1:54502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:14:35.697637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:34.754037Z","time spent":"943.593779ms","remote":"127.0.0.1:54740","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-21T18:14:35.697654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:34.692099Z","time spent":"1.005485945s","remote":"127.0.0.1:54502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:14:35.697741Z","caller":"traceutil/trace.go:171","msg":"trace[1839777902] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"961.112769ms","start":"2026-04-21T18:14:34.736618Z","end":"2026-04-21T18:14:35.697731Z","steps":["trace[1839777902] 'process raft request' (duration: 959.803721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:35.697849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:34.7366Z","time spent":"961.165031ms","remote":"127.0.0.1:54502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:14:35.697879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.389425ms","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-21T18:14:35.697908Z","caller":"traceutil/trace.go:171","msg":"trace[1469458035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:528; }","duration":"940.441046ms","start":"2026-04-21T18:14:34.757459Z","end":"2026-04-21T18:14:35.6979Z","steps":["trace[1469458035] 'agreement among raft nodes before linearized reading' (duration: 940.387135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:35.697934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:34.75745Z","time spent":"940.478507ms","remote":"127.0.0.1:54740","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-21T18:14:35.697934Z","caller":"traceutil/trace.go:171","msg":"trace[494875100] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"957.576545ms","start":"2026-04-21T18:14:34.740348Z","end":"2026-04-21T18:14:35.697925Z","steps":["trace[494875100] 'process raft request' (duration: 956.118522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:35.697972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:34.740341Z","time spent":"957.614126ms","remote":"127.0.0.1:54502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:14:35.69805Z","caller":"traceutil/trace.go:171","msg":"trace[1883715868] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"950.546236ms","start":"2026-04-21T18:14:34.747497Z","end":"2026-04-21T18:14:35.698043Z","steps":["trace[1883715868] 'process raft request' (duration: 949.003909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:35.698089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.858871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:14:35.698118Z","caller":"traceutil/trace.go:171","msg":"trace[523603580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:528; }","duration":"212.917183ms","start":"2026-04-21T18:14:35.485193Z","end":"2026-04-21T18:14:35.69811Z","steps":["trace[523603580] 'agreement among raft nodes before linearized reading' (duration: 212.876102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:35.698245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.908431ms","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-21T18:14:35.698274Z","caller":"traceutil/trace.go:171","msg":"trace[1471889671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:528; }","duration":"939.947972ms","start":"2026-04-21T18:14:34.758318Z","end":"2026-04-21T18:14:35.698266Z","steps":["trace[1471889671] 'agreement among raft nodes before linearized reading' (duration: 939.89952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:35.698299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:34.758312Z","time spent":"939.980833ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T18:14:35.698088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:34.747477Z","time spent":"950.591947ms","remote":"127.0.0.1:54502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:14:35.941957Z","caller":"traceutil/trace.go:171","msg":"trace[363195902] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"106.504327ms","start":"2026-04-21T18:14:35.835432Z","end":"2026-04-21T18:14:35.941937Z","steps":["trace[363195902] 'process raft request' (duration: 88.154514ms)","trace[363195902] 'compare' (duration: 17.944032ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:14:35.942079Z","caller":"traceutil/trace.go:171","msg":"trace[905210177] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"103.266892ms","start":"2026-04-21T18:14:35.838805Z","end":"2026-04-21T18:14:35.942072Z","steps":["trace[905210177] 'process raft request' (duration: 102.86206ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:36.138956Z","caller":"traceutil/trace.go:171","msg":"trace[1168598090] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"100.474409ms","start":"2026-04-21T18:14:36.038451Z","end":"2026-04-21T18:14:36.138925Z","steps":["trace[1168598090] 'process raft request' (duration: 19.549279ms)","trace[1168598090] 'compare' (duration: 80.800276ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:14:36.853097Z","caller":"traceutil/trace.go:171","msg":"trace[2012362639] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"273.298274ms","start":"2026-04-21T18:14:36.579748Z","end":"2026-04-21T18:14:36.853047Z","steps":["trace[2012362639] 'process raft request' (duration: 201.963359ms)","trace[2012362639] 'compare' (duration: 71.059107ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:14:36.853341Z","caller":"traceutil/trace.go:171","msg":"trace[675265749] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"268.412529ms","start":"2026-04-21T18:14:36.58489Z","end":"2026-04-21T18:14:36.853303Z","steps":["trace[675265749] 'process raft request' (duration: 268.12585ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:36.856557Z","caller":"traceutil/trace.go:171","msg":"trace[979679180] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"198.425182ms","start":"2026-04-21T18:14:36.658112Z","end":"2026-04-21T18:14:36.856537Z","steps":["trace[979679180] 'process raft request' (duration: 198.255827ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:37.061104Z","caller":"traceutil/trace.go:171","msg":"trace[1277264343] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"182.887203ms","start":"2026-04-21T18:14:36.878193Z","end":"2026-04-21T18:14:37.061081Z","steps":["trace[1277264343] 'process raft request' (duration: 121.998077ms)","trace[1277264343] 'compare' (duration: 60.783642ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:14:37.062048Z","caller":"traceutil/trace.go:171","msg":"trace[265531935] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"124.612865ms","start":"2026-04-21T18:14:36.937417Z","end":"2026-04-21T18:14:37.06203Z","steps":["trace[265531935] 'process raft request' (duration: 124.567664ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:37.062292Z","caller":"traceutil/trace.go:171","msg":"trace[652092036] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"181.339697ms","start":"2026-04-21T18:14:36.88094Z","end":"2026-04-21T18:14:37.06228Z","steps":["trace[652092036] 'process raft request' (duration: 180.933086ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:37.548021Z","caller":"traceutil/trace.go:171","msg":"trace[1611142209] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"434.598628ms","start":"2026-04-21T18:14:37.113407Z","end":"2026-04-21T18:14:37.548006Z","steps":["trace[1611142209] 'process raft request' (duration: 425.356523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:37.548124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:37.113382Z","time spent":"434.684379ms","remote":"127.0.0.1:54502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74754,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:14:37.552805Z","caller":"traceutil/trace.go:171","msg":"trace[1660518177] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"252.427315ms","start":"2026-04-21T18:14:37.300344Z","end":"2026-04-21T18:14:37.552771Z","steps":["trace[1660518177] 'process raft request' (duration: 248.386024ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:37.55757Z","caller":"traceutil/trace.go:171","msg":"trace[1374497828] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"164.793676ms","start":"2026-04-21T18:14:37.392733Z","end":"2026-04-21T18:14:37.557527Z","steps":["trace[1374497828] 'process raft request' (duration: 160.611082ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:38.160541Z","caller":"traceutil/trace.go:171","msg":"trace[305539730] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"125.518721ms","start":"2026-04-21T18:14:38.035001Z","end":"2026-04-21T18:14:38.16052Z","steps":["trace[305539730] 'read index received' (duration: 100.84779ms)","trace[305539730] 'applied index is now lower than readState.Index' (duration: 24.670171ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:14:38.160615Z","caller":"traceutil/trace.go:171","msg":"trace[1378026221] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"194.995432ms","start":"2026-04-21T18:14:37.965538Z","end":"2026-04-21T18:14:38.160534Z","steps":["trace[1378026221] 'process raft request' (duration: 170.405773ms)","trace[1378026221] 'compare' (duration: 24.422514ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:14:38.160835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.810111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:15688"} {"level":"info","ts":"2026-04-21T18:14:38.160915Z","caller":"traceutil/trace.go:171","msg":"trace[494578612] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:598; }","duration":"125.910984ms","start":"2026-04-21T18:14:38.034986Z","end":"2026-04-21T18:14:38.160897Z","steps":["trace[494578612] 'agreement among raft nodes before linearized reading' (duration: 125.659646ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:39.953938Z","caller":"traceutil/trace.go:171","msg":"trace[2129630338] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"136.835537ms","start":"2026-04-21T18:14:39.817045Z","end":"2026-04-21T18:14:39.95388Z","steps":["trace[2129630338] 'process raft request' (duration: 125.76946ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:39.958965Z","caller":"traceutil/trace.go:171","msg":"trace[1631282630] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"124.978895ms","start":"2026-04-21T18:14:39.833958Z","end":"2026-04-21T18:14:39.958937Z","steps":["trace[1631282630] 'process raft request' (duration: 124.750429ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:40.601388Z","caller":"traceutil/trace.go:171","msg":"trace[922773795] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"117.965888ms","start":"2026-04-21T18:14:40.483397Z","end":"2026-04-21T18:14:40.601363Z","steps":["trace[922773795] 'read index received' (duration: 116.420862ms)","trace[922773795] 'applied index is now lower than readState.Index' (duration: 1.544156ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:14:40.603671Z","caller":"traceutil/trace.go:171","msg":"trace[261759544] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"177.431671ms","start":"2026-04-21T18:14:40.426227Z","end":"2026-04-21T18:14:40.603658Z","steps":["trace[261759544] 'process raft request' (duration: 173.873456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:40.603906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.491853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:14:40.603946Z","caller":"traceutil/trace.go:171","msg":"trace[715726172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"120.570274ms","start":"2026-04-21T18:14:40.483365Z","end":"2026-04-21T18:14:40.603935Z","steps":["trace[715726172] 'agreement among raft nodes before linearized reading' (duration: 120.488132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:44.083957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.880354ms","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-21T18:14:44.084044Z","caller":"traceutil/trace.go:171","msg":"trace[772326802] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:627; }","duration":"353.042128ms","start":"2026-04-21T18:14:43.730985Z","end":"2026-04-21T18:14:44.084027Z","steps":["trace[772326802] 'range keys from in-memory index tree' (duration: 352.77018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:44.084077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:43.730964Z","time spent":"353.10609ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-21T18:14:44.08404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.131752ms","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-21T18:14:44.084194Z","caller":"traceutil/trace.go:171","msg":"trace[3540898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"322.309947ms","start":"2026-04-21T18:14:43.761811Z","end":"2026-04-21T18:14:44.084121Z","steps":["trace[3540898] 'range keys from in-memory index tree' (duration: 321.985968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:44.084263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:43.761788Z","time spent":"322.460782ms","remote":"127.0.0.1:54740","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-21T18:14:44.1865Z","caller":"traceutil/trace.go:171","msg":"trace[1457240131] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"374.967279ms","start":"2026-04-21T18:14:43.811509Z","end":"2026-04-21T18:14:44.186476Z","steps":["trace[1457240131] 'read index received' (duration: 374.213137ms)","trace[1457240131] 'applied index is now lower than readState.Index' (duration: 752.682µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:14:44.186712Z","caller":"traceutil/trace.go:171","msg":"trace[258019164] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"382.571203ms","start":"2026-04-21T18:14:43.804004Z","end":"2026-04-21T18:14:44.186575Z","steps":["trace[258019164] 'process raft request' (duration: 381.838201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:44.186751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.219166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fvktp\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-21T18:14:44.186834Z","caller":"traceutil/trace.go:171","msg":"trace[2065261977] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fvktp; range_end:; response_count:1; response_revision:628; }","duration":"375.36949ms","start":"2026-04-21T18:14:43.811443Z","end":"2026-04-21T18:14:44.186812Z","steps":["trace[2065261977] 'agreement among raft nodes before linearized reading' (duration: 375.153494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:44.186888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:43.811427Z","time spent":"375.452965ms","remote":"127.0.0.1:54662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-fvktp\" "} {"level":"warn","ts":"2026-04-21T18:14:44.18689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:43.80397Z","time spent":"382.829442ms","remote":"127.0.0.1:54546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:14:44.341428Z","caller":"traceutil/trace.go:171","msg":"trace[2059621808] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:650; }","duration":"154.83607ms","start":"2026-04-21T18:14:44.18656Z","end":"2026-04-21T18:14:44.341397Z","steps":["trace[2059621808] 'read index received' (duration: 154.46501ms)","trace[2059621808] 'applied index is now lower than readState.Index' (duration: 370.19µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:14:44.341543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.055219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-21T18:14:44.341567Z","caller":"traceutil/trace.go:171","msg":"trace[1423858774] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:630; }","duration":"500.132941ms","start":"2026-04-21T18:14:43.841424Z","end":"2026-04-21T18:14:44.341557Z","steps":["trace[1423858774] 'agreement among raft nodes before linearized reading' (duration: 500.068398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:44.341591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:43.841402Z","time spent":"500.182961ms","remote":"127.0.0.1:54740","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":"info","ts":"2026-04-21T18:14:44.341638Z","caller":"traceutil/trace.go:171","msg":"trace[1430298110] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"253.934799ms","start":"2026-04-21T18:14:44.087671Z","end":"2026-04-21T18:14:44.341606Z","steps":["trace[1430298110] 'process raft request' (duration: 253.341622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:44.341691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.040019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.175\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-21T18:14:44.341714Z","caller":"traceutil/trace.go:171","msg":"trace[2080907003] range","detail":"{range_begin:/registry/masterleases/199.19.213.175; range_end:; response_count:1; response_revision:630; }","duration":"378.090411ms","start":"2026-04-21T18:14:43.963617Z","end":"2026-04-21T18:14:44.341708Z","steps":["trace[2080907003] 'agreement among raft nodes before linearized reading' (duration: 378.0497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:44.341729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:43.963596Z","time spent":"378.128813ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.175\" "} {"level":"warn","ts":"2026-04-21T18:14:44.341783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.299482ms","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-21T18:14:44.341843Z","caller":"traceutil/trace.go:171","msg":"trace[168836232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:630; }","duration":"365.422165ms","start":"2026-04-21T18:14:43.976406Z","end":"2026-04-21T18:14:44.341828Z","steps":["trace[168836232] 'agreement among raft nodes before linearized reading' (duration: 365.207609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:44.341879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:43.976382Z","time spent":"365.488998ms","remote":"127.0.0.1:54740","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-21T18:14:44.341975Z","caller":"traceutil/trace.go:171","msg":"trace[1208763843] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"253.883308ms","start":"2026-04-21T18:14:44.087688Z","end":"2026-04-21T18:14:44.341571Z","steps":["trace[1208763843] 'process raft request' (duration: 253.676922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:44.659724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.28517ms","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-21T18:14:44.659803Z","caller":"traceutil/trace.go:171","msg":"trace[1067337136] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"453.975712ms","start":"2026-04-21T18:14:44.205787Z","end":"2026-04-21T18:14:44.659763Z","steps":["trace[1067337136] 'process raft request' (duration: 447.286054ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:44.659884Z","caller":"traceutil/trace.go:171","msg":"trace[2034606015] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:630; }","duration":"139.465245ms","start":"2026-04-21T18:14:44.520398Z","end":"2026-04-21T18:14:44.659863Z","steps":["trace[2034606015] 'range keys from in-memory index tree' (duration: 139.263879ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:44.660063Z","caller":"traceutil/trace.go:171","msg":"trace[94251212] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"414.089469ms","start":"2026-04-21T18:14:44.245639Z","end":"2026-04-21T18:14:44.659729Z","steps":["trace[94251212] 'process raft request' (duration: 414.047848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:44.660148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:44.205771Z","time spent":"454.113256ms","remote":"127.0.0.1:54662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:14:44.660172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:44.245616Z","time spent":"414.505591ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T18:14:44.680292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:44.342967Z","time spent":"337.315712ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-21T18:14:44.680372Z","caller":"traceutil/trace.go:171","msg":"trace[1944622536] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:652; }","duration":"195.509738ms","start":"2026-04-21T18:14:44.484843Z","end":"2026-04-21T18:14:44.680353Z","steps":["trace[1944622536] 'read index received' (duration: 168.183517ms)","trace[1944622536] 'applied index is now lower than readState.Index' (duration: 27.325441ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:14:44.680403Z","caller":"traceutil/trace.go:171","msg":"trace[1970150890] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"333.791607ms","start":"2026-04-21T18:14:44.346581Z","end":"2026-04-21T18:14:44.680372Z","steps":["trace[1970150890] 'process raft request' (duration: 333.624562ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:44.680458Z","caller":"traceutil/trace.go:171","msg":"trace[11351148] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"333.803698ms","start":"2026-04-21T18:14:44.346627Z","end":"2026-04-21T18:14:44.68043Z","steps":["trace[11351148] 'process raft request' (duration: 333.685914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:44.680489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.632971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:14:44.680522Z","caller":"traceutil/trace.go:171","msg":"trace[2082497665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"195.707793ms","start":"2026-04-21T18:14:44.484807Z","end":"2026-04-21T18:14:44.680514Z","steps":["trace[2082497665] 'agreement among raft nodes before linearized reading' (duration: 195.634152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:44.680539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:44.346555Z","time spent":"333.914712ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:14:44.680599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:44.346603Z","time spent":"333.913831ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:14:45.579517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.734099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:14:45.579645Z","caller":"traceutil/trace.go:171","msg":"trace[1251925684] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"879.597691ms","start":"2026-04-21T18:14:44.700029Z","end":"2026-04-21T18:14:45.579627Z","steps":["trace[1251925684] 'process raft request' (duration: 755.684346ms)","trace[1251925684] 'compare' (duration: 123.450821ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:14:45.579941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:44.700018Z","time spent":"879.890972ms","remote":"127.0.0.1:54662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:14:45.804983Z","caller":"traceutil/trace.go:171","msg":"trace[1062800620] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"220.935541ms","start":"2026-04-21T18:14:45.584017Z","end":"2026-04-21T18:14:45.804952Z","steps":["trace[1062800620] 'process raft request' (duration: 192.90826ms)","trace[1062800620] 'compare' (duration: 27.876937ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:14:45.867111Z","caller":"traceutil/trace.go:171","msg":"trace[2091866204] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"280.668253ms","start":"2026-04-21T18:14:45.586417Z","end":"2026-04-21T18:14:45.867085Z","steps":["trace[2091866204] 'process raft request' (duration: 280.58316ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:45.867126Z","caller":"traceutil/trace.go:171","msg":"trace[669576026] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"282.059004ms","start":"2026-04-21T18:14:45.58498Z","end":"2026-04-21T18:14:45.867039Z","steps":["trace[669576026] 'process raft request' (duration: 281.821207ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:45.8675Z","caller":"traceutil/trace.go:171","msg":"trace[621030955] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"281.319012ms","start":"2026-04-21T18:14:45.586159Z","end":"2026-04-21T18:14:45.867478Z","steps":["trace[621030955] 'process raft request' (duration: 280.757705ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:47.357856Z","caller":"traceutil/trace.go:171","msg":"trace[503948273] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"346.35584ms","start":"2026-04-21T18:14:47.011382Z","end":"2026-04-21T18:14:47.357738Z","steps":["trace[503948273] 'process raft request' (duration: 344.34706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:14:47.358019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:14:47.011365Z","time spent":"346.583528ms","remote":"127.0.0.1:54528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":856,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:14:58.510981Z","caller":"traceutil/trace.go:171","msg":"trace[1191787753] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"103.935972ms","start":"2026-04-21T18:14:58.40702Z","end":"2026-04-21T18:14:58.510956Z","steps":["trace[1191787753] 'process raft request' (duration: 100.940393ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:14:59.757381Z","caller":"traceutil/trace.go:171","msg":"trace[1498874236] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"160.146588ms","start":"2026-04-21T18:14:59.597206Z","end":"2026-04-21T18:14:59.757352Z","steps":["trace[1498874236] 'process raft request' (duration: 159.979653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:02.848723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.2465ms","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-21T18:15:02.848799Z","caller":"traceutil/trace.go:171","msg":"trace[136068841] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:795; }","duration":"422.407905ms","start":"2026-04-21T18:15:02.426373Z","end":"2026-04-21T18:15:02.848781Z","steps":["trace[136068841] 'range keys from in-memory index tree' (duration: 422.103666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:02.848843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:02.426352Z","time spent":"422.480677ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-21T18:15:02.84904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.460543ms","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-21T18:15:02.849094Z","caller":"traceutil/trace.go:171","msg":"trace[1160134724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:795; }","duration":"322.567815ms","start":"2026-04-21T18:15:02.526513Z","end":"2026-04-21T18:15:02.849081Z","steps":["trace[1160134724] 'range keys from in-memory index tree' (duration: 322.233045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:02.849172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:02.52647Z","time spent":"322.691328ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T18:15:02.849253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.54168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dcbmq\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-21T18:15:02.849314Z","caller":"traceutil/trace.go:171","msg":"trace[1536447054] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dcbmq; range_end:; response_count:1; response_revision:795; }","duration":"937.636223ms","start":"2026-04-21T18:15:01.911666Z","end":"2026-04-21T18:15:02.849302Z","steps":["trace[1536447054] 'range keys from in-memory index tree' (duration: 937.417106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:02.849351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:01.911647Z","time spent":"937.696274ms","remote":"127.0.0.1:54662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dcbmq\" "} {"level":"warn","ts":"2026-04-21T18:15:02.849415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.082196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:15:02.849503Z","caller":"traceutil/trace.go:171","msg":"trace[1097634654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"364.21053ms","start":"2026-04-21T18:15:02.485269Z","end":"2026-04-21T18:15:02.84948Z","steps":["trace[1097634654] 'range keys from in-memory index tree' (duration: 363.980693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:02.849555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:15:02.485249Z","time spent":"364.294541ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-21T18:15:02.956225Z","caller":"traceutil/trace.go:171","msg":"trace[358399470] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"101.82066ms","start":"2026-04-21T18:15:02.854382Z","end":"2026-04-21T18:15:02.956203Z","steps":["trace[358399470] 'process raft request' (duration: 101.762328ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:02.956391Z","caller":"traceutil/trace.go:171","msg":"trace[1499395975] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"102.072586ms","start":"2026-04-21T18:15:02.854313Z","end":"2026-04-21T18:15:02.956385Z","steps":["trace[1499395975] 'process raft request' (duration: 101.592442ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:02.956818Z","caller":"traceutil/trace.go:171","msg":"trace[51070581] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"102.223821ms","start":"2026-04-21T18:15:02.854569Z","end":"2026-04-21T18:15:02.956793Z","steps":["trace[51070581] 'process raft request' (duration: 101.603223ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:02.957415Z","caller":"traceutil/trace.go:171","msg":"trace[682648872] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"101.583812ms","start":"2026-04-21T18:15:02.855821Z","end":"2026-04-21T18:15:02.957405Z","steps":["trace[682648872] 'process raft request' (duration: 100.741867ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:38.297089Z","caller":"traceutil/trace.go:171","msg":"trace[895377465] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"153.885852ms","start":"2026-04-21T18:15:38.143171Z","end":"2026-04-21T18:15:38.297057Z","steps":["trace[895377465] 'process raft request' (duration: 121.157813ms)","trace[895377465] 'compare' (duration: 32.391699ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:15:39.275688Z","caller":"traceutil/trace.go:171","msg":"trace[734013768] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1050; }","duration":"154.942373ms","start":"2026-04-21T18:15:39.120728Z","end":"2026-04-21T18:15:39.275671Z","steps":["trace[734013768] 'read index received' (duration: 154.736777ms)","trace[734013768] 'applied index is now lower than readState.Index' (duration: 205.116µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:15:39.275734Z","caller":"traceutil/trace.go:171","msg":"trace[1393566779] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"157.310975ms","start":"2026-04-21T18:15:39.118393Z","end":"2026-04-21T18:15:39.275704Z","steps":["trace[1393566779] 'process raft request' (duration: 157.14673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:39.275873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.13975ms","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-21T18:15:39.2759Z","caller":"traceutil/trace.go:171","msg":"trace[755650675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1014; }","duration":"155.209442ms","start":"2026-04-21T18:15:39.120683Z","end":"2026-04-21T18:15:39.275893Z","steps":["trace[755650675] 'agreement among raft nodes before linearized reading' (duration: 155.078988ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:15:39.285749Z","caller":"traceutil/trace.go:171","msg":"trace[2082545797] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"164.889339ms","start":"2026-04-21T18:15:39.120836Z","end":"2026-04-21T18:15:39.285725Z","steps":["trace[2082545797] 'process raft request' (duration: 164.648071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:39.285883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.995883ms","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-21T18:15:39.285959Z","caller":"traceutil/trace.go:171","msg":"trace[1249217613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1015; }","duration":"165.093086ms","start":"2026-04-21T18:15:39.120849Z","end":"2026-04-21T18:15:39.285942Z","steps":["trace[1249217613] 'agreement among raft nodes before linearized reading' (duration: 164.831817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:57.679059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.658092ms","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-21T18:15:57.679159Z","caller":"traceutil/trace.go:171","msg":"trace[153902353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1074; }","duration":"220.792327ms","start":"2026-04-21T18:15:57.458337Z","end":"2026-04-21T18:15:57.67913Z","steps":["trace[153902353] 'range keys from in-memory index tree' (duration: 220.445386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:57.679222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.309364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-21T18:15:57.67933Z","caller":"traceutil/trace.go:171","msg":"trace[1131924219] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1074; }","duration":"245.451818ms","start":"2026-04-21T18:15:57.433848Z","end":"2026-04-21T18:15:57.6793Z","steps":["trace[1131924219] 'range keys from in-memory index tree' (duration: 245.121778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:15:57.679326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.920821ms","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-21T18:15:57.679418Z","caller":"traceutil/trace.go:171","msg":"trace[636643419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1074; }","duration":"221.064825ms","start":"2026-04-21T18:15:57.458331Z","end":"2026-04-21T18:15:57.679396Z","steps":["trace[636643419] 'range keys from in-memory index tree' (duration: 220.691994ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:02.678417Z","caller":"traceutil/trace.go:171","msg":"trace[86096400] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1133; }","duration":"193.104117ms","start":"2026-04-21T18:16:02.485295Z","end":"2026-04-21T18:16:02.678399Z","steps":["trace[86096400] 'read index received' (duration: 192.998223ms)","trace[86096400] 'applied index is now lower than readState.Index' (duration: 105.113µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:16:02.678512Z","caller":"traceutil/trace.go:171","msg":"trace[1389095113] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"267.300737ms","start":"2026-04-21T18:16:02.411198Z","end":"2026-04-21T18:16:02.678499Z","steps":["trace[1389095113] 'process raft request' (duration: 267.0858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:02.678682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.378584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:16:02.678708Z","caller":"traceutil/trace.go:171","msg":"trace[1271944733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1092; }","duration":"193.443977ms","start":"2026-04-21T18:16:02.485257Z","end":"2026-04-21T18:16:02.678701Z","steps":["trace[1271944733] 'agreement among raft nodes before linearized reading' (duration: 193.389305ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:03.66539Z","caller":"traceutil/trace.go:171","msg":"trace[646518773] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"204.433392ms","start":"2026-04-21T18:16:03.460935Z","end":"2026-04-21T18:16:03.665368Z","steps":["trace[646518773] 'process raft request' (duration: 204.296628ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:04.773018Z","caller":"traceutil/trace.go:171","msg":"trace[782182965] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1141; }","duration":"288.609528ms","start":"2026-04-21T18:16:04.484379Z","end":"2026-04-21T18:16:04.772988Z","steps":["trace[782182965] 'read index received' (duration: 288.500065ms)","trace[782182965] 'applied index is now lower than readState.Index' (duration: 108.323µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:16:04.773114Z","caller":"traceutil/trace.go:171","msg":"trace[55269258] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"484.640211ms","start":"2026-04-21T18:16:04.288452Z","end":"2026-04-21T18:16:04.773093Z","steps":["trace[55269258] 'process raft request' (duration: 484.373233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:04.773221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.835735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T18:16:04.773285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:04.288434Z","time spent":"484.736684ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:16:04.77332Z","caller":"traceutil/trace.go:171","msg":"trace[1497367415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1099; }","duration":"288.978609ms","start":"2026-04-21T18:16:04.484328Z","end":"2026-04-21T18:16:04.773307Z","steps":["trace[1497367415] 'agreement among raft nodes before linearized reading' (duration: 288.810445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:05.27922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.68528ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:16:05.279383Z","caller":"traceutil/trace.go:171","msg":"trace[767061828] linearizableReadLoop","detail":"{readStateIndex:1143; appliedIndex:1142; }","duration":"505.021014ms","start":"2026-04-21T18:16:04.774325Z","end":"2026-04-21T18:16:05.279346Z","steps":["trace[767061828] 'read index received' (duration: 359.021225ms)","trace[767061828] 'applied index is now lower than readState.Index' (duration: 145.997719ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:16:05.279456Z","caller":"traceutil/trace.go:171","msg":"trace[1744999800] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"595.071816ms","start":"2026-04-21T18:16:04.684362Z","end":"2026-04-21T18:16:05.279434Z","steps":["trace[1744999800] 'process raft request' (duration: 449.029576ms)","trace[1744999800] 'compare' (duration: 145.468482ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:16:05.279615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.21049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T18:16:05.279697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.439326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:16:05.279744Z","caller":"traceutil/trace.go:171","msg":"trace[962072100] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1100; }","duration":"164.531398ms","start":"2026-04-21T18:16:05.115202Z","end":"2026-04-21T18:16:05.279733Z","steps":["trace[962072100] 'agreement among raft nodes before linearized reading' (duration: 164.457457ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:05.279836Z","caller":"traceutil/trace.go:171","msg":"trace[1558256270] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1100; }","duration":"505.372625ms","start":"2026-04-21T18:16:04.774322Z","end":"2026-04-21T18:16:05.279695Z","steps":["trace[1558256270] 'agreement among raft nodes before linearized reading' (duration: 505.154518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:05.279923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:04.77429Z","time spent":"505.621183ms","remote":"127.0.0.1:54456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T18:16:05.280137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:04.684335Z","time spent":"595.268741ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:16:06.1504Z","caller":"traceutil/trace.go:171","msg":"trace[163668989] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"322.243205ms","start":"2026-04-21T18:16:05.828135Z","end":"2026-04-21T18:16:06.150378Z","steps":["trace[163668989] 'process raft request' (duration: 322.106151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:06.150539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:05.828108Z","time spent":"322.357449ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:16:06.580649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.607914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T18:16:06.580838Z","caller":"traceutil/trace.go:171","msg":"trace[419916211] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1104; }","duration":"272.843701ms","start":"2026-04-21T18:16:06.307975Z","end":"2026-04-21T18:16:06.580819Z","steps":["trace[419916211] 'count revisions from in-memory index tree' (duration: 272.47503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:06.581294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.44362ms","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-21T18:16:06.581372Z","caller":"traceutil/trace.go:171","msg":"trace[1379161459] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1104; }","duration":"354.574645ms","start":"2026-04-21T18:16:06.226776Z","end":"2026-04-21T18:16:06.581351Z","steps":["trace[1379161459] 'range keys from in-memory index tree' (duration: 353.797881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:06.581569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:06.226746Z","time spent":"354.80458ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-21T18:16:18.433651Z","caller":"traceutil/trace.go:171","msg":"trace[593993062] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"219.85991ms","start":"2026-04-21T18:16:18.213762Z","end":"2026-04-21T18:16:18.433622Z","steps":["trace[593993062] 'process raft request' (duration: 219.639194ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:20.366582Z","caller":"traceutil/trace.go:171","msg":"trace[287414616] linearizableReadLoop","detail":"{readStateIndex:1245; appliedIndex:1244; }","duration":"444.228593ms","start":"2026-04-21T18:16:19.922331Z","end":"2026-04-21T18:16:20.36656Z","steps":["trace[287414616] 'read index received' (duration: 444.009856ms)","trace[287414616] 'applied index is now lower than readState.Index' (duration: 217.987µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:16:20.366797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.455159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-21T18:16:20.366832Z","caller":"traceutil/trace.go:171","msg":"trace[494927405] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1197; }","duration":"444.50186ms","start":"2026-04-21T18:16:19.922319Z","end":"2026-04-21T18:16:20.366821Z","steps":["trace[494927405] 'agreement among raft nodes before linearized reading' (duration: 444.362086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:20.366864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:19.922269Z","time spent":"444.587264ms","remote":"127.0.0.1:54740","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-21T18:16:20.366961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.077277ms","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-21T18:16:20.367007Z","caller":"traceutil/trace.go:171","msg":"trace[168706941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1197; }","duration":"390.14781ms","start":"2026-04-21T18:16:19.976846Z","end":"2026-04-21T18:16:20.366993Z","steps":["trace[168706941] 'agreement among raft nodes before linearized reading' (duration: 390.031046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:20.367036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:19.976834Z","time spent":"390.19618ms","remote":"127.0.0.1:54740","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-21T18:16:20.36669Z","caller":"traceutil/trace.go:171","msg":"trace[1049849057] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"616.774379ms","start":"2026-04-21T18:16:19.749884Z","end":"2026-04-21T18:16:20.366658Z","steps":["trace[1049849057] 'process raft request' (duration: 616.513031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:20.367434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:19.749868Z","time spent":"617.482379ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:16:20.939145Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4891364054905721657,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T18:16:21.022617Z","caller":"traceutil/trace.go:171","msg":"trace[1233152975] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"651.463777ms","start":"2026-04-21T18:16:20.371129Z","end":"2026-04-21T18:16:21.022593Z","steps":["trace[1233152975] 'process raft request' (duration: 643.680897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:21.022736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:20.371112Z","time spent":"651.56618ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:16:21.024931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.357798ms","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-21T18:16:21.024977Z","caller":"traceutil/trace.go:171","msg":"trace[1100188539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1199; }","duration":"586.44473ms","start":"2026-04-21T18:16:20.438521Z","end":"2026-04-21T18:16:21.024966Z","steps":["trace[1100188539] 'agreement among raft nodes before linearized reading' (duration: 586.280495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:21.024997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:20.438467Z","time spent":"586.525312ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-21T18:16:21.024863Z","caller":"traceutil/trace.go:171","msg":"trace[489397431] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"653.540509ms","start":"2026-04-21T18:16:20.371251Z","end":"2026-04-21T18:16:21.024791Z","steps":["trace[489397431] 'process raft request' (duration: 653.431196ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:21.024751Z","caller":"traceutil/trace.go:171","msg":"trace[697516122] linearizableReadLoop","detail":"{readStateIndex:1247; appliedIndex:1245; }","duration":"586.14971ms","start":"2026-04-21T18:16:20.43859Z","end":"2026-04-21T18:16:21.02474Z","steps":["trace[697516122] 'read index received' (duration: 576.233456ms)","trace[697516122] 'applied index is now lower than readState.Index' (duration: 9.915414ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:16:21.025341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:20.37123Z","time spent":"653.992793ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:16:21.025373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.965921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-21T18:16:21.025405Z","caller":"traceutil/trace.go:171","msg":"trace[1710918702] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1199; }","duration":"280.032333ms","start":"2026-04-21T18:16:20.745367Z","end":"2026-04-21T18:16:21.025399Z","steps":["trace[1710918702] 'agreement among raft nodes before linearized reading' (duration: 279.960562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:21.025366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.139207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:16:21.025581Z","caller":"traceutil/trace.go:171","msg":"trace[1789186288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1199; }","duration":"541.329162ms","start":"2026-04-21T18:16:20.484185Z","end":"2026-04-21T18:16:21.025514Z","steps":["trace[1789186288] 'agreement among raft nodes before linearized reading' (duration: 541.114065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:21.025765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:20.484164Z","time spent":"541.59157ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-21T18:16:25.218608Z","caller":"traceutil/trace.go:171","msg":"trace[2042809999] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"105.997333ms","start":"2026-04-21T18:16:25.112586Z","end":"2026-04-21T18:16:25.218584Z","steps":["trace[2042809999] 'process raft request' (duration: 105.808057ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:25.22782Z","caller":"traceutil/trace.go:171","msg":"trace[1147485266] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"114.65962ms","start":"2026-04-21T18:16:25.113143Z","end":"2026-04-21T18:16:25.227803Z","steps":["trace[1147485266] 'process raft request' (duration: 114.557647ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:25.227906Z","caller":"traceutil/trace.go:171","msg":"trace[564046933] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"113.823225ms","start":"2026-04-21T18:16:25.114073Z","end":"2026-04-21T18:16:25.227896Z","steps":["trace[564046933] 'process raft request' (duration: 113.699451ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:29.39524Z","caller":"traceutil/trace.go:171","msg":"trace[1710645774] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"153.995386ms","start":"2026-04-21T18:16:29.241223Z","end":"2026-04-21T18:16:29.395218Z","steps":["trace[1710645774] 'process raft request' (duration: 107.211039ms)","trace[1710645774] 'compare' (duration: 46.658304ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:16:29.395473Z","caller":"traceutil/trace.go:171","msg":"trace[476550047] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"154.212653ms","start":"2026-04-21T18:16:29.241222Z","end":"2026-04-21T18:16:29.395434Z","steps":["trace[476550047] 'process raft request' (duration: 153.968656ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:33.606292Z","caller":"traceutil/trace.go:171","msg":"trace[2069439477] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"172.149065ms","start":"2026-04-21T18:16:33.434116Z","end":"2026-04-21T18:16:33.606265Z","steps":["trace[2069439477] 'process raft request' (duration: 171.960279ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:33.60641Z","caller":"traceutil/trace.go:171","msg":"trace[1917817087] linearizableReadLoop","detail":"{readStateIndex:1291; appliedIndex:1289; }","duration":"121.718169ms","start":"2026-04-21T18:16:33.484667Z","end":"2026-04-21T18:16:33.606385Z","steps":["trace[1917817087] 'read index received' (duration: 5.635597ms)","trace[1917817087] 'applied index is now lower than readState.Index' (duration: 116.080632ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:16:33.606519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.841313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:16:33.606508Z","caller":"traceutil/trace.go:171","msg":"trace[834420347] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"118.947087ms","start":"2026-04-21T18:16:33.487536Z","end":"2026-04-21T18:16:33.606483Z","steps":["trace[834420347] 'process raft request' (duration: 118.690099ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:33.606546Z","caller":"traceutil/trace.go:171","msg":"trace[1060019203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1242; }","duration":"121.922415ms","start":"2026-04-21T18:16:33.484615Z","end":"2026-04-21T18:16:33.606537Z","steps":["trace[1060019203] 'agreement among raft nodes before linearized reading' (duration: 121.847444ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:35.409267Z","caller":"traceutil/trace.go:171","msg":"trace[416259454] linearizableReadLoop","detail":"{readStateIndex:1296; appliedIndex:1295; }","duration":"110.653651ms","start":"2026-04-21T18:16:35.298591Z","end":"2026-04-21T18:16:35.409245Z","steps":["trace[416259454] 'read index received' (duration: 110.450065ms)","trace[416259454] 'applied index is now lower than readState.Index' (duration: 202.566µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:16:35.409334Z","caller":"traceutil/trace.go:171","msg":"trace[778850264] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"216.029736ms","start":"2026-04-21T18:16:35.193258Z","end":"2026-04-21T18:16:35.409288Z","steps":["trace[778850264] 'process raft request' (duration: 215.85413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:35.409422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.812066ms","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-21T18:16:35.40945Z","caller":"traceutil/trace.go:171","msg":"trace[1253504073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1245; }","duration":"110.900769ms","start":"2026-04-21T18:16:35.298541Z","end":"2026-04-21T18:16:35.409442Z","steps":["trace[1253504073] 'agreement among raft nodes before linearized reading' (duration: 110.783256ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:36.880092Z","caller":"traceutil/trace.go:171","msg":"trace[1030256490] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"362.911926ms","start":"2026-04-21T18:16:36.517144Z","end":"2026-04-21T18:16:36.880056Z","steps":["trace[1030256490] 'process raft request' (duration: 362.73446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:36.880351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:36.517117Z","time spent":"363.06131ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:16:37.593876Z","caller":"traceutil/trace.go:171","msg":"trace[1197360458] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"154.420543ms","start":"2026-04-21T18:16:37.439426Z","end":"2026-04-21T18:16:37.593847Z","steps":["trace[1197360458] 'process raft request' (duration: 150.100382ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:39.354259Z","caller":"traceutil/trace.go:171","msg":"trace[768471232] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"112.802994ms","start":"2026-04-21T18:16:39.241433Z","end":"2026-04-21T18:16:39.354236Z","steps":["trace[768471232] 'process raft request' (duration: 112.66013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:43.966133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.146161ms","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-21T18:16:43.966224Z","caller":"traceutil/trace.go:171","msg":"trace[376534853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1306; }","duration":"349.304306ms","start":"2026-04-21T18:16:43.616904Z","end":"2026-04-21T18:16:43.966208Z","steps":["trace[376534853] 'range keys from in-memory index tree' (duration: 349.011588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:43.966259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:43.616886Z","time spent":"349.363907ms","remote":"127.0.0.1:54740","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-21T18:16:43.966656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.040414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-21T18:16:43.966698Z","caller":"traceutil/trace.go:171","msg":"trace[455321710] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1306; }","duration":"304.106146ms","start":"2026-04-21T18:16:43.662581Z","end":"2026-04-21T18:16:43.966687Z","steps":["trace[455321710] 'range keys from in-memory index tree' (duration: 303.86018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:43.966726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:43.662565Z","time spent":"304.150858ms","remote":"127.0.0.1:54740","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":"info","ts":"2026-04-21T18:16:43.967216Z","caller":"traceutil/trace.go:171","msg":"trace[35449680] linearizableReadLoop","detail":"{readStateIndex:1359; appliedIndex:1358; }","duration":"268.35833ms","start":"2026-04-21T18:16:43.698846Z","end":"2026-04-21T18:16:43.967204Z","steps":["trace[35449680] 'read index received' (duration: 235.266098ms)","trace[35449680] 'applied index is now lower than readState.Index' (duration: 33.091052ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:16:43.967334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.490633ms","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-21T18:16:43.967406Z","caller":"traceutil/trace.go:171","msg":"trace[1393706662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1307; }","duration":"268.552786ms","start":"2026-04-21T18:16:43.698807Z","end":"2026-04-21T18:16:43.96736Z","steps":["trace[1393706662] 'agreement among raft nodes before linearized reading' (duration: 268.447233ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:43.967681Z","caller":"traceutil/trace.go:171","msg":"trace[329946935] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"288.957905ms","start":"2026-04-21T18:16:43.678711Z","end":"2026-04-21T18:16:43.967669Z","steps":["trace[329946935] 'process raft request' (duration: 255.44437ms)","trace[329946935] 'compare' (duration: 31.803767ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:16:44.152881Z","caller":"traceutil/trace.go:171","msg":"trace[92943193] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"132.679998ms","start":"2026-04-21T18:16:44.020179Z","end":"2026-04-21T18:16:44.152859Z","steps":["trace[92943193] 'process raft request' (duration: 53.382938ms)","trace[92943193] 'compare' (duration: 79.202717ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:16:45.748412Z","caller":"traceutil/trace.go:171","msg":"trace[1577432593] linearizableReadLoop","detail":"{readStateIndex:1366; appliedIndex:1365; }","duration":"263.935956ms","start":"2026-04-21T18:16:45.484458Z","end":"2026-04-21T18:16:45.748394Z","steps":["trace[1577432593] 'read index received' (duration: 263.757481ms)","trace[1577432593] 'applied index is now lower than readState.Index' (duration: 177.805µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:16:45.748547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.0712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:16:45.748569Z","caller":"traceutil/trace.go:171","msg":"trace[156007880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1313; }","duration":"264.142603ms","start":"2026-04-21T18:16:45.484419Z","end":"2026-04-21T18:16:45.748562Z","steps":["trace[156007880] 'agreement among raft nodes before linearized reading' (duration: 264.06407ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:45.74854Z","caller":"traceutil/trace.go:171","msg":"trace[490216628] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"831.713372ms","start":"2026-04-21T18:16:44.916801Z","end":"2026-04-21T18:16:45.748515Z","steps":["trace[490216628] 'process raft request' (duration: 831.478634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:45.748668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:44.916772Z","time spent":"831.825924ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:16:45.748871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.00369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-21T18:16:45.748896Z","caller":"traceutil/trace.go:171","msg":"trace[986613582] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1313; }","duration":"202.069092ms","start":"2026-04-21T18:16:45.546819Z","end":"2026-04-21T18:16:45.748888Z","steps":["trace[986613582] 'agreement among raft nodes before linearized reading' (duration: 201.960419ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:45.875772Z","caller":"traceutil/trace.go:171","msg":"trace[1772269455] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"121.748293ms","start":"2026-04-21T18:16:45.753994Z","end":"2026-04-21T18:16:45.875742Z","steps":["trace[1772269455] 'process raft request' (duration: 45.22974ms)","trace[1772269455] 'compare' (duration: 76.265717ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:16:45.962115Z","caller":"traceutil/trace.go:171","msg":"trace[113507220] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"103.748793ms","start":"2026-04-21T18:16:45.858338Z","end":"2026-04-21T18:16:45.962087Z","steps":["trace[113507220] 'process raft request' (duration: 103.521126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:46.217681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.832566ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:16:46.217778Z","caller":"traceutil/trace.go:171","msg":"trace[1892138745] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"337.254841ms","start":"2026-04-21T18:16:45.880509Z","end":"2026-04-21T18:16:46.217764Z","steps":["trace[1892138745] 'process raft request' (duration: 220.280901ms)","trace[1892138745] 'compare' (duration: 116.733373ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:16:46.217823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:45.880484Z","time spent":"337.317871ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:16:46.296108Z","caller":"traceutil/trace.go:171","msg":"trace[2013700258] linearizableReadLoop","detail":"{readStateIndex:1370; appliedIndex:1368; }","duration":"290.146876ms","start":"2026-04-21T18:16:46.005937Z","end":"2026-04-21T18:16:46.296083Z","steps":["trace[2013700258] 'read index received' (duration: 94.884284ms)","trace[2013700258] 'applied index is now lower than readState.Index' (duration: 195.261292ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:16:46.296244Z","caller":"traceutil/trace.go:171","msg":"trace[538190229] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"271.003683ms","start":"2026-04-21T18:16:46.025229Z","end":"2026-04-21T18:16:46.296233Z","steps":["trace[538190229] 'process raft request' (duration: 270.811219ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:46.29625Z","caller":"traceutil/trace.go:171","msg":"trace[2056036777] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"323.691062ms","start":"2026-04-21T18:16:45.972517Z","end":"2026-04-21T18:16:46.296208Z","steps":["trace[2056036777] 'process raft request' (duration: 323.383633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:46.296458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:45.972492Z","time spent":"323.856786ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:16:46.296637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.696162ms","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-21T18:16:46.296721Z","caller":"traceutil/trace.go:171","msg":"trace[938420653] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1318; }","duration":"290.815326ms","start":"2026-04-21T18:16:46.00589Z","end":"2026-04-21T18:16:46.296705Z","steps":["trace[938420653] 'agreement among raft nodes before linearized reading' (duration: 290.57788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:46.296943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.022842ms","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-21T18:16:46.297063Z","caller":"traceutil/trace.go:171","msg":"trace[2095434694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1318; }","duration":"272.146156ms","start":"2026-04-21T18:16:46.024875Z","end":"2026-04-21T18:16:46.297021Z","steps":["trace[2095434694] 'agreement among raft nodes before linearized reading' (duration: 271.998591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:46.297323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.506991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-21T18:16:46.297425Z","caller":"traceutil/trace.go:171","msg":"trace[650247290] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1318; }","duration":"269.588134ms","start":"2026-04-21T18:16:46.027779Z","end":"2026-04-21T18:16:46.297368Z","steps":["trace[650247290] 'agreement among raft nodes before linearized reading' (duration: 269.458851ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:46.66059Z","caller":"traceutil/trace.go:171","msg":"trace[479644651] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"255.432619ms","start":"2026-04-21T18:16:46.405132Z","end":"2026-04-21T18:16:46.660565Z","steps":["trace[479644651] 'process raft request' (duration: 255.291195ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:46.960607Z","caller":"traceutil/trace.go:171","msg":"trace[2095291770] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"539.583899ms","start":"2026-04-21T18:16:46.420988Z","end":"2026-04-21T18:16:46.960572Z","steps":["trace[2095291770] 'process raft request' (duration: 518.120901ms)","trace[2095291770] 'compare' (duration: 20.985305ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:16:46.960747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:46.420974Z","time spent":"539.704473ms","remote":"127.0.0.1:54528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":837,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T18:16:46.960947Z","caller":"traceutil/trace.go:171","msg":"trace[1214735589] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"537.492711ms","start":"2026-04-21T18:16:46.423435Z","end":"2026-04-21T18:16:46.960927Z","steps":["trace[1214735589] 'process raft request' (duration: 537.071979ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:46.960952Z","caller":"traceutil/trace.go:171","msg":"trace[639553836] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1376; }","duration":"479.062713ms","start":"2026-04-21T18:16:46.48187Z","end":"2026-04-21T18:16:46.960933Z","steps":["trace[639553836] 'read index received' (duration: 178.553797ms)","trace[639553836] 'applied index is now lower than readState.Index' (duration: 300.507406ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:16:46.961055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:46.423427Z","time spent":"537.568024ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:16:46.961126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.236258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:16:46.961192Z","caller":"traceutil/trace.go:171","msg":"trace[2120631730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1326; }","duration":"479.33951ms","start":"2026-04-21T18:16:46.481836Z","end":"2026-04-21T18:16:46.961175Z","steps":["trace[2120631730] 'agreement among raft nodes before linearized reading' (duration: 479.184415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:46.96123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:46.481823Z","time spent":"479.400001ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T18:16:47.31877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.274074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-21T18:16:47.31886Z","caller":"traceutil/trace.go:171","msg":"trace[2052987516] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1326; }","duration":"218.405767ms","start":"2026-04-21T18:16:47.100429Z","end":"2026-04-21T18:16:47.318834Z","steps":["trace[2052987516] 'range keys from in-memory index tree' (duration: 218.108049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:47.64795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.859605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:16:47.648071Z","caller":"traceutil/trace.go:171","msg":"trace[1549489060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1327; }","duration":"165.229875ms","start":"2026-04-21T18:16:47.482828Z","end":"2026-04-21T18:16:47.648058Z","steps":["trace[1549489060] 'range keys from in-memory index tree' (duration: 164.762093ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:52.173862Z","caller":"traceutil/trace.go:171","msg":"trace[1650737780] linearizableReadLoop","detail":"{readStateIndex:1415; appliedIndex:1414; }","duration":"273.093572ms","start":"2026-04-21T18:16:51.90074Z","end":"2026-04-21T18:16:52.173834Z","steps":["trace[1650737780] 'read index received' (duration: 272.884926ms)","trace[1650737780] 'applied index is now lower than readState.Index' (duration: 207.516µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:16:52.174067Z","caller":"traceutil/trace.go:171","msg":"trace[812266537] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"394.855386ms","start":"2026-04-21T18:16:51.779173Z","end":"2026-04-21T18:16:52.174028Z","steps":["trace[812266537] 'process raft request' (duration: 394.436664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:52.17423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:51.779156Z","time spent":"394.966358ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:16:52.17429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.385618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-04-21T18:16:52.174388Z","caller":"traceutil/trace.go:171","msg":"trace[1268120033] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1361; }","duration":"208.500582ms","start":"2026-04-21T18:16:51.965841Z","end":"2026-04-21T18:16:52.174341Z","steps":["trace[1268120033] 'agreement among raft nodes before linearized reading' (duration: 208.321007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:52.174603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.824981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-21T18:16:52.174645Z","caller":"traceutil/trace.go:171","msg":"trace[830899515] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1361; }","duration":"273.948225ms","start":"2026-04-21T18:16:51.900687Z","end":"2026-04-21T18:16:52.174635Z","steps":["trace[830899515] 'agreement among raft nodes before linearized reading' (duration: 273.798661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:54.413742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.708318803s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:16:54.413896Z","caller":"traceutil/trace.go:171","msg":"trace[1225792730] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"2.235695274s","start":"2026-04-21T18:16:52.178173Z","end":"2026-04-21T18:16:54.413868Z","steps":["trace[1225792730] 'process raft request' (duration: 527.146203ms)","trace[1225792730] 'compare' (duration: 1.707492601s)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:16:54.413995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:52.178144Z","time spent":"2.235805776s","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:16:54.54588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000579159s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-21T18:16:54.546012Z","caller":"traceutil/trace.go:171","msg":"trace[718904273] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000763025s","start":"2026-04-21T18:16:52.545224Z","end":"2026-04-21T18:16:54.545987Z","steps":["trace[718904273] 'agreement among raft nodes before linearized reading' (duration: 2.000613411s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:54.546084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:52.545205Z","time spent":"2.000862328s","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/21 18:16:54 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-21T18:16:54.589959Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.884563506s","expected-duration":"1s"} {"level":"info","ts":"2026-04-21T18:16:54.590851Z","caller":"traceutil/trace.go:171","msg":"trace[673294351] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"2.412588293s","start":"2026-04-21T18:16:52.178227Z","end":"2026-04-21T18:16:54.590815Z","steps":["trace[673294351] 'process raft request' (duration: 2.412251594s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:54.591036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:52.178204Z","time spent":"2.412746709s","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T18:16:54.591626Z","caller":"traceutil/trace.go:171","msg":"trace[405944232] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1415; }","duration":"2.250863996s","start":"2026-04-21T18:16:52.340736Z","end":"2026-04-21T18:16:54.5916Z","steps":["trace[405944232] 'read index received' (duration: 364.597622ms)","trace[405944232] 'applied index is now lower than readState.Index' (duration: 1.886265494s)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:16:54.591673Z","caller":"traceutil/trace.go:171","msg":"trace[1282090504] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"2.277875459s","start":"2026-04-21T18:16:52.313782Z","end":"2026-04-21T18:16:54.591658Z","steps":["trace[1282090504] 'process raft request' (duration: 2.276937264s)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:54.591731Z","caller":"traceutil/trace.go:171","msg":"trace[782217022] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"1.9955443s","start":"2026-04-21T18:16:52.596174Z","end":"2026-04-21T18:16:54.591718Z","steps":["trace[782217022] 'process raft request' (duration: 1.995344574s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:54.591746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:52.313759Z","time spent":"2.277949501s","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:16:54.591892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:52.596155Z","time spent":"1.995608361s","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T18:16:54.591976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.740951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-21T18:16:54.592013Z","caller":"traceutil/trace.go:171","msg":"trace[226288109] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1365; }","duration":"742.803914ms","start":"2026-04-21T18:16:53.849197Z","end":"2026-04-21T18:16:54.592001Z","steps":["trace[226288109] 'agreement among raft nodes before linearized reading' (duration: 742.65729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:54.592048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:53.849183Z","time spent":"742.857935ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-21T18:16:54.592084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.555156ms","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-21T18:16:54.592024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.182530841s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-21T18:16:54.592172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.169777426s","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-21T18:16:54.592188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.598565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.175\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-21T18:16:54.592219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.160837327s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-21T18:16:54.592231Z","caller":"traceutil/trace.go:171","msg":"trace[405474403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1365; }","duration":"2.182768008s","start":"2026-04-21T18:16:52.409441Z","end":"2026-04-21T18:16:54.592209Z","steps":["trace[405474403] 'agreement among raft nodes before linearized reading' (duration: 2.182416438s)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:54.592243Z","caller":"traceutil/trace.go:171","msg":"trace[262635315] range","detail":"{range_begin:/registry/masterleases/199.19.213.175; range_end:; response_count:1; response_revision:1365; }","duration":"621.682968ms","start":"2026-04-21T18:16:53.970551Z","end":"2026-04-21T18:16:54.592234Z","steps":["trace[262635315] 'agreement among raft nodes before linearized reading' (duration: 621.567115ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:54.59225Z","caller":"traceutil/trace.go:171","msg":"trace[2017599935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1365; }","duration":"2.16990369s","start":"2026-04-21T18:16:52.422325Z","end":"2026-04-21T18:16:54.592229Z","steps":["trace[2017599935] 'agreement among raft nodes before linearized reading' (duration: 2.169711624s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:54.592245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.107989112s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:16:54.59227Z","caller":"traceutil/trace.go:171","msg":"trace[1216156747] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1365; }","duration":"2.160893328s","start":"2026-04-21T18:16:52.431363Z","end":"2026-04-21T18:16:54.592257Z","steps":["trace[1216156747] 'agreement among raft nodes before linearized reading' (duration: 2.1606067s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:54.592275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:53.970532Z","time spent":"621.736109ms","remote":"127.0.0.1:54482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.175\" "} {"level":"info","ts":"2026-04-21T18:16:54.592288Z","caller":"traceutil/trace.go:171","msg":"trace[1732413728] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1365; }","duration":"1.108090985s","start":"2026-04-21T18:16:53.484188Z","end":"2026-04-21T18:16:54.592279Z","steps":["trace[1732413728] 'agreement among raft nodes before linearized reading' (duration: 1.108036414s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:54.59228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:52.40942Z","time spent":"2.18284864s","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T18:16:54.592292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:52.422304Z","time spent":"2.169979122s","remote":"127.0.0.1:54740","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-21T18:16:54.592288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.251558396s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-21T18:16:54.592312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:53.484164Z","time spent":"1.108143746s","remote":"127.0.0.1:54456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T18:16:54.592307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:52.431312Z","time spent":"2.16098737s","remote":"127.0.0.1:54740","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":"info","ts":"2026-04-21T18:16:54.592331Z","caller":"traceutil/trace.go:171","msg":"trace[1469313120] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1365; }","duration":"2.251625947s","start":"2026-04-21T18:16:52.340693Z","end":"2026-04-21T18:16:54.592319Z","steps":["trace[1469313120] 'agreement among raft nodes before linearized reading' (duration: 2.251528554s)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:16:54.59217Z","caller":"traceutil/trace.go:171","msg":"trace[84867932] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1365; }","duration":"413.68701ms","start":"2026-04-21T18:16:54.178464Z","end":"2026-04-21T18:16:54.592151Z","steps":["trace[84867932] 'agreement among raft nodes before linearized reading' (duration: 413.434503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:54.59237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:52.340677Z","time spent":"2.251685119s","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-21T18:16:54.592199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.617586545s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-21T18:16:54.592504Z","caller":"traceutil/trace.go:171","msg":"trace[686750] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1365; }","duration":"1.617966106s","start":"2026-04-21T18:16:52.974529Z","end":"2026-04-21T18:16:54.592495Z","steps":["trace[686750] 'agreement among raft nodes before linearized reading' (duration: 1.617427201s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:54.592511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.042925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-21T18:16:54.592535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:52.974516Z","time spent":"1.618011497s","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-21T18:16:54.592557Z","caller":"traceutil/trace.go:171","msg":"trace[1381425986] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1365; }","duration":"689.119298ms","start":"2026-04-21T18:16:53.903427Z","end":"2026-04-21T18:16:54.592546Z","steps":["trace[1381425986] 'agreement among raft nodes before linearized reading' (duration: 689.043335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:16:54.592705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:53.903406Z","time spent":"689.279911ms","remote":"127.0.0.1:54726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-04-21T18:16:54.592882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T18:16:54.178445Z","time spent":"413.946927ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-21T18:18:00.016925Z","caller":"traceutil/trace.go:171","msg":"trace[218350142] transaction","detail":"{read_only:false; response_revision:1669; number_of_response:1; }","duration":"190.441728ms","start":"2026-04-21T18:17:59.826451Z","end":"2026-04-21T18:18:00.016893Z","steps":["trace[218350142] 'process raft request' (duration: 190.217272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:17.697216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.156582ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T18:18:17.697318Z","caller":"traceutil/trace.go:171","msg":"trace[854078076] transaction","detail":"{read_only:false; response_revision:1748; number_of_response:1; }","duration":"236.539143ms","start":"2026-04-21T18:18:17.46076Z","end":"2026-04-21T18:18:17.697299Z","steps":["trace[854078076] 'process raft request' (duration: 82.233677ms)","trace[854078076] 'compare' (duration: 153.872854ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:18:17.699977Z","caller":"traceutil/trace.go:171","msg":"trace[63305974] linearizableReadLoop","detail":"{readStateIndex:1821; appliedIndex:1818; }","duration":"174.724463ms","start":"2026-04-21T18:18:17.525177Z","end":"2026-04-21T18:18:17.699902Z","steps":["trace[63305974] 'read index received' (duration: 17.823004ms)","trace[63305974] 'applied index is now lower than readState.Index' (duration: 156.900619ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T18:18:17.7001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.902909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T18:18:17.700099Z","caller":"traceutil/trace.go:171","msg":"trace[509485064] transaction","detail":"{read_only:false; response_revision:1749; number_of_response:1; }","duration":"238.933472ms","start":"2026-04-21T18:18:17.461131Z","end":"2026-04-21T18:18:17.700065Z","steps":["trace[509485064] 'process raft request' (duration: 238.626563ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:17.700171Z","caller":"traceutil/trace.go:171","msg":"trace[38440833] transaction","detail":"{read_only:false; response_revision:1750; number_of_response:1; }","duration":"237.502181ms","start":"2026-04-21T18:18:17.46266Z","end":"2026-04-21T18:18:17.700162Z","steps":["trace[38440833] 'process raft request' (duration: 237.204243ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:17.700138Z","caller":"traceutil/trace.go:171","msg":"trace[987145575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1750; }","duration":"174.982171ms","start":"2026-04-21T18:18:17.525144Z","end":"2026-04-21T18:18:17.700126Z","steps":["trace[987145575] 'agreement among raft nodes before linearized reading' (duration: 174.905669ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:19.601154Z","caller":"traceutil/trace.go:171","msg":"trace[302475108] transaction","detail":"{read_only:false; response_revision:1755; number_of_response:1; }","duration":"114.011297ms","start":"2026-04-21T18:18:19.487107Z","end":"2026-04-21T18:18:19.601118Z","steps":["trace[302475108] 'process raft request' (duration: 56.398316ms)","trace[302475108] 'compare' (duration: 57.448057ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:18:27.963312Z","caller":"traceutil/trace.go:171","msg":"trace[2110964131] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"208.712506ms","start":"2026-04-21T18:18:27.754565Z","end":"2026-04-21T18:18:27.963277Z","steps":["trace[2110964131] 'process raft request' (duration: 182.111903ms)","trace[2110964131] 'compare' (duration: 26.228362ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:18:27.963609Z","caller":"traceutil/trace.go:171","msg":"trace[234647970] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"208.558852ms","start":"2026-04-21T18:18:27.75503Z","end":"2026-04-21T18:18:27.963589Z","steps":["trace[234647970] 'process raft request' (duration: 208.063768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:33.699253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.527731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T18:18:33.699931Z","caller":"traceutil/trace.go:171","msg":"trace[188938179] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1817; }","duration":"250.24943ms","start":"2026-04-21T18:18:33.449654Z","end":"2026-04-21T18:18:33.699903Z","steps":["trace[188938179] 'count revisions from in-memory index tree' (duration: 249.444569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:18:33.69947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.251105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-21T18:18:33.700232Z","caller":"traceutil/trace.go:171","msg":"trace[858652615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1817; }","duration":"120.062777ms","start":"2026-04-21T18:18:33.580147Z","end":"2026-04-21T18:18:33.70021Z","steps":["trace[858652615] 'range keys from in-memory index tree' (duration: 119.052658ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:55.301026Z","caller":"traceutil/trace.go:171","msg":"trace[58750543] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"134.216437ms","start":"2026-04-21T18:18:55.166776Z","end":"2026-04-21T18:18:55.300992Z","steps":["trace[58750543] 'process raft request' (duration: 133.990611ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:18:55.539199Z","caller":"traceutil/trace.go:171","msg":"trace[839411021] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"241.062392ms","start":"2026-04-21T18:18:55.298111Z","end":"2026-04-21T18:18:55.539173Z","steps":["trace[839411021] 'process raft request' (duration: 240.840556ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T18:20:52.138615Z","caller":"traceutil/trace.go:171","msg":"trace[697185639] linearizableReadLoop","detail":"{readStateIndex:2596; appliedIndex:2594; }","duration":"132.331121ms","start":"2026-04-21T18:20:52.006259Z","end":"2026-04-21T18:20:52.138591Z","steps":["trace[697185639] 'read index received' (duration: 6.290159ms)","trace[697185639] 'applied index is now lower than readState.Index' (duration: 126.040022ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T18:20:52.138736Z","caller":"traceutil/trace.go:171","msg":"trace[702291109] transaction","detail":"{read_only:false; response_revision:2492; number_of_response:1; }","duration":"196.027475ms","start":"2026-04-21T18:20:51.942692Z","end":"2026-04-21T18:20:52.138719Z","steps":["trace[702291109] 'process raft request' (duration: 195.731797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T18:20:52.138819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.540407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5164"} {"level":"info","ts":"2026-04-21T18:20:52.138873Z","caller":"traceutil/trace.go:171","msg":"trace[1278979999] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:2492; }","duration":"132.643559ms","start":"2026-04-21T18:20:52.006214Z","end":"2026-04-21T18:20:52.138857Z","steps":["trace[1278979999] 'agreement among raft nodes before linearized reading' (duration: 132.538896ms)"],"step_count":1}