{"level":"warn","ts":"2026-04-28T16:28:29.103697Z","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-28T16:28:29.103791Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-28T16:28:29.103866Z","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-28T16:28:29.103886Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-04-28T16:28:29.103913Z","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-28T16:28:29.104639Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-04-28T16:28:29.104719Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-28T16:28:29.157313Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"52.307331ms"} {"level":"info","ts":"2026-04-28T16:28:29.250559Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-04-28T16:28:29.250684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T16:28:29.250709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-04-28T16:28:29.250729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T16:28:29.250837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-04-28T16:28:29.250874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-04-28T16:28:29.305205Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T16:28:29.321104Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T16:28:29.328019Z","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-28T16:28:29.343285Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T16:28:29.343612Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T16:28:29.343746Z","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-28T16:28:29.343843Z","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-28T16:28:29.343867Z","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-28T16:28:29.345152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-04-28T16:28:29.347202Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-04-28T16:28:29.350674Z","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-28T16:28:29.350861Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-04-28T16:28:29.350968Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T16:28:29.350922Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-04-28T16:28:29.351005Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T16:28:29.751838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T16:28:29.751909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T16:28:29.751946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-04-28T16:28:29.751966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-04-28T16:28:29.751977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-04-28T16:28:29.751993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-04-28T16:28:29.752007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-04-28T16:28:29.821046Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T16:28:29.821077Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T16:28:29.821156Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T16:28:29.821306Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T16:28:29.821871Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T16:28:29.821924Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T16:28:29.824844Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T16:28:29.824948Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"warn","ts":"2026-04-28T16:28:30.075447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.494766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-28T16:28:30.075531Z","caller":"traceutil/trace.go:171","msg":"trace[1825096979] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; }","duration":"122.594841ms","start":"2026-04-28T16:28:29.952923Z","end":"2026-04-28T16:28:30.075518Z","steps":["trace[1825096979] 'agreement among raft nodes before linearized reading' (duration: 122.505127ms)"],"step_count":1} 2026/04/28 16:28:30 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-28T16:28:30.075644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.392962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-28T16:28:30.075664Z","caller":"traceutil/trace.go:171","msg":"trace[1610022725] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; }","duration":"122.678995ms","start":"2026-04-28T16:28:29.952981Z","end":"2026-04-28T16:28:30.07566Z","steps":["trace[1610022725] 'agreement among raft nodes before linearized reading' (duration: 122.406643ms)"],"step_count":1} 2026/04/28 16:28:30 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-04-28T16:28:30.109328Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T16:28:30.109439Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T16:28:30.10947Z","caller":"traceutil/trace.go:171","msg":"trace[976771570] linearizableReadLoop","detail":"{readStateIndex:4; appliedIndex:3; }","duration":"247.269052ms","start":"2026-04-28T16:28:29.862189Z","end":"2026-04-28T16:28:30.109458Z","steps":["trace[976771570] 'read index received' (duration: 247.102454ms)","trace[976771570] 'applied index is now lower than readState.Index' (duration: 165.778µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:28:30.109509Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-28T16:28:30.109648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.431469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:30.109693Z","caller":"traceutil/trace.go:171","msg":"trace[1654412516] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"247.495942ms","start":"2026-04-28T16:28:29.862183Z","end":"2026-04-28T16:28:30.109679Z","steps":["trace[1654412516] 'agreement among raft nodes before linearized reading' (duration: 247.337505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:30.110097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.719062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:30.110144Z","caller":"traceutil/trace.go:171","msg":"trace[474737093] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"247.799555ms","start":"2026-04-28T16:28:29.862335Z","end":"2026-04-28T16:28:30.110134Z","steps":["trace[474737093] 'agreement among raft nodes before linearized reading' (duration: 247.727962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:30.111354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.007748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:30.111415Z","caller":"traceutil/trace.go:171","msg":"trace[1679458617] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"100.085212ms","start":"2026-04-28T16:28:30.011319Z","end":"2026-04-28T16:28:30.111405Z","steps":["trace[1679458617] 'agreement among raft nodes before linearized reading' (duration: 100.007158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:30.111556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.065632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:30.111596Z","caller":"traceutil/trace.go:171","msg":"trace[522415638] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"113.118954ms","start":"2026-04-28T16:28:29.998469Z","end":"2026-04-28T16:28:30.111588Z","steps":["trace[522415638] 'agreement among raft nodes before linearized reading' (duration: 113.061281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:30.111736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.316293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:30.111771Z","caller":"traceutil/trace.go:171","msg":"trace[1982177789] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"113.369485ms","start":"2026-04-28T16:28:29.998393Z","end":"2026-04-28T16:28:30.111763Z","steps":["trace[1982177789] 'agreement among raft nodes before linearized reading' (duration: 113.320983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:30.111891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.381075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:30.111942Z","caller":"traceutil/trace.go:171","msg":"trace[1388259294] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"125.441299ms","start":"2026-04-28T16:28:29.986483Z","end":"2026-04-28T16:28:30.111924Z","steps":["trace[1388259294] 'agreement among raft nodes before linearized reading' (duration: 125.377716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:30.112149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.793614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:30.112189Z","caller":"traceutil/trace.go:171","msg":"trace[1027660292] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"125.856527ms","start":"2026-04-28T16:28:29.986324Z","end":"2026-04-28T16:28:30.11218Z","steps":["trace[1027660292] 'agreement among raft nodes before linearized reading' (duration: 125.800504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:30.112317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.099617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:30.112371Z","caller":"traceutil/trace.go:171","msg":"trace[1820078907] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"138.185711ms","start":"2026-04-28T16:28:29.974155Z","end":"2026-04-28T16:28:30.112341Z","steps":["trace[1820078907] 'agreement among raft nodes before linearized reading' (duration: 138.141839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:30.112497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.61496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:30.112528Z","caller":"traceutil/trace.go:171","msg":"trace[811452904] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"138.656512ms","start":"2026-04-28T16:28:29.973865Z","end":"2026-04-28T16:28:30.112521Z","steps":["trace[811452904] 'agreement among raft nodes before linearized reading' (duration: 138.61177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:30.112646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.760174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:30.112681Z","caller":"traceutil/trace.go:171","msg":"trace[1661151264] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"147.818256ms","start":"2026-04-28T16:28:29.964853Z","end":"2026-04-28T16:28:30.112671Z","steps":["trace[1661151264] 'agreement among raft nodes before linearized reading' (duration: 147.757754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:30.11286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.046297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:30.112913Z","caller":"traceutil/trace.go:171","msg":"trace[2129540965] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"148.13769ms","start":"2026-04-28T16:28:29.964762Z","end":"2026-04-28T16:28:30.112899Z","steps":["trace[2129540965] 'agreement among raft nodes before linearized reading' (duration: 148.061257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:30.113101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.261918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:30.113159Z","caller":"traceutil/trace.go:171","msg":"trace[1480413990] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"170.332821ms","start":"2026-04-28T16:28:29.942815Z","end":"2026-04-28T16:28:30.113148Z","steps":["trace[1480413990] 'agreement among raft nodes before linearized reading' (duration: 170.253217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:30.113374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.561371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:30.113415Z","caller":"traceutil/trace.go:171","msg":"trace[85493048] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"170.612563ms","start":"2026-04-28T16:28:29.942789Z","end":"2026-04-28T16:28:30.113402Z","steps":["trace[85493048] 'agreement among raft nodes before linearized reading' (duration: 170.558901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:30.113535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.230948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:30.113566Z","caller":"traceutil/trace.go:171","msg":"trace[1738673670] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"180.27311ms","start":"2026-04-28T16:28:29.933285Z","end":"2026-04-28T16:28:30.113558Z","steps":["trace[1738673670] 'agreement among raft nodes before linearized reading' (duration: 180.229428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:30.113695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.423496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:30.113725Z","caller":"traceutil/trace.go:171","msg":"trace[871549081] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"180.466639ms","start":"2026-04-28T16:28:29.933251Z","end":"2026-04-28T16:28:30.113718Z","steps":["trace[871549081] 'agreement among raft nodes before linearized reading' (duration: 180.423307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:30.113836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.716622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:30.113862Z","caller":"traceutil/trace.go:171","msg":"trace[1618233144] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"190.762384ms","start":"2026-04-28T16:28:29.923092Z","end":"2026-04-28T16:28:30.113855Z","steps":["trace[1618233144] 'agreement among raft nodes before linearized reading' (duration: 190.724882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:30.114064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.005624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:30.114123Z","caller":"traceutil/trace.go:171","msg":"trace[1299960954] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"191.093448ms","start":"2026-04-28T16:28:29.923017Z","end":"2026-04-28T16:28:30.114111Z","steps":["trace[1299960954] 'agreement among raft nodes before linearized reading' (duration: 190.980813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:30.114282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.383534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:30.114318Z","caller":"traceutil/trace.go:171","msg":"trace[1933491972] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"199.418905ms","start":"2026-04-28T16:28:29.914882Z","end":"2026-04-28T16:28:30.114301Z","steps":["trace[1933491972] 'agreement among raft nodes before linearized reading' (duration: 199.380534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:30.114433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.562782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:30.114457Z","caller":"traceutil/trace.go:171","msg":"trace[1755083768] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"199.615054ms","start":"2026-04-28T16:28:29.914836Z","end":"2026-04-28T16:28:30.114452Z","steps":["trace[1755083768] 'agreement among raft nodes before linearized reading' (duration: 199.577312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:30.114538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.308252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:30.11456Z","caller":"traceutil/trace.go:171","msg":"trace[1071122613] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"209.356464ms","start":"2026-04-28T16:28:29.905198Z","end":"2026-04-28T16:28:30.114555Z","steps":["trace[1071122613] 'agreement among raft nodes before linearized reading' (duration: 209.323493ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:31.227417Z","caller":"traceutil/trace.go:171","msg":"trace[1353625435] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"148.608028ms","start":"2026-04-28T16:28:31.078785Z","end":"2026-04-28T16:28:31.227393Z","steps":["trace[1353625435] 'process raft request' (duration: 148.376648ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:31.392346Z","caller":"traceutil/trace.go:171","msg":"trace[1411745103] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"311.577826ms","start":"2026-04-28T16:28:31.080743Z","end":"2026-04-28T16:28:31.392321Z","steps":["trace[1411745103] 'process raft request' (duration: 311.339136ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:31.392562Z","caller":"traceutil/trace.go:171","msg":"trace[722327822] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"239.059964ms","start":"2026-04-28T16:28:31.153469Z","end":"2026-04-28T16:28:31.392529Z","steps":["trace[722327822] 'process raft request' (duration: 238.74623ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:31.393066Z","caller":"traceutil/trace.go:171","msg":"trace[963543351] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"307.099853ms","start":"2026-04-28T16:28:31.085935Z","end":"2026-04-28T16:28:31.393034Z","steps":["trace[963543351] 'read index received' (duration: 141.411557ms)","trace[963543351] 'applied index is now lower than readState.Index' (duration: 165.687536ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:28:31.393146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:28:31.08072Z","time spent":"311.724042ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T16:28:31.39321Z","caller":"traceutil/trace.go:171","msg":"trace[45608373] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"238.081312ms","start":"2026-04-28T16:28:31.15511Z","end":"2026-04-28T16:28:31.393192Z","steps":["trace[45608373] 'process raft request' (duration: 237.861812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:31.393775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.955452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T16:28:31.393759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.701468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T16:28:31.39382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.01365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T16:28:31.39392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.521487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:31.393934Z","caller":"traceutil/trace.go:171","msg":"trace[258345423] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"191.187166ms","start":"2026-04-28T16:28:31.202719Z","end":"2026-04-28T16:28:31.393906Z","steps":["trace[258345423] 'agreement among raft nodes before linearized reading' (duration: 191.03197ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:31.393962Z","caller":"traceutil/trace.go:171","msg":"trace[201781549] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:0; response_revision:9; }","duration":"243.6149ms","start":"2026-04-28T16:28:31.15034Z","end":"2026-04-28T16:28:31.393955Z","steps":["trace[201781549] 'agreement among raft nodes before linearized reading' (duration: 243.544437ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:31.39402Z","caller":"traceutil/trace.go:171","msg":"trace[1457579656] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:9; }","duration":"241.887347ms","start":"2026-04-28T16:28:31.15198Z","end":"2026-04-28T16:28:31.393867Z","steps":["trace[1457579656] 'agreement among raft nodes before linearized reading' (duration: 241.666167ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:31.39324Z","caller":"traceutil/trace.go:171","msg":"trace[6594602] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"238.326792ms","start":"2026-04-28T16:28:31.154909Z","end":"2026-04-28T16:28:31.393236Z","steps":["trace[6594602] 'process raft request' (duration: 237.919285ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:31.393276Z","caller":"traceutil/trace.go:171","msg":"trace[1574197253] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"238.233239ms","start":"2026-04-28T16:28:31.155035Z","end":"2026-04-28T16:28:31.393268Z","steps":["trace[1574197253] 'process raft request' (duration: 237.911745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:31.393428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.475138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:31.394432Z","caller":"traceutil/trace.go:171","msg":"trace[938490506] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:9; }","duration":"308.721673ms","start":"2026-04-28T16:28:31.085679Z","end":"2026-04-28T16:28:31.3944Z","steps":["trace[938490506] 'agreement among raft nodes before linearized reading' (duration: 307.612545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:31.394504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:28:31.085666Z","time spent":"308.816406ms","remote":"127.0.0.1:59374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-04-28T16:28:31.393279Z","caller":"traceutil/trace.go:171","msg":"trace[1233734221] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"238.101552ms","start":"2026-04-28T16:28:31.155109Z","end":"2026-04-28T16:28:31.39321Z","steps":["trace[1233734221] 'process raft request' (duration: 237.885363ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:31.393839Z","caller":"traceutil/trace.go:171","msg":"trace[1857351214] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"243.020335ms","start":"2026-04-28T16:28:31.150809Z","end":"2026-04-28T16:28:31.39383Z","steps":["trace[1857351214] 'agreement among raft nodes before linearized reading' (duration: 242.932411ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:31.393233Z","caller":"traceutil/trace.go:171","msg":"trace[62378148] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"237.919435ms","start":"2026-04-28T16:28:31.1553Z","end":"2026-04-28T16:28:31.39322Z","steps":["trace[62378148] 'process raft request' (duration: 237.711606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:31.395201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.231914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:31.395264Z","caller":"traceutil/trace.go:171","msg":"trace[2090150983] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"266.903436ms","start":"2026-04-28T16:28:31.128343Z","end":"2026-04-28T16:28:31.395246Z","steps":["trace[2090150983] 'agreement among raft nodes before linearized reading' (duration: 265.210243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:31.640953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.451344ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-28T16:28:31.641182Z","caller":"traceutil/trace.go:171","msg":"trace[2064022258] linearizableReadLoop","detail":"{readStateIndex:27; appliedIndex:20; }","duration":"205.592778ms","start":"2026-04-28T16:28:31.435576Z","end":"2026-04-28T16:28:31.641169Z","steps":["trace[2064022258] 'read index received' (duration: 19.741722ms)","trace[2064022258] 'applied index is now lower than readState.Index' (duration: 185.850276ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:28:31.641151Z","caller":"traceutil/trace.go:171","msg":"trace[1547409012] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"206.932236ms","start":"2026-04-28T16:28:31.434189Z","end":"2026-04-28T16:28:31.641121Z","steps":["trace[1547409012] 'process raft request' (duration: 206.836862ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:31.641231Z","caller":"traceutil/trace.go:171","msg":"trace[62846639] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"206.702336ms","start":"2026-04-28T16:28:31.434516Z","end":"2026-04-28T16:28:31.641219Z","steps":["trace[62846639] 'process raft request' (duration: 206.572361ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:31.641258Z","caller":"traceutil/trace.go:171","msg":"trace[433478939] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"206.663285ms","start":"2026-04-28T16:28:31.434588Z","end":"2026-04-28T16:28:31.641252Z","steps":["trace[433478939] 'process raft request' (duration: 206.519028ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:31.641354Z","caller":"traceutil/trace.go:171","msg":"trace[576398207] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"205.931853ms","start":"2026-04-28T16:28:31.435417Z","end":"2026-04-28T16:28:31.641349Z","steps":["trace[576398207] 'process raft request' (duration: 205.705083ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:31.641392Z","caller":"traceutil/trace.go:171","msg":"trace[44964657] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"207.03605ms","start":"2026-04-28T16:28:31.434349Z","end":"2026-04-28T16:28:31.641385Z","steps":["trace[44964657] 'process raft request' (duration: 206.715997ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:31.641534Z","caller":"traceutil/trace.go:171","msg":"trace[295844518] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"187.528039ms","start":"2026-04-28T16:28:31.453997Z","end":"2026-04-28T16:28:31.641525Z","steps":["trace[295844518] 'process raft request' (duration: 187.146182ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:31.641551Z","caller":"traceutil/trace.go:171","msg":"trace[2111031158] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"207.550643ms","start":"2026-04-28T16:28:31.433985Z","end":"2026-04-28T16:28:31.641535Z","steps":["trace[2111031158] 'process raft request' (duration: 76.338967ms)","trace[2111031158] 'compare' (duration: 130.284416ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:28:31.641763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.163493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T16:28:31.641892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.096894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T16:28:31.641912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.556566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:31.641932Z","caller":"traceutil/trace.go:171","msg":"trace[1893039944] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:24; }","duration":"182.414698ms","start":"2026-04-28T16:28:31.459507Z","end":"2026-04-28T16:28:31.641922Z","steps":["trace[1893039944] 'agreement among raft nodes before linearized reading' (duration: 182.057992ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:31.642728Z","caller":"traceutil/trace.go:171","msg":"trace[1751758348] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:24; }","duration":"164.65058ms","start":"2026-04-28T16:28:31.477302Z","end":"2026-04-28T16:28:31.641953Z","steps":["trace[1751758348] 'agreement among raft nodes before linearized reading' (duration: 164.549206ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:31.641837Z","caller":"traceutil/trace.go:171","msg":"trace[1107850299] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:24; }","duration":"206.262817ms","start":"2026-04-28T16:28:31.435554Z","end":"2026-04-28T16:28:31.641817Z","steps":["trace[1107850299] 'agreement among raft nodes before linearized reading' (duration: 206.132662ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:31.931908Z","caller":"traceutil/trace.go:171","msg":"trace[1737512853] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"287.206672ms","start":"2026-04-28T16:28:31.644662Z","end":"2026-04-28T16:28:31.931869Z","steps":["trace[1737512853] 'process raft request' (duration: 242.555914ms)","trace[1737512853] 'compare' (duration: 44.493231ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:28:31.931962Z","caller":"traceutil/trace.go:171","msg":"trace[170234172] linearizableReadLoop","detail":"{readStateIndex:33; appliedIndex:28; }","duration":"286.013391ms","start":"2026-04-28T16:28:31.645933Z","end":"2026-04-28T16:28:31.931947Z","steps":["trace[170234172] 'read index received' (duration: 60.980403ms)","trace[170234172] 'applied index is now lower than readState.Index' (duration: 225.032478ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:28:31.932252Z","caller":"traceutil/trace.go:171","msg":"trace[1547683360] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"286.427738ms","start":"2026-04-28T16:28:31.645814Z","end":"2026-04-28T16:28:31.932241Z","steps":["trace[1547683360] 'process raft request' (duration: 286.00282ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:31.932425Z","caller":"traceutil/trace.go:171","msg":"trace[1709181279] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"285.705998ms","start":"2026-04-28T16:28:31.646705Z","end":"2026-04-28T16:28:31.932411Z","steps":["trace[1709181279] 'process raft request' (duration: 285.222587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:31.932496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.549403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:31.932526Z","caller":"traceutil/trace.go:171","msg":"trace[1320452128] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:33; }","duration":"286.625557ms","start":"2026-04-28T16:28:31.645891Z","end":"2026-04-28T16:28:31.932517Z","steps":["trace[1320452128] 'agreement among raft nodes before linearized reading' (duration: 286.546824ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:31.932576Z","caller":"traceutil/trace.go:171","msg":"trace[1097919925] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"286.687339ms","start":"2026-04-28T16:28:31.645879Z","end":"2026-04-28T16:28:31.932567Z","steps":["trace[1097919925] 'process raft request' (duration: 285.962748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:31.932574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.623863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:31.932637Z","caller":"traceutil/trace.go:171","msg":"trace[768518922] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:33; }","duration":"222.737698ms","start":"2026-04-28T16:28:31.709884Z","end":"2026-04-28T16:28:31.932622Z","steps":["trace[768518922] 'agreement among raft nodes before linearized reading' (duration: 222.619263ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:31.9327Z","caller":"traceutil/trace.go:171","msg":"trace[1097791740] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"286.803945ms","start":"2026-04-28T16:28:31.645888Z","end":"2026-04-28T16:28:31.932692Z","steps":["trace[1097791740] 'process raft request' (duration: 285.972229ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:31.932774Z","caller":"traceutil/trace.go:171","msg":"trace[1011709952] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"286.450359ms","start":"2026-04-28T16:28:31.646317Z","end":"2026-04-28T16:28:31.932767Z","steps":["trace[1011709952] 'process raft request' (duration: 285.599332ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:31.93283Z","caller":"traceutil/trace.go:171","msg":"trace[46460965] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"286.889609ms","start":"2026-04-28T16:28:31.645936Z","end":"2026-04-28T16:28:31.932825Z","steps":["trace[46460965] 'process raft request' (duration: 285.946928ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:31.932926Z","caller":"traceutil/trace.go:171","msg":"trace[1408918624] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"286.653549ms","start":"2026-04-28T16:28:31.646266Z","end":"2026-04-28T16:28:31.932919Z","steps":["trace[1408918624] 'process raft request' (duration: 285.630844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:32.166179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.219435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-28T16:28:32.166431Z","caller":"traceutil/trace.go:171","msg":"trace[147036742] linearizableReadLoop","detail":"{readStateIndex:40; appliedIndex:38; }","duration":"114.785492ms","start":"2026-04-28T16:28:32.051627Z","end":"2026-04-28T16:28:32.166412Z","steps":["trace[147036742] 'read index received' (duration: 61.493µs)","trace[147036742] 'applied index is now lower than readState.Index' (duration: 114.723009ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:28:32.166436Z","caller":"traceutil/trace.go:171","msg":"trace[2044107953] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"231.069991ms","start":"2026-04-28T16:28:31.93534Z","end":"2026-04-28T16:28:32.16641Z","steps":["trace[2044107953] 'process raft request' (duration: 113.475949ms)","trace[2044107953] 'compare' (duration: 117.000936ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:28:32.166543Z","caller":"traceutil/trace.go:171","msg":"trace[1388828430] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"229.44788ms","start":"2026-04-28T16:28:31.93702Z","end":"2026-04-28T16:28:32.166468Z","steps":["trace[1388828430] 'process raft request' (duration: 229.300164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:32.166751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.793241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:32.166814Z","caller":"traceutil/trace.go:171","msg":"trace[454731542] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:36; }","duration":"114.878775ms","start":"2026-04-28T16:28:32.051918Z","end":"2026-04-28T16:28:32.166796Z","steps":["trace[454731542] 'agreement among raft nodes before linearized reading' (duration: 114.682087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:32.166848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.446392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:32.166905Z","caller":"traceutil/trace.go:171","msg":"trace[100097125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:36; }","duration":"105.521946ms","start":"2026-04-28T16:28:32.061362Z","end":"2026-04-28T16:28:32.166884Z","steps":["trace[100097125] 'agreement among raft nodes before linearized reading' (duration: 105.428322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:32.167275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.652488ms","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-28T16:28:32.167321Z","caller":"traceutil/trace.go:171","msg":"trace[1056834142] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:36; }","duration":"115.737492ms","start":"2026-04-28T16:28:32.051568Z","end":"2026-04-28T16:28:32.167306Z","steps":["trace[1056834142] 'agreement among raft nodes before linearized reading' (duration: 114.99311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:32.511103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.229107ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-28T16:28:32.511298Z","caller":"traceutil/trace.go:171","msg":"trace[375845862] linearizableReadLoop","detail":"{readStateIndex:48; appliedIndex:46; }","duration":"269.984328ms","start":"2026-04-28T16:28:32.241291Z","end":"2026-04-28T16:28:32.511275Z","steps":["trace[375845862] 'read index received' (duration: 122.440228ms)","trace[375845862] 'applied index is now lower than readState.Index' (duration: 147.54253ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:28:32.511418Z","caller":"traceutil/trace.go:171","msg":"trace[31424672] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"270.906458ms","start":"2026-04-28T16:28:32.240492Z","end":"2026-04-28T16:28:32.511399Z","steps":["trace[31424672] 'process raft request' (duration: 123.226692ms)","trace[31424672] 'compare' (duration: 147.063479ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:28:32.511436Z","caller":"traceutil/trace.go:171","msg":"trace[121997815] transaction","detail":"{read_only:false; response_revision:44; number_of_response:1; }","duration":"270.626586ms","start":"2026-04-28T16:28:32.240782Z","end":"2026-04-28T16:28:32.511408Z","steps":["trace[121997815] 'process raft request' (duration: 270.433388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:32.511515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.208788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cluster-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:32.511555Z","caller":"traceutil/trace.go:171","msg":"trace[530313134] range","detail":"{range_begin:/registry/clusterroles/cluster-admin; range_end:; response_count:0; response_revision:44; }","duration":"270.270141ms","start":"2026-04-28T16:28:32.241272Z","end":"2026-04-28T16:28:32.511543Z","steps":["trace[530313134] 'agreement among raft nodes before linearized reading' (duration: 270.137685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:32.51158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.068244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:32.511667Z","caller":"traceutil/trace.go:171","msg":"trace[1591097330] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:44; }","duration":"256.165338ms","start":"2026-04-28T16:28:32.255479Z","end":"2026-04-28T16:28:32.511644Z","steps":["trace[1591097330] 'agreement among raft nodes before linearized reading' (duration: 256.022682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:32.511683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.541636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:32.511722Z","caller":"traceutil/trace.go:171","msg":"trace[615509379] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:44; }","duration":"112.624689ms","start":"2026-04-28T16:28:32.399084Z","end":"2026-04-28T16:28:32.511709Z","steps":["trace[615509379] 'agreement among raft nodes before linearized reading' (duration: 112.546806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:32.754193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.595152ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-28T16:28:32.754421Z","caller":"traceutil/trace.go:171","msg":"trace[1795797360] transaction","detail":"{read_only:false; response_revision:50; number_of_response:1; }","duration":"194.218684ms","start":"2026-04-28T16:28:32.560164Z","end":"2026-04-28T16:28:32.754382Z","steps":["trace[1795797360] 'process raft request' (duration: 91.238396ms)","trace[1795797360] 'compare' (duration: 102.31987ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:28:32.75454Z","caller":"traceutil/trace.go:171","msg":"trace[932199114] linearizableReadLoop","detail":"{readStateIndex:56; appliedIndex:53; }","duration":"188.105103ms","start":"2026-04-28T16:28:32.566411Z","end":"2026-04-28T16:28:32.754516Z","steps":["trace[932199114] 'read index received' (duration: 85.0048ms)","trace[932199114] 'applied index is now lower than readState.Index' (duration: 103.099153ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:28:32.75469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.258389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:32.754733Z","caller":"traceutil/trace.go:171","msg":"trace[205256023] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:52; }","duration":"188.329683ms","start":"2026-04-28T16:28:32.56639Z","end":"2026-04-28T16:28:32.75472Z","steps":["trace[205256023] 'agreement among raft nodes before linearized reading' (duration: 188.206447ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:32.754931Z","caller":"traceutil/trace.go:171","msg":"trace[3558730] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"194.414423ms","start":"2026-04-28T16:28:32.560471Z","end":"2026-04-28T16:28:32.754885Z","steps":["trace[3558730] 'process raft request' (duration: 193.89205ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:32.754956Z","caller":"traceutil/trace.go:171","msg":"trace[948602541] transaction","detail":"{read_only:false; response_revision:52; number_of_response:1; }","duration":"189.033023ms","start":"2026-04-28T16:28:32.5659Z","end":"2026-04-28T16:28:32.754933Z","steps":["trace[948602541] 'process raft request' (duration: 188.559573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:32.940194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.695193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:32.940311Z","caller":"traceutil/trace.go:171","msg":"trace[1305044430] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"169.703706ms","start":"2026-04-28T16:28:32.770447Z","end":"2026-04-28T16:28:32.940151Z","steps":["trace[1305044430] 'process raft request' (duration: 93.241761ms)","trace[1305044430] 'compare' (duration: 75.574707ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:28:32.940664Z","caller":"traceutil/trace.go:171","msg":"trace[149045294] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:57; }","duration":"128.206135ms","start":"2026-04-28T16:28:32.81242Z","end":"2026-04-28T16:28:32.940626Z","steps":["trace[149045294] 'agreement among raft nodes before linearized reading' (duration: 127.668442ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:32.940024Z","caller":"traceutil/trace.go:171","msg":"trace[2146722251] linearizableReadLoop","detail":"{readStateIndex:60; appliedIndex:58; }","duration":"127.492085ms","start":"2026-04-28T16:28:32.812477Z","end":"2026-04-28T16:28:32.939969Z","steps":["trace[2146722251] 'read index received' (duration: 26.956581ms)","trace[2146722251] 'applied index is now lower than readState.Index' (duration: 100.346785ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:28:32.943777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.177273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:basic-user\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:32.943938Z","caller":"traceutil/trace.go:171","msg":"trace[1316504076] range","detail":"{range_begin:/registry/clusterroles/system:basic-user; range_end:; response_count:0; response_revision:57; }","duration":"102.253726ms","start":"2026-04-28T16:28:32.841547Z","end":"2026-04-28T16:28:32.9438Z","steps":["trace[1316504076] 'agreement among raft nodes before linearized reading' (duration: 102.174033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:33.666083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.732127ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-28T16:28:33.666297Z","caller":"traceutil/trace.go:171","msg":"trace[715017790] linearizableReadLoop","detail":"{readStateIndex:64; appliedIndex:61; }","duration":"567.24057ms","start":"2026-04-28T16:28:33.099037Z","end":"2026-04-28T16:28:33.666277Z","steps":["trace[715017790] 'read index received' (duration: 143.298491ms)","trace[715017790] 'applied index is now lower than readState.Index' (duration: 423.940859ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:28:33.6664Z","caller":"traceutil/trace.go:171","msg":"trace[490738799] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"720.247908ms","start":"2026-04-28T16:28:32.946127Z","end":"2026-04-28T16:28:33.666375Z","steps":["trace[490738799] 'process raft request' (duration: 720.06079ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:33.666354Z","caller":"traceutil/trace.go:171","msg":"trace[916107987] transaction","detail":"{read_only:false; response_revision:58; number_of_response:1; }","duration":"720.261158ms","start":"2026-04-28T16:28:32.946028Z","end":"2026-04-28T16:28:33.666289Z","steps":["trace[916107987] 'process raft request' (duration: 149.238129ms)","trace[916107987] 'compare' (duration: 570.600472ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:28:33.666579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.548242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:33.666602Z","caller":"traceutil/trace.go:171","msg":"trace[525444593] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"720.130592ms","start":"2026-04-28T16:28:32.94639Z","end":"2026-04-28T16:28:33.66652Z","steps":["trace[525444593] 'process raft request' (duration: 719.84719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:33.666494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:28:32.946103Z","time spent":"720.352593ms","remote":"127.0.0.1:59340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":665,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T16:28:33.666617Z","caller":"traceutil/trace.go:171","msg":"trace[1782993550] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:60; }","duration":"567.616936ms","start":"2026-04-28T16:28:33.098988Z","end":"2026-04-28T16:28:33.666605Z","steps":["trace[1782993550] 'agreement among raft nodes before linearized reading' (duration: 567.552943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:33.666744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:28:33.098968Z","time spent":"567.742911ms","remote":"127.0.0.1:58918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T16:28:33.666784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:28:32.946013Z","time spent":"720.551311ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1089,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T16:28:33.667082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:28:32.946384Z","time spent":"720.569432ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":702,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T16:28:33.702692Z","caller":"traceutil/trace.go:171","msg":"trace[773800248] transaction","detail":"{read_only:false; response_revision:61; number_of_response:1; }","duration":"569.270306ms","start":"2026-04-28T16:28:33.133406Z","end":"2026-04-28T16:28:33.702676Z","steps":["trace[773800248] 'process raft request' (duration: 569.072297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:33.702695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.438836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T16:28:33.702714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.592115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:33.702762Z","caller":"traceutil/trace.go:171","msg":"trace[679746217] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:61; }","duration":"494.549451ms","start":"2026-04-28T16:28:33.208194Z","end":"2026-04-28T16:28:33.702744Z","steps":["trace[679746217] 'agreement among raft nodes before linearized reading' (duration: 494.374063ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:33.702766Z","caller":"traceutil/trace.go:171","msg":"trace[1634326594] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:61; }","duration":"576.672198ms","start":"2026-04-28T16:28:33.126079Z","end":"2026-04-28T16:28:33.702751Z","steps":["trace[1634326594] 'agreement among raft nodes before linearized reading' (duration: 576.543413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:33.702767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:28:33.133379Z","time spent":"569.357139ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3268,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T16:28:33.702806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:28:33.126063Z","time spent":"576.735281ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-04-28T16:28:33.702808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:28:33.208175Z","time spent":"494.619934ms","remote":"127.0.0.1:59374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-28T16:28:34.244472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.434196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-28T16:28:34.244767Z","caller":"traceutil/trace.go:171","msg":"trace[1870345594] transaction","detail":"{read_only:false; response_revision:62; number_of_response:1; }","duration":"574.031074ms","start":"2026-04-28T16:28:33.670706Z","end":"2026-04-28T16:28:34.244738Z","steps":["trace[1870345594] 'process raft request' (duration: 258.249254ms)","trace[1870345594] 'compare' (duration: 315.238037ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:28:34.244901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:28:33.670684Z","time spent":"574.150589ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T16:28:34.245022Z","caller":"traceutil/trace.go:171","msg":"trace[1145912616] transaction","detail":"{read_only:false; response_revision:63; number_of_response:1; }","duration":"572.936987ms","start":"2026-04-28T16:28:33.672001Z","end":"2026-04-28T16:28:34.244938Z","steps":["trace[1145912616] 'process raft request' (duration: 572.588173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:34.245066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.893885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:34.245152Z","caller":"traceutil/trace.go:171","msg":"trace[381396018] linearizableReadLoop","detail":"{readStateIndex:67; appliedIndex:65; }","duration":"542.410239ms","start":"2026-04-28T16:28:33.70254Z","end":"2026-04-28T16:28:34.244951Z","steps":["trace[381396018] 'read index received' (duration: 226.452931ms)","trace[381396018] 'applied index is now lower than readState.Index' (duration: 315.955358ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:28:34.245188Z","caller":"traceutil/trace.go:171","msg":"trace[211557093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:63; }","duration":"577.050031ms","start":"2026-04-28T16:28:33.668116Z","end":"2026-04-28T16:28:34.245166Z","steps":["trace[211557093] 'agreement among raft nodes before linearized reading' (duration: 576.839622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:34.245244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:28:33.668097Z","time spent":"577.133844ms","remote":"127.0.0.1:58916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T16:28:34.245311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.415852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3280"} {"level":"info","ts":"2026-04-28T16:28:34.245344Z","caller":"traceutil/trace.go:171","msg":"trace[2036720006] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:63; }","duration":"539.476515ms","start":"2026-04-28T16:28:33.705855Z","end":"2026-04-28T16:28:34.245332Z","steps":["trace[2036720006] 'agreement among raft nodes before linearized reading' (duration: 539.37468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:34.245313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:28:33.671982Z","time spent":"573.203919ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1120,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T16:28:34.24538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:28:33.705844Z","time spent":"539.529416ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3303,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-28T16:28:34.245346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.773647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:public-info-viewer\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T16:28:34.245495Z","caller":"traceutil/trace.go:171","msg":"trace[748303298] range","detail":"{range_begin:/registry/clusterroles/system:public-info-viewer; range_end:; response_count:0; response_revision:63; }","duration":"576.006657ms","start":"2026-04-28T16:28:33.669431Z","end":"2026-04-28T16:28:34.245437Z","steps":["trace[748303298] 'agreement among raft nodes before linearized reading' (duration: 575.555428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:34.245585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:28:33.669412Z","time spent":"576.160083ms","remote":"127.0.0.1:59340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:public-info-viewer\" "} {"level":"warn","ts":"2026-04-28T16:28:34.245631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.333849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3280"} {"level":"info","ts":"2026-04-28T16:28:34.246125Z","caller":"traceutil/trace.go:171","msg":"trace[1824847285] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:63; }","duration":"539.815439ms","start":"2026-04-28T16:28:33.706259Z","end":"2026-04-28T16:28:34.246075Z","steps":["trace[1824847285] 'agreement among raft nodes before linearized reading' (duration: 539.321668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:34.245693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.474636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3280"} {"level":"warn","ts":"2026-04-28T16:28:34.246209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:28:33.706247Z","time spent":"539.925203ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3303,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-28T16:28:34.246227Z","caller":"traceutil/trace.go:171","msg":"trace[1705438030] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:63; }","duration":"532.009439ms","start":"2026-04-28T16:28:33.714168Z","end":"2026-04-28T16:28:34.246177Z","steps":["trace[1705438030] 'agreement among raft nodes before linearized reading' (duration: 531.411694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:34.246288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:28:33.714146Z","time spent":"532.121184ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3303,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-28T16:28:34.668499Z","caller":"traceutil/trace.go:171","msg":"trace[1486686882] transaction","detail":"{read_only:false; response_revision:96; number_of_response:1; }","duration":"102.493329ms","start":"2026-04-28T16:28:34.56598Z","end":"2026-04-28T16:28:34.668473Z","steps":["trace[1486686882] 'process raft request' (duration: 50.769159ms)","trace[1486686882] 'compare' (duration: 51.588354ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:28:34.848171Z","caller":"traceutil/trace.go:171","msg":"trace[740127285] transaction","detail":"{read_only:false; response_revision:98; number_of_response:1; }","duration":"117.666093ms","start":"2026-04-28T16:28:34.730473Z","end":"2026-04-28T16:28:34.848139Z","steps":["trace[740127285] 'process raft request' (duration: 109.288693ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:35.254455Z","caller":"traceutil/trace.go:171","msg":"trace[807069964] transaction","detail":"{read_only:false; response_revision:109; number_of_response:1; }","duration":"193.152035ms","start":"2026-04-28T16:28:35.061273Z","end":"2026-04-28T16:28:35.254425Z","steps":["trace[807069964] 'process raft request' (duration: 148.007421ms)","trace[807069964] 'compare' (duration: 45.035049ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:28:42.616508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.797297ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T16:28:42.616701Z","caller":"traceutil/trace.go:171","msg":"trace[723607278] linearizableReadLoop","detail":"{readStateIndex:373; appliedIndex:370; }","duration":"312.684556ms","start":"2026-04-28T16:28:42.303999Z","end":"2026-04-28T16:28:42.616683Z","steps":["trace[723607278] 'read index received' (duration: 190.64966ms)","trace[723607278] 'applied index is now lower than readState.Index' (duration: 122.033616ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:28:42.616716Z","caller":"traceutil/trace.go:171","msg":"trace[958939194] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"352.107514ms","start":"2026-04-28T16:28:42.264567Z","end":"2026-04-28T16:28:42.616675Z","steps":["trace[958939194] 'process raft request' (duration: 230.082278ms)","trace[958939194] 'compare' (duration: 121.693093ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:28:42.616915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.867102ms","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-28T16:28:42.616934Z","caller":"traceutil/trace.go:171","msg":"trace[1847090454] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"332.38077ms","start":"2026-04-28T16:28:42.28454Z","end":"2026-04-28T16:28:42.616921Z","steps":["trace[1847090454] 'process raft request' (duration: 332.094839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:42.616931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.901215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-04-28T16:28:42.616985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:28:42.284525Z","time spent":"332.435182ms","remote":"127.0.0.1:59068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T16:28:42.616952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.84955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-28T16:28:42.617007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.805637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-28T16:28:42.617012Z","caller":"traceutil/trace.go:171","msg":"trace[32348149] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:361; }","duration":"313.025839ms","start":"2026-04-28T16:28:42.303967Z","end":"2026-04-28T16:28:42.616993Z","steps":["trace[32348149] 'agreement among raft nodes before linearized reading' (duration: 312.829202ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:42.617034Z","caller":"traceutil/trace.go:171","msg":"trace[912884372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:361; }","duration":"235.86454ms","start":"2026-04-28T16:28:42.381162Z","end":"2026-04-28T16:28:42.617027Z","steps":["trace[912884372] 'agreement among raft nodes before linearized reading' (duration: 235.816318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:42.617009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.765355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-28T16:28:42.617061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:28:42.303953Z","time spent":"313.099673ms","remote":"127.0.0.1:59170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-04-28T16:28:42.617042Z","caller":"traceutil/trace.go:171","msg":"trace[1333813212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:361; }","duration":"234.964195ms","start":"2026-04-28T16:28:42.382061Z","end":"2026-04-28T16:28:42.617026Z","steps":["trace[1333813212] 'agreement among raft nodes before linearized reading' (duration: 234.798268ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:42.617105Z","caller":"traceutil/trace.go:171","msg":"trace[1206093193] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:361; }","duration":"213.89885ms","start":"2026-04-28T16:28:42.403187Z","end":"2026-04-28T16:28:42.617086Z","steps":["trace[1206093193] 'agreement among raft nodes before linearized reading' (duration: 213.691132ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:42.616962Z","caller":"traceutil/trace.go:171","msg":"trace[1761423950] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:361; }","duration":"262.947865ms","start":"2026-04-28T16:28:42.354Z","end":"2026-04-28T16:28:42.616948Z","steps":["trace[1761423950] 'agreement among raft nodes before linearized reading' (duration: 262.82089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:42.617425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:28:42.264557Z","time spent":"352.225109ms","remote":"127.0.0.1:59340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T16:28:42.617719Z","caller":"traceutil/trace.go:171","msg":"trace[415973930] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"349.806385ms","start":"2026-04-28T16:28:42.267898Z","end":"2026-04-28T16:28:42.617704Z","steps":["trace[415973930] 'process raft request' (duration: 348.694044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:42.617781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:28:42.267884Z","time spent":"349.870117ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4355,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T16:28:42.737961Z","caller":"traceutil/trace.go:171","msg":"trace[1493537058] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"117.302495ms","start":"2026-04-28T16:28:42.620635Z","end":"2026-04-28T16:28:42.737938Z","steps":["trace[1493537058] 'process raft request' (duration: 111.306655ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:42.739605Z","caller":"traceutil/trace.go:171","msg":"trace[2023127811] linearizableReadLoop","detail":"{readStateIndex:375; appliedIndex:373; }","duration":"117.623808ms","start":"2026-04-28T16:28:42.621952Z","end":"2026-04-28T16:28:42.739576Z","steps":["trace[2023127811] 'read index received' (duration: 109.972885ms)","trace[2023127811] 'applied index is now lower than readState.Index' (duration: 7.649963ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:28:42.73966Z","caller":"traceutil/trace.go:171","msg":"trace[2128696464] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"116.201893ms","start":"2026-04-28T16:28:42.623447Z","end":"2026-04-28T16:28:42.739649Z","steps":["trace[2128696464] 'process raft request' (duration: 116.004056ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:42.739655Z","caller":"traceutil/trace.go:171","msg":"trace[219417126] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"116.850048ms","start":"2026-04-28T16:28:42.622775Z","end":"2026-04-28T16:28:42.739626Z","steps":["trace[219417126] 'process raft request' (duration: 116.62727ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:42.739789Z","caller":"traceutil/trace.go:171","msg":"trace[524576565] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"115.094161ms","start":"2026-04-28T16:28:42.624686Z","end":"2026-04-28T16:28:42.73978Z","steps":["trace[524576565] 'process raft request' (duration: 114.80887ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:42.739824Z","caller":"traceutil/trace.go:171","msg":"trace[402071777] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"117.842446ms","start":"2026-04-28T16:28:42.621975Z","end":"2026-04-28T16:28:42.739817Z","steps":["trace[402071777] 'process raft request' (duration: 117.387128ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:42.73986Z","caller":"traceutil/trace.go:171","msg":"trace[668041506] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"119.131586ms","start":"2026-04-28T16:28:42.620707Z","end":"2026-04-28T16:28:42.739839Z","steps":["trace[668041506] 'process raft request' (duration: 118.513922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:28:42.739974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.004482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-28T16:28:42.741107Z","caller":"traceutil/trace.go:171","msg":"trace[294634723] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:367; }","duration":"119.159336ms","start":"2026-04-28T16:28:42.62192Z","end":"2026-04-28T16:28:42.74108Z","steps":["trace[294634723] 'agreement among raft nodes before linearized reading' (duration: 117.939419ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:49.326816Z","caller":"traceutil/trace.go:171","msg":"trace[1350529245] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"130.299456ms","start":"2026-04-28T16:28:49.196485Z","end":"2026-04-28T16:28:49.326785Z","steps":["trace[1350529245] 'process raft request' (duration: 130.095959ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:28:52.456493Z","caller":"traceutil/trace.go:171","msg":"trace[1976493206] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"173.67695ms","start":"2026-04-28T16:28:52.282796Z","end":"2026-04-28T16:28:52.456473Z","steps":["trace[1976493206] 'process raft request' (duration: 127.087066ms)","trace[1976493206] 'compare' (duration: 46.48284ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:28:53.06915Z","caller":"traceutil/trace.go:171","msg":"trace[1415515390] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"138.550188ms","start":"2026-04-28T16:28:52.93057Z","end":"2026-04-28T16:28:53.06912Z","steps":["trace[1415515390] 'process raft request' (duration: 61.168066ms)","trace[1415515390] 'compare' (duration: 77.237847ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:29:07.216887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:06.60233Z","time spent":"614.553782ms","remote":"127.0.0.1:58980","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-28T16:29:07.749974Z","caller":"traceutil/trace.go:171","msg":"trace[945973211] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"998.164374ms","start":"2026-04-28T16:29:06.751776Z","end":"2026-04-28T16:29:07.74994Z","steps":["trace[945973211] 'process raft request' (duration: 997.862445ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:07.750008Z","caller":"traceutil/trace.go:171","msg":"trace[1786069658] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"992.972957ms","start":"2026-04-28T16:29:06.756992Z","end":"2026-04-28T16:29:07.749965Z","steps":["trace[1786069658] 'process raft request' (duration: 992.869304ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:07.750112Z","caller":"traceutil/trace.go:171","msg":"trace[304260407] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"985.780962ms","start":"2026-04-28T16:29:06.764302Z","end":"2026-04-28T16:29:07.750083Z","steps":["trace[304260407] 'process raft request' (duration: 985.590416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:07.750137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:06.756987Z","time spent":"993.088502ms","remote":"127.0.0.1:59004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T16:29:07.750199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:06.764294Z","time spent":"985.864464ms","remote":"127.0.0.1:59004","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-28T16:29:07.750173Z","caller":"traceutil/trace.go:171","msg":"trace[662527402] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"983.02346ms","start":"2026-04-28T16:29:06.767112Z","end":"2026-04-28T16:29:07.750135Z","steps":["trace[662527402] 'process raft request' (duration: 982.837464ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:07.750249Z","caller":"traceutil/trace.go:171","msg":"trace[859613223] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"962.999388ms","start":"2026-04-28T16:29:06.787228Z","end":"2026-04-28T16:29:07.750227Z","steps":["trace[859613223] 'process raft request' (duration: 962.856974ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:07.750255Z","caller":"traceutil/trace.go:171","msg":"trace[347672885] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"962.968648ms","start":"2026-04-28T16:29:06.787276Z","end":"2026-04-28T16:29:07.750245Z","steps":["trace[347672885] 'process raft request' (duration: 962.855874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:07.750309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:06.787223Z","time spent":"963.06104ms","remote":"127.0.0.1:59004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T16:29:07.750312Z","caller":"traceutil/trace.go:171","msg":"trace[1010044405] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"963.130962ms","start":"2026-04-28T16:29:06.78717Z","end":"2026-04-28T16:29:07.750301Z","steps":["trace[1010044405] 'process raft request' (duration: 962.852124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:07.75031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:06.78727Z","time spent":"963.017089ms","remote":"127.0.0.1:59004","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-28T16:29:07.750351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:06.767104Z","time spent":"983.146553ms","remote":"127.0.0.1:59004","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-28T16:29:07.75036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:06.78716Z","time spent":"963.178644ms","remote":"127.0.0.1:59004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T16:29:07.75012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:06.751754Z","time spent":"998.296937ms","remote":"127.0.0.1:59004","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-28T16:29:07.7506Z","caller":"traceutil/trace.go:171","msg":"trace[1905025440] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"943.313727ms","start":"2026-04-28T16:29:06.807278Z","end":"2026-04-28T16:29:07.750592Z","steps":["trace[1905025440] 'process raft request' (duration: 942.892555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:07.752782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:06.807216Z","time spent":"945.532793ms","remote":"127.0.0.1:59004","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-28T16:29:07.75136Z","caller":"traceutil/trace.go:171","msg":"trace[273064356] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"993.469072ms","start":"2026-04-28T16:29:06.756927Z","end":"2026-04-28T16:29:07.750396Z","steps":["trace[273064356] 'process raft request' (duration: 992.856784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:07.753066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:06.756906Z","time spent":"996.07398ms","remote":"127.0.0.1:59004","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-28T16:29:07.753871Z","caller":"traceutil/trace.go:171","msg":"trace[1142629138] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"930.112451ms","start":"2026-04-28T16:29:06.823739Z","end":"2026-04-28T16:29:07.753852Z","steps":["trace[1142629138] 'process raft request' (duration: 926.473983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:07.753935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:06.823712Z","time spent":"930.199604ms","remote":"127.0.0.1:59004","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-28T16:29:08.280693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.9689ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T16:29:08.280868Z","caller":"traceutil/trace.go:171","msg":"trace[1850067408] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"1.440897354s","start":"2026-04-28T16:29:06.839946Z","end":"2026-04-28T16:29:08.280843Z","steps":["trace[1850067408] 'process raft request' (duration: 916.697897ms)","trace[1850067408] 'compare' (duration: 523.832366ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:29:08.280951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:06.839924Z","time spent":"1.440989167s","remote":"127.0.0.1:59004","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-28T16:29:08.281286Z","caller":"traceutil/trace.go:171","msg":"trace[953365097] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.063266261s","start":"2026-04-28T16:29:07.218004Z","end":"2026-04-28T16:29:08.28127Z","steps":["trace[953365097] 'process raft request' (duration: 1.063193589s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:08.28139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:07.217982Z","time spent":"1.063356214s","remote":"127.0.0.1:58980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T16:29:08.281293Z","caller":"traceutil/trace.go:171","msg":"trace[120230287] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"1.339046537s","start":"2026-04-28T16:29:06.942225Z","end":"2026-04-28T16:29:08.281272Z","steps":["trace[120230287] 'process raft request' (duration: 1.338867652s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:08.281627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:06.942195Z","time spent":"1.339392357s","remote":"127.0.0.1:59004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T16:29:08.281754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.282309895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T16:29:08.28175Z","caller":"traceutil/trace.go:171","msg":"trace[1592596693] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:529; }","duration":"1.28214733s","start":"2026-04-28T16:29:06.999407Z","end":"2026-04-28T16:29:08.281554Z","steps":["trace[1592596693] 'read index received' (duration: 217.502639ms)","trace[1592596693] 'applied index is now lower than readState.Index' (duration: 1.064643081s)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:29:08.281812Z","caller":"traceutil/trace.go:171","msg":"trace[87444736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:523; }","duration":"1.282443949s","start":"2026-04-28T16:29:06.999354Z","end":"2026-04-28T16:29:08.281798Z","steps":["trace[87444736] 'agreement among raft nodes before linearized reading' (duration: 1.282279104s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:08.281841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:06.999337Z","time spent":"1.282495201s","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T16:29:08.288972Z","caller":"traceutil/trace.go:171","msg":"trace[1356180094] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"523.550884ms","start":"2026-04-28T16:29:07.765405Z","end":"2026-04-28T16:29:08.288956Z","steps":["trace[1356180094] 'process raft request' (duration: 523.422111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:08.289091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:07.765388Z","time spent":"523.648718ms","remote":"127.0.0.1:59004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T16:29:08.289234Z","caller":"traceutil/trace.go:171","msg":"trace[208756823] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"523.40713ms","start":"2026-04-28T16:29:07.765813Z","end":"2026-04-28T16:29:08.28922Z","steps":["trace[208756823] 'process raft request' (duration: 523.145733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:08.289295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:07.765805Z","time spent":"523.462963ms","remote":"127.0.0.1:59508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T16:29:08.289473Z","caller":"traceutil/trace.go:171","msg":"trace[1759333847] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"523.805462ms","start":"2026-04-28T16:29:07.765657Z","end":"2026-04-28T16:29:08.289462Z","steps":["trace[1759333847] 'process raft request' (duration: 523.243616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:08.289521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:07.76565Z","time spent":"523.850673ms","remote":"127.0.0.1:59508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T16:29:08.289628Z","caller":"traceutil/trace.go:171","msg":"trace[1820570779] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"523.877265ms","start":"2026-04-28T16:29:07.765742Z","end":"2026-04-28T16:29:08.289619Z","steps":["trace[1820570779] 'process raft request' (duration: 523.186785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:08.289676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:07.765735Z","time spent":"523.920816ms","remote":"127.0.0.1:59508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T16:29:08.289865Z","caller":"traceutil/trace.go:171","msg":"trace[1538008106] transaction","detail":"{read_only:false; number_of_response:1; response_revision:528; }","duration":"523.892405ms","start":"2026-04-28T16:29:07.765959Z","end":"2026-04-28T16:29:08.289852Z","steps":["trace[1538008106] 'process raft request' (duration: 523.05002ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:08.289942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:07.76595Z","time spent":"523.954876ms","remote":"127.0.0.1:59004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7366,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T16:29:08.290049Z","caller":"traceutil/trace.go:171","msg":"trace[1268957574] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"524.226634ms","start":"2026-04-28T16:29:07.765813Z","end":"2026-04-28T16:29:08.29004Z","steps":["trace[1268957574] 'process raft request' (duration: 523.165253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:08.290097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:07.765789Z","time spent":"524.287066ms","remote":"127.0.0.1:59508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T16:29:08.290283Z","caller":"traceutil/trace.go:171","msg":"trace[840569503] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"522.891985ms","start":"2026-04-28T16:29:07.767334Z","end":"2026-04-28T16:29:08.290225Z","steps":["trace[840569503] 'process raft request' (duration: 521.70194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:08.290429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:07.767327Z","time spent":"523.010598ms","remote":"127.0.0.1:59508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T16:29:08.290526Z","caller":"traceutil/trace.go:171","msg":"trace[42832729] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"521.669498ms","start":"2026-04-28T16:29:07.768844Z","end":"2026-04-28T16:29:08.290513Z","steps":["trace[42832729] 'process raft request' (duration: 520.223626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:08.290612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:07.768837Z","time spent":"521.728621ms","remote":"127.0.0.1:59004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T16:29:08.291364Z","caller":"traceutil/trace.go:171","msg":"trace[1856263242] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"519.126673ms","start":"2026-04-28T16:29:07.77222Z","end":"2026-04-28T16:29:08.291347Z","steps":["trace[1856263242] 'process raft request' (duration: 516.882617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:08.291463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:07.77219Z","time spent":"519.219686ms","remote":"127.0.0.1:59004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T16:29:08.291798Z","caller":"traceutil/trace.go:171","msg":"trace[1822240825] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"516.089912ms","start":"2026-04-28T16:29:07.775513Z","end":"2026-04-28T16:29:08.291603Z","steps":["trace[1822240825] 'process raft request' (duration: 515.68113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:08.291901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:07.775499Z","time spent":"516.350831ms","remote":"127.0.0.1:59004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T16:29:08.291999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.983937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T16:29:08.292322Z","caller":"traceutil/trace.go:171","msg":"trace[1778314603] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"519.349149ms","start":"2026-04-28T16:29:07.772959Z","end":"2026-04-28T16:29:08.292308Z","steps":["trace[1778314603] 'process raft request' (duration: 516.189666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:08.292398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158729406s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T16:29:08.292566Z","caller":"traceutil/trace.go:171","msg":"trace[359080954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:533; }","duration":"1.158957833s","start":"2026-04-28T16:29:07.133593Z","end":"2026-04-28T16:29:08.292551Z","steps":["trace[359080954] 'agreement among raft nodes before linearized reading' (duration: 1.158693225s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:08.292606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:07.133571Z","time spent":"1.159027415s","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T16:29:08.292443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:07.772951Z","time spent":"519.446522ms","remote":"127.0.0.1:59004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T16:29:08.293482Z","caller":"traceutil/trace.go:171","msg":"trace[46362610] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:533; }","duration":"783.050029ms","start":"2026-04-28T16:29:07.508981Z","end":"2026-04-28T16:29:08.292031Z","steps":["trace[46362610] 'agreement among raft nodes before linearized reading' (duration: 782.839683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:08.293554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:07.508921Z","time spent":"784.616924ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T16:29:09.638066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.303363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backends.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:18056"} {"level":"info","ts":"2026-04-28T16:29:09.63814Z","caller":"traceutil/trace.go:171","msg":"trace[2125930656] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backends.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:589; }","duration":"116.421277ms","start":"2026-04-28T16:29:09.521702Z","end":"2026-04-28T16:29:09.638123Z","steps":["trace[2125930656] 'range keys from in-memory index tree' (duration: 116.154529ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:09.658446Z","caller":"traceutil/trace.go:171","msg":"trace[401447376] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"127.874911ms","start":"2026-04-28T16:29:09.53055Z","end":"2026-04-28T16:29:09.658425Z","steps":["trace[401447376] 'process raft request' (duration: 127.725647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:10.218721Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828291258533807,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T16:29:10.719834Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828291258533807,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T16:29:11.219944Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828291258533807,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T16:29:11.433826Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.728491808s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-28T16:29:11.457499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:10.481927Z","time spent":"975.564495ms","remote":"127.0.0.1:42460","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-28T16:29:11.457686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.175220236s","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-28T16:29:11.457797Z","caller":"traceutil/trace.go:171","msg":"trace[607130864] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:590; }","duration":"1.17534557s","start":"2026-04-28T16:29:10.282435Z","end":"2026-04-28T16:29:11.45778Z","steps":["trace[607130864] 'range keys from in-memory index tree' (duration: 1.175206186s)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:11.458145Z","caller":"traceutil/trace.go:171","msg":"trace[336332003] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"1.739461466s","start":"2026-04-28T16:29:09.718665Z","end":"2026-04-28T16:29:11.458127Z","steps":["trace[336332003] 'read index received' (duration: 1.715312624s)","trace[336332003] 'applied index is now lower than readState.Index' (duration: 24.148072ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:29:11.458462Z","caller":"traceutil/trace.go:171","msg":"trace[826265541] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"1.753475314s","start":"2026-04-28T16:29:09.704975Z","end":"2026-04-28T16:29:11.45845Z","steps":["trace[826265541] 'process raft request' (duration: 1.729096906s)","trace[826265541] 'compare' (duration: 23.926175ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:29:11.458578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:09.704953Z","time spent":"1.753538176s","remote":"127.0.0.1:59004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208470,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T16:29:11.458931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136903088s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T16:29:11.459027Z","caller":"traceutil/trace.go:171","msg":"trace[1686329870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"1.137029872s","start":"2026-04-28T16:29:10.321976Z","end":"2026-04-28T16:29:11.459006Z","steps":["trace[1686329870] 'agreement among raft nodes before linearized reading' (duration: 1.136754224s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:11.45908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:10.321963Z","time spent":"1.137107164s","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T16:29:11.45922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.953864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T16:29:11.45927Z","caller":"traceutil/trace.go:171","msg":"trace[569605146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"968.015615ms","start":"2026-04-28T16:29:10.491238Z","end":"2026-04-28T16:29:11.459254Z","steps":["trace[569605146] 'agreement among raft nodes before linearized reading' (duration: 967.935123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:11.459304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:10.491229Z","time spent":"968.066867ms","remote":"127.0.0.1:58916","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-28T16:29:11.459329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.10653078s","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-28T16:29:11.459365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135382334s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T16:29:11.459363Z","caller":"traceutil/trace.go:171","msg":"trace[706537803] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:591; }","duration":"1.106577642s","start":"2026-04-28T16:29:10.352774Z","end":"2026-04-28T16:29:11.459352Z","steps":["trace[706537803] 'agreement among raft nodes before linearized reading' (duration: 1.106079828s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:11.4594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:10.352766Z","time spent":"1.106628513s","remote":"127.0.0.1:59254","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-28T16:29:11.459401Z","caller":"traceutil/trace.go:171","msg":"trace[1479202695] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:591; }","duration":"1.135429956s","start":"2026-04-28T16:29:10.323959Z","end":"2026-04-28T16:29:11.459389Z","steps":["trace[1479202695] 'agreement among raft nodes before linearized reading' (duration: 1.135325363s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:11.45943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:10.323953Z","time spent":"1.135470047s","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T16:29:11.45943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.137277199s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T16:29:11.459753Z","caller":"traceutil/trace.go:171","msg":"trace[489498515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"1.137628199s","start":"2026-04-28T16:29:10.322111Z","end":"2026-04-28T16:29:11.459739Z","steps":["trace[489498515] 'agreement among raft nodes before linearized reading' (duration: 1.137182746s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:11.459803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:10.322105Z","time spent":"1.13768443s","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T16:29:11.459428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.740738122s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:20866"} {"level":"info","ts":"2026-04-28T16:29:11.459892Z","caller":"traceutil/trace.go:171","msg":"trace[1901400634] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:591; }","duration":"1.741252157s","start":"2026-04-28T16:29:09.718628Z","end":"2026-04-28T16:29:11.45988Z","steps":["trace[1901400634] 'agreement among raft nodes before linearized reading' (duration: 1.740543417s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:11.459927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:09.718613Z","time spent":"1.741305329s","remote":"127.0.0.1:59004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":20889,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-04-28T16:29:14.842168Z","caller":"traceutil/trace.go:171","msg":"trace[1741339714] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"343.204989ms","start":"2026-04-28T16:29:14.498941Z","end":"2026-04-28T16:29:14.842146Z","steps":["trace[1741339714] 'read index received' (duration: 342.362065ms)","trace[1741339714] 'applied index is now lower than readState.Index' (duration: 841.814µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:29:14.84228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.325753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T16:29:14.842266Z","caller":"traceutil/trace.go:171","msg":"trace[1831994719] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"571.076444ms","start":"2026-04-28T16:29:14.271171Z","end":"2026-04-28T16:29:14.842247Z","steps":["trace[1831994719] 'process raft request' (duration: 570.359274ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:14.842316Z","caller":"traceutil/trace.go:171","msg":"trace[734395983] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"343.412825ms","start":"2026-04-28T16:29:14.498893Z","end":"2026-04-28T16:29:14.842306Z","steps":["trace[734395983] 'agreement among raft nodes before linearized reading' (duration: 343.341213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:14.842345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:14.498872Z","time spent":"343.466216ms","remote":"127.0.0.1:58918","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-28T16:29:14.8424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:14.27115Z","time spent":"571.188406ms","remote":"127.0.0.1:59062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T16:29:15.002325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.383416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T16:29:15.002398Z","caller":"traceutil/trace.go:171","msg":"trace[11470066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"158.456528ms","start":"2026-04-28T16:29:14.843913Z","end":"2026-04-28T16:29:15.00237Z","steps":["trace[11470066] 'range keys from in-memory index tree' (duration: 158.265043ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:15.030443Z","caller":"traceutil/trace.go:171","msg":"trace[1838645268] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"138.995019ms","start":"2026-04-28T16:29:14.89142Z","end":"2026-04-28T16:29:15.030415Z","steps":["trace[1838645268] 'process raft request' (duration: 138.803883ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:15.819832Z","caller":"traceutil/trace.go:171","msg":"trace[1865151963] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"337.728344ms","start":"2026-04-28T16:29:15.482079Z","end":"2026-04-28T16:29:15.819807Z","steps":["trace[1865151963] 'process raft request' (duration: 336.803059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:15.820103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:15.482058Z","time spent":"337.866728ms","remote":"127.0.0.1:59062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T16:29:15.819774Z","caller":"traceutil/trace.go:171","msg":"trace[331878259] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"287.214618ms","start":"2026-04-28T16:29:15.53252Z","end":"2026-04-28T16:29:15.819735Z","steps":["trace[331878259] 'read index received' (duration: 286.005475ms)","trace[331878259] 'applied index is now lower than readState.Index' (duration: 1.207333ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:29:15.820348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.827695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T16:29:15.820702Z","caller":"traceutil/trace.go:171","msg":"trace[43304413] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:609; }","duration":"288.101342ms","start":"2026-04-28T16:29:15.532462Z","end":"2026-04-28T16:29:15.820563Z","steps":["trace[43304413] 'agreement among raft nodes before linearized reading' (duration: 287.428214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:15.820663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.265914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T16:29:15.821032Z","caller":"traceutil/trace.go:171","msg":"trace[1103044191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:609; }","duration":"285.734277ms","start":"2026-04-28T16:29:15.535267Z","end":"2026-04-28T16:29:15.821001Z","steps":["trace[1103044191] 'agreement among raft nodes before linearized reading' (duration: 285.222013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:15.820757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.403078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-28T16:29:15.821118Z","caller":"traceutil/trace.go:171","msg":"trace[1365655278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:609; }","duration":"285.772268ms","start":"2026-04-28T16:29:15.535322Z","end":"2026-04-28T16:29:15.821094Z","steps":["trace[1365655278] 'agreement among raft nodes before linearized reading' (duration: 285.310285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:15.820722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.902284ms","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-28T16:29:15.821259Z","caller":"traceutil/trace.go:171","msg":"trace[1025000995] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:609; }","duration":"285.49392ms","start":"2026-04-28T16:29:15.535754Z","end":"2026-04-28T16:29:15.821248Z","steps":["trace[1025000995] 'agreement among raft nodes before linearized reading' (duration: 284.77184ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:15.953305Z","caller":"traceutil/trace.go:171","msg":"trace[308401025] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"127.886765ms","start":"2026-04-28T16:29:15.825387Z","end":"2026-04-28T16:29:15.953274Z","steps":["trace[308401025] 'process raft request' (duration: 92.118686ms)","trace[308401025] 'compare' (duration: 35.530852ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:29:15.953713Z","caller":"traceutil/trace.go:171","msg":"trace[1072778390] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"128.293526ms","start":"2026-04-28T16:29:15.825387Z","end":"2026-04-28T16:29:15.953681Z","steps":["trace[1072778390] 'process raft request' (duration: 127.799592ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:15.954026Z","caller":"traceutil/trace.go:171","msg":"trace[267463083] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"127.698649ms","start":"2026-04-28T16:29:15.826321Z","end":"2026-04-28T16:29:15.95402Z","steps":["trace[267463083] 'process raft request' (duration: 127.301328ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:15.954146Z","caller":"traceutil/trace.go:171","msg":"trace[42080373] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"127.651638ms","start":"2026-04-28T16:29:15.82648Z","end":"2026-04-28T16:29:15.954132Z","steps":["trace[42080373] 'process raft request' (duration: 127.403161ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:16.665398Z","caller":"traceutil/trace.go:171","msg":"trace[169588674] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"171.350184ms","start":"2026-04-28T16:29:16.494023Z","end":"2026-04-28T16:29:16.665373Z","steps":["trace[169588674] 'read index received' (duration: 168.223968ms)","trace[169588674] 'applied index is now lower than readState.Index' (duration: 3.125376ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:29:16.665594Z","caller":"traceutil/trace.go:171","msg":"trace[429897603] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"259.809517ms","start":"2026-04-28T16:29:16.405685Z","end":"2026-04-28T16:29:16.665495Z","steps":["trace[429897603] 'process raft request' (duration: 256.226869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:16.665601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.564729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T16:29:16.665918Z","caller":"traceutil/trace.go:171","msg":"trace[960946302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"171.922539ms","start":"2026-04-28T16:29:16.493985Z","end":"2026-04-28T16:29:16.665907Z","steps":["trace[960946302] 'agreement among raft nodes before linearized reading' (duration: 171.56993ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:17.420174Z","caller":"traceutil/trace.go:171","msg":"trace[114407706] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"728.157868ms","start":"2026-04-28T16:29:16.691984Z","end":"2026-04-28T16:29:17.420142Z","steps":["trace[114407706] 'process raft request' (duration: 666.957236ms)","trace[114407706] 'compare' (duration: 60.988156ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:29:17.420998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:16.691964Z","time spent":"728.94293ms","remote":"127.0.0.1:58980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T16:29:17.617954Z","caller":"traceutil/trace.go:171","msg":"trace[1829227631] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:643; }","duration":"194.149852ms","start":"2026-04-28T16:29:17.423785Z","end":"2026-04-28T16:29:17.617935Z","steps":["trace[1829227631] 'read index received' (duration: 156.234703ms)","trace[1829227631] 'applied index is now lower than readState.Index' (duration: 37.914099ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:29:17.618203Z","caller":"traceutil/trace.go:171","msg":"trace[1018566734] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"913.366844ms","start":"2026-04-28T16:29:16.704824Z","end":"2026-04-28T16:29:17.618191Z","steps":["trace[1018566734] 'process raft request' (duration: 875.250329ms)","trace[1018566734] 'compare' (duration: 37.515629ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:29:17.618294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:16.7048Z","time spent":"913.431186ms","remote":"127.0.0.1:59158","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-28T16:29:17.618364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.571143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-28T16:29:17.618431Z","caller":"traceutil/trace.go:171","msg":"trace[1312273898] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:621; }","duration":"194.671536ms","start":"2026-04-28T16:29:17.423743Z","end":"2026-04-28T16:29:17.618415Z","steps":["trace[1312273898] 'agreement among raft nodes before linearized reading' (duration: 194.542323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:17.618463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.621494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T16:29:17.618492Z","caller":"traceutil/trace.go:171","msg":"trace[710877346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"124.715857ms","start":"2026-04-28T16:29:17.493767Z","end":"2026-04-28T16:29:17.618483Z","steps":["trace[710877346] 'agreement among raft nodes before linearized reading' (duration: 124.674506ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:17.618724Z","caller":"traceutil/trace.go:171","msg":"trace[1875599229] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"898.702192ms","start":"2026-04-28T16:29:16.719962Z","end":"2026-04-28T16:29:17.618664Z","steps":["trace[1875599229] 'process raft request' (duration: 897.921071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:17.620019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:16.719941Z","time spent":"899.993608ms","remote":"127.0.0.1:59340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T16:29:19.003444Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828291258533953,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T16:29:19.504287Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828291258533953,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T16:29:20.004893Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828291258533953,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T16:29:20.493628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999925968s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-28T16:29:20.493713Z","caller":"traceutil/trace.go:171","msg":"trace[1053668172] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000079062s","start":"2026-04-28T16:29:18.49362Z","end":"2026-04-28T16:29:20.493699Z","steps":["trace[1053668172] 'agreement among raft nodes before linearized reading' (duration: 1.999973389s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:20.493748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:18.4936Z","time spent":"2.000142224s","remote":"127.0.0.1:58918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/28 16:29:20 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-28T16:29:20.505226Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828291258533953,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T16:29:21.005728Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828291258533953,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T16:29:21.026429Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.979438445s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-28T16:29:21.027532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.046921ms","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-28T16:29:21.027634Z","caller":"traceutil/trace.go:171","msg":"trace[974399200] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:621; }","duration":"744.156794ms","start":"2026-04-28T16:29:20.283462Z","end":"2026-04-28T16:29:21.027618Z","steps":["trace[974399200] 'range keys from in-memory index tree' (duration: 744.031241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:21.027615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.069951402s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T16:29:21.027689Z","caller":"traceutil/trace.go:171","msg":"trace[222932278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"3.070057634s","start":"2026-04-28T16:29:17.957614Z","end":"2026-04-28T16:29:21.027672Z","steps":["trace[222932278] 'range keys from in-memory index tree' (duration: 3.069825989s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:21.027723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:17.957609Z","time spent":"3.070106306s","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T16:29:21.027773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.070175997s","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-28T16:29:21.027849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.070792825s","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-28T16:29:21.02784Z","caller":"traceutil/trace.go:171","msg":"trace[2137844805] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"3.07027195s","start":"2026-04-28T16:29:17.95755Z","end":"2026-04-28T16:29:21.027822Z","steps":["trace[2137844805] 'range keys from in-memory index tree' (duration: 3.069977713s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:21.027847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.069783047s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-28T16:29:21.027873Z","caller":"traceutil/trace.go:171","msg":"trace[22631999] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"3.070852497s","start":"2026-04-28T16:29:17.957012Z","end":"2026-04-28T16:29:21.027865Z","steps":["trace[22631999] 'range keys from in-memory index tree' (duration: 3.070436175s)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:21.027894Z","caller":"traceutil/trace.go:171","msg":"trace[1235781825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:621; }","duration":"3.069879s","start":"2026-04-28T16:29:17.958001Z","end":"2026-04-28T16:29:21.02788Z","steps":["trace[1235781825] 'range keys from in-memory index tree' (duration: 3.069675675s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:21.027902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:17.95754Z","time spent":"3.070338242s","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T16:29:21.027909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:17.956992Z","time spent":"3.070901277s","remote":"127.0.0.1:59254","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-28T16:29:21.027931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:17.957994Z","time spent":"3.069929951s","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T16:29:21.027922Z","caller":"traceutil/trace.go:171","msg":"trace[1993849810] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"2.982141856s","start":"2026-04-28T16:29:18.045757Z","end":"2026-04-28T16:29:21.027899Z","steps":["trace[1993849810] 'process raft request' (duration: 2.981053088s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:21.028049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:18.045729Z","time spent":"2.982248599s","remote":"127.0.0.1:59062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T16:29:21.037793Z","caller":"traceutil/trace.go:171","msg":"trace[1012862946] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:645; }","duration":"2.54409341s","start":"2026-04-28T16:29:18.493685Z","end":"2026-04-28T16:29:21.037778Z","steps":["trace[1012862946] 'read index received' (duration: 2.532962888s)","trace[1012862946] 'applied index is now lower than readState.Index' (duration: 11.129722ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:29:21.037973Z","caller":"traceutil/trace.go:171","msg":"trace[1794289954] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"1.654147102s","start":"2026-04-28T16:29:19.383816Z","end":"2026-04-28T16:29:21.037963Z","steps":["trace[1794289954] 'process raft request' (duration: 1.653863364s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:21.038049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:19.383786Z","time spent":"1.654208254s","remote":"127.0.0.1:59254","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-28T16:29:21.061608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.290717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T16:29:21.061679Z","caller":"traceutil/trace.go:171","msg":"trace[1255257435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"567.3995ms","start":"2026-04-28T16:29:20.494262Z","end":"2026-04-28T16:29:21.061662Z","steps":["trace[1255257435] 'agreement among raft nodes before linearized reading' (duration: 567.252406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:21.061717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:20.494245Z","time spent":"567.463703ms","remote":"127.0.0.1:58916","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-28T16:29:21.777087Z","caller":"traceutil/trace.go:171","msg":"trace[1428062211] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"124.095105ms","start":"2026-04-28T16:29:21.652972Z","end":"2026-04-28T16:29:21.777067Z","steps":["trace[1428062211] 'process raft request' (duration: 123.695705ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:23.456023Z","caller":"traceutil/trace.go:171","msg":"trace[1128288057] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"615.824041ms","start":"2026-04-28T16:29:22.84018Z","end":"2026-04-28T16:29:23.456004Z","steps":["trace[1128288057] 'process raft request' (duration: 615.429751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:23.456229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:22.84016Z","time spent":"616.026696ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T16:29:23.461152Z","caller":"traceutil/trace.go:171","msg":"trace[406983439] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:674; }","duration":"396.315283ms","start":"2026-04-28T16:29:23.064801Z","end":"2026-04-28T16:29:23.461116Z","steps":["trace[406983439] 'read index received' (duration: 390.826281ms)","trace[406983439] 'applied index is now lower than readState.Index' (duration: 5.487972ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:29:23.461269Z","caller":"traceutil/trace.go:171","msg":"trace[236164531] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"517.42893ms","start":"2026-04-28T16:29:22.94383Z","end":"2026-04-28T16:29:23.461258Z","steps":["trace[236164531] 'process raft request' (duration: 517.123923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:23.461371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:22.943797Z","time spent":"517.497782ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T16:29:23.461523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.027866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T16:29:23.461609Z","caller":"traceutil/trace.go:171","msg":"trace[515304851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:651; }","duration":"396.144788ms","start":"2026-04-28T16:29:23.065446Z","end":"2026-04-28T16:29:23.461591Z","steps":["trace[515304851] 'agreement among raft nodes before linearized reading' (duration: 395.84213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:23.461617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.803025ms","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-28T16:29:23.461678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:23.065433Z","time spent":"396.233131ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T16:29:23.461698Z","caller":"traceutil/trace.go:171","msg":"trace[258428243] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:651; }","duration":"396.905448ms","start":"2026-04-28T16:29:23.064765Z","end":"2026-04-28T16:29:23.461671Z","steps":["trace[258428243] 'agreement among raft nodes before linearized reading' (duration: 396.797635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:23.461725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:23.064745Z","time spent":"396.97315ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T16:29:23.46179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.921407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-28T16:29:23.461827Z","caller":"traceutil/trace.go:171","msg":"trace[250740717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:651; }","duration":"382.992069ms","start":"2026-04-28T16:29:23.078824Z","end":"2026-04-28T16:29:23.461816Z","steps":["trace[250740717] 'agreement among raft nodes before linearized reading' (duration: 382.917007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:23.461852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:23.078811Z","time spent":"383.03603ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T16:29:23.46186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.133818ms","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-28T16:29:23.461922Z","caller":"traceutil/trace.go:171","msg":"trace[766979988] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:651; }","duration":"396.21704ms","start":"2026-04-28T16:29:23.065693Z","end":"2026-04-28T16:29:23.46191Z","steps":["trace[766979988] 'agreement among raft nodes before linearized reading' (duration: 396.099047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:23.461954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:23.065681Z","time spent":"396.265241ms","remote":"127.0.0.1:59254","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-28T16:29:24.940242Z","caller":"traceutil/trace.go:171","msg":"trace[632579415] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"199.691308ms","start":"2026-04-28T16:29:24.740531Z","end":"2026-04-28T16:29:24.940223Z","steps":["trace[632579415] 'process raft request' (duration: 199.570205ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:25.033114Z","caller":"traceutil/trace.go:171","msg":"trace[1817660492] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:709; }","duration":"166.983475ms","start":"2026-04-28T16:29:24.866101Z","end":"2026-04-28T16:29:25.033085Z","steps":["trace[1817660492] 'read index received' (duration: 74.196818ms)","trace[1817660492] 'applied index is now lower than readState.Index' (duration: 92.784807ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:29:25.033271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.141289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T16:29:25.033495Z","caller":"traceutil/trace.go:171","msg":"trace[1326889469] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:685; }","duration":"167.420886ms","start":"2026-04-28T16:29:24.866052Z","end":"2026-04-28T16:29:25.033473Z","steps":["trace[1326889469] 'agreement among raft nodes before linearized reading' (duration: 167.151049ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:25.033183Z","caller":"traceutil/trace.go:171","msg":"trace[394211757] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"280.111229ms","start":"2026-04-28T16:29:24.753037Z","end":"2026-04-28T16:29:25.033148Z","steps":["trace[394211757] 'process raft request' (duration: 279.739309ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:27.837101Z","caller":"traceutil/trace.go:171","msg":"trace[994436606] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"115.674886ms","start":"2026-04-28T16:29:27.721392Z","end":"2026-04-28T16:29:27.837067Z","steps":["trace[994436606] 'process raft request' (duration: 115.42115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:28.094998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.911867ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T16:29:28.095202Z","caller":"traceutil/trace.go:171","msg":"trace[1948765298] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:749; }","duration":"229.982241ms","start":"2026-04-28T16:29:27.865206Z","end":"2026-04-28T16:29:28.095188Z","steps":["trace[1948765298] 'read index received' (duration: 52.868319ms)","trace[1948765298] 'applied index is now lower than readState.Index' (duration: 177.113282ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:29:28.095379Z","caller":"traceutil/trace.go:171","msg":"trace[1143306339] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"247.92634ms","start":"2026-04-28T16:29:27.847384Z","end":"2026-04-28T16:29:28.095311Z","steps":["trace[1143306339] 'process raft request' (duration: 70.621913ms)","trace[1143306339] 'compare' (duration: 176.815874ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:29:28.095466Z","caller":"traceutil/trace.go:171","msg":"trace[108432734] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"243.611202ms","start":"2026-04-28T16:29:27.851833Z","end":"2026-04-28T16:29:28.095444Z","steps":["trace[108432734] 'process raft request' (duration: 243.258193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:28.095464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.237478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-04-28T16:29:28.095688Z","caller":"traceutil/trace.go:171","msg":"trace[1143791016] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:725; }","duration":"230.495845ms","start":"2026-04-28T16:29:27.865177Z","end":"2026-04-28T16:29:28.095673Z","steps":["trace[1143791016] 'agreement among raft nodes before linearized reading' (duration: 230.080934ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:28.217193Z","caller":"traceutil/trace.go:171","msg":"trace[576383125] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"115.810947ms","start":"2026-04-28T16:29:28.101362Z","end":"2026-04-28T16:29:28.217173Z","steps":["trace[576383125] 'process raft request' (duration: 113.618953ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:28.226501Z","caller":"traceutil/trace.go:171","msg":"trace[1737870180] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"124.294837ms","start":"2026-04-28T16:29:28.102188Z","end":"2026-04-28T16:29:28.226483Z","steps":["trace[1737870180] 'process raft request' (duration: 124.142283ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:28.368821Z","caller":"traceutil/trace.go:171","msg":"trace[1782514759] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"126.332028ms","start":"2026-04-28T16:29:28.242463Z","end":"2026-04-28T16:29:28.368795Z","steps":["trace[1782514759] 'process raft request' (duration: 99.148355ms)","trace[1782514759] 'compare' (duration: 27.06326ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:29:29.654654Z","caller":"traceutil/trace.go:171","msg":"trace[1692839345] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"137.61736ms","start":"2026-04-28T16:29:29.517011Z","end":"2026-04-28T16:29:29.654628Z","steps":["trace[1692839345] 'process raft request' (duration: 136.435541ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:29.847046Z","caller":"traceutil/trace.go:171","msg":"trace[662937210] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"187.411643ms","start":"2026-04-28T16:29:29.659612Z","end":"2026-04-28T16:29:29.847024Z","steps":["trace[662937210] 'process raft request' (duration: 187.345291ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:29.847123Z","caller":"traceutil/trace.go:171","msg":"trace[41713120] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"188.890249ms","start":"2026-04-28T16:29:29.658202Z","end":"2026-04-28T16:29:29.847092Z","steps":["trace[41713120] 'process raft request' (duration: 121.77366ms)","trace[41713120] 'compare' (duration: 66.793341ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:29:29.847328Z","caller":"traceutil/trace.go:171","msg":"trace[1792839577] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"188.184222ms","start":"2026-04-28T16:29:29.659114Z","end":"2026-04-28T16:29:29.847299Z","steps":["trace[1792839577] 'process raft request' (duration: 187.786792ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:29.847491Z","caller":"traceutil/trace.go:171","msg":"trace[1484391467] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"184.85627ms","start":"2026-04-28T16:29:29.662612Z","end":"2026-04-28T16:29:29.847468Z","steps":["trace[1484391467] 'process raft request' (duration: 184.596233ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:29.84756Z","caller":"traceutil/trace.go:171","msg":"trace[2055320266] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"187.474644ms","start":"2026-04-28T16:29:29.660059Z","end":"2026-04-28T16:29:29.847533Z","steps":["trace[2055320266] 'process raft request' (duration: 186.934151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:29.848074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.398852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:2075"} {"level":"info","ts":"2026-04-28T16:29:29.848138Z","caller":"traceutil/trace.go:171","msg":"trace[551641307] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:741; }","duration":"187.493885ms","start":"2026-04-28T16:29:29.660629Z","end":"2026-04-28T16:29:29.848123Z","steps":["trace[551641307] 'agreement among raft nodes before linearized reading' (duration: 187.372772ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:29.847969Z","caller":"traceutil/trace.go:171","msg":"trace[1687032093] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:761; }","duration":"187.223768ms","start":"2026-04-28T16:29:29.66065Z","end":"2026-04-28T16:29:29.847874Z","steps":["trace[1687032093] 'read index received' (duration: 38.838393ms)","trace[1687032093] 'applied index is now lower than readState.Index' (duration: 148.383125ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:29:29.848774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.721411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T16:29:29.848823Z","caller":"traceutil/trace.go:171","msg":"trace[1176775779] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:741; }","duration":"174.810854ms","start":"2026-04-28T16:29:29.673994Z","end":"2026-04-28T16:29:29.848805Z","steps":["trace[1176775779] 'agreement among raft nodes before linearized reading' (duration: 174.732282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:29.848868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.248229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T16:29:29.848915Z","caller":"traceutil/trace.go:171","msg":"trace[837368853] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:741; }","duration":"174.325441ms","start":"2026-04-28T16:29:29.674577Z","end":"2026-04-28T16:29:29.848902Z","steps":["trace[837368853] 'agreement among raft nodes before linearized reading' (duration: 174.224669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:29.849039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.955107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T16:29:29.849066Z","caller":"traceutil/trace.go:171","msg":"trace[1990333818] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:741; }","duration":"175.006928ms","start":"2026-04-28T16:29:29.674051Z","end":"2026-04-28T16:29:29.849058Z","steps":["trace[1990333818] 'agreement among raft nodes before linearized reading' (duration: 174.958237ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:33.95087Z","caller":"traceutil/trace.go:171","msg":"trace[1395027388] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"112.2167ms","start":"2026-04-28T16:29:33.838626Z","end":"2026-04-28T16:29:33.950842Z","steps":["trace[1395027388] 'process raft request' (duration: 101.842513ms)","trace[1395027388] 'compare' (duration: 10.0598ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:29:34.932858Z","caller":"traceutil/trace.go:171","msg":"trace[156579744] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"977.970903ms","start":"2026-04-28T16:29:33.954867Z","end":"2026-04-28T16:29:34.932838Z","steps":["trace[156579744] 'process raft request' (duration: 977.828649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:34.932993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:33.954833Z","time spent":"978.090696ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T16:29:35.579528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.0589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T16:29:35.580205Z","caller":"traceutil/trace.go:171","msg":"trace[1842903855] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:798; }","duration":"1.087932116s","start":"2026-04-28T16:29:34.492252Z","end":"2026-04-28T16:29:35.580184Z","steps":["trace[1842903855] 'read index received' (duration: 440.455511ms)","trace[1842903855] 'applied index is now lower than readState.Index' (duration: 647.475705ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:29:35.580342Z","caller":"traceutil/trace.go:171","msg":"trace[1048040274] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"1.624760351s","start":"2026-04-28T16:29:33.955564Z","end":"2026-04-28T16:29:35.580324Z","steps":["trace[1048040274] 'process raft request' (duration: 1.624501565s)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:35.58038Z","caller":"traceutil/trace.go:171","msg":"trace[1705390347] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"1.625064269s","start":"2026-04-28T16:29:33.955274Z","end":"2026-04-28T16:29:35.580338Z","steps":["trace[1705390347] 'process raft request' (duration: 1.135098927s)","trace[1705390347] 'compare' (duration: 488.673841ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:29:35.580479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:33.95555Z","time spent":"1.624871645s","remote":"127.0.0.1:59254","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-28T16:29:35.580602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.003674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T16:29:35.58064Z","caller":"traceutil/trace.go:171","msg":"trace[1097588699] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:775; }","duration":"705.140037ms","start":"2026-04-28T16:29:34.875493Z","end":"2026-04-28T16:29:35.580633Z","steps":["trace[1097588699] 'agreement among raft nodes before linearized reading' (duration: 705.074345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:35.580659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:34.875471Z","time spent":"705.183038ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-28T16:29:35.580501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:33.955255Z","time spent":"1.625181922s","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T16:29:35.580758Z","caller":"traceutil/trace.go:171","msg":"trace[263012476] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"1.624135807s","start":"2026-04-28T16:29:33.956588Z","end":"2026-04-28T16:29:35.580724Z","steps":["trace[263012476] 'process raft request' (duration: 1.623555873s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:35.580894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:33.956574Z","time spent":"1.624249339s","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T16:29:35.580794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.08854563s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T16:29:35.580964Z","caller":"traceutil/trace.go:171","msg":"trace[1013714207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:775; }","duration":"1.088730515s","start":"2026-04-28T16:29:34.49221Z","end":"2026-04-28T16:29:35.580941Z","steps":["trace[1013714207] 'agreement among raft nodes before linearized reading' (duration: 1.088566941s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:35.581037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:34.492187Z","time spent":"1.088833138s","remote":"127.0.0.1:58918","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-28T16:29:40.28787Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828291258534266,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T16:29:40.788532Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828291258534266,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T16:29:41.288775Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828291258534266,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T16:29:41.426492Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.805365838s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T16:29:41.427137Z","caller":"traceutil/trace.go:171","msg":"trace[381170009] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"1.806123445s","start":"2026-04-28T16:29:39.620973Z","end":"2026-04-28T16:29:41.427097Z","steps":["trace[381170009] 'process raft request' (duration: 1.805799437s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:41.427443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:39.620948Z","time spent":"1.806380001s","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T16:29:41.436533Z","caller":"traceutil/trace.go:171","msg":"trace[4857939] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"1.815460165s","start":"2026-04-28T16:29:39.62105Z","end":"2026-04-28T16:29:41.43651Z","steps":["trace[4857939] 'process raft request' (duration: 1.815224389s)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:41.436818Z","caller":"traceutil/trace.go:171","msg":"trace[1079259227] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:820; }","duration":"1.650007202s","start":"2026-04-28T16:29:39.786766Z","end":"2026-04-28T16:29:41.436773Z","steps":["trace[1079259227] 'read index received' (duration: 1.640158541s)","trace[1079259227] 'applied index is now lower than readState.Index' (duration: 9.847431ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:29:41.436864Z","caller":"traceutil/trace.go:171","msg":"trace[1640570063] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"1.815480864s","start":"2026-04-28T16:29:39.621361Z","end":"2026-04-28T16:29:41.436842Z","steps":["trace[1640570063] 'process raft request' (duration: 1.815105186s)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:41.43683Z","caller":"traceutil/trace.go:171","msg":"trace[1191622570] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"1.736644882s","start":"2026-04-28T16:29:39.700164Z","end":"2026-04-28T16:29:41.436809Z","steps":["trace[1191622570] 'process raft request' (duration: 1.736486829s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:41.436983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:39.62135Z","time spent":"1.815553687s","remote":"127.0.0.1:59254","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-28T16:29:41.437041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.620446079s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-fpjgl\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-28T16:29:41.437082Z","caller":"traceutil/trace.go:171","msg":"trace[454571673] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-fpjgl; range_end:; response_count:1; response_revision:795; }","duration":"1.62051719s","start":"2026-04-28T16:29:39.816554Z","end":"2026-04-28T16:29:41.437072Z","steps":["trace[454571673] 'agreement among raft nodes before linearized reading' (duration: 1.620394788s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:41.437062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:39.700138Z","time spent":"1.736833106s","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T16:29:41.437168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.136354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-28T16:29:41.437191Z","caller":"traceutil/trace.go:171","msg":"trace[2023553957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:795; }","duration":"471.198235ms","start":"2026-04-28T16:29:40.965987Z","end":"2026-04-28T16:29:41.437186Z","steps":["trace[2023553957] 'agreement among raft nodes before linearized reading' (duration: 471.136763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:41.437213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:40.96597Z","time spent":"471.236896ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T16:29:41.437183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.65040799s","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-28T16:29:41.437284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.116294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T16:29:41.43732Z","caller":"traceutil/trace.go:171","msg":"trace[153481876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"945.178795ms","start":"2026-04-28T16:29:40.492134Z","end":"2026-04-28T16:29:41.437313Z","steps":["trace[153481876] 'agreement among raft nodes before linearized reading' (duration: 945.134824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:41.437337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:40.492119Z","time spent":"945.214466ms","remote":"127.0.0.1:58918","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-28T16:29:41.43711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:39.816539Z","time spent":"1.620564942s","remote":"127.0.0.1:59158","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-fpjgl\" "} {"level":"info","ts":"2026-04-28T16:29:41.437385Z","caller":"traceutil/trace.go:171","msg":"trace[1844313670] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:795; }","duration":"1.650588825s","start":"2026-04-28T16:29:39.786719Z","end":"2026-04-28T16:29:41.437308Z","steps":["trace[1844313670] 'agreement among raft nodes before linearized reading' (duration: 1.650158435s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:41.437485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:39.786681Z","time spent":"1.650792089s","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T16:29:41.43734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.426778677s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T16:29:41.437592Z","caller":"traceutil/trace.go:171","msg":"trace[2139456152] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:795; }","duration":"1.427019242s","start":"2026-04-28T16:29:40.010527Z","end":"2026-04-28T16:29:41.437546Z","steps":["trace[2139456152] 'agreement among raft nodes before linearized reading' (duration: 1.426754546s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:41.437657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:40.01048Z","time spent":"1.427159595s","remote":"127.0.0.1:59306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-04-28T16:29:41.438108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:39.621033Z","time spent":"1.815592717s","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T16:29:52.652545Z","caller":"traceutil/trace.go:171","msg":"trace[1941166753] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"195.302766ms","start":"2026-04-28T16:29:52.457216Z","end":"2026-04-28T16:29:52.652519Z","steps":["trace[1941166753] 'process raft request' (duration: 195.054551ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:52.698484Z","caller":"traceutil/trace.go:171","msg":"trace[1677896810] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:967; }","duration":"210.597134ms","start":"2026-04-28T16:29:52.487868Z","end":"2026-04-28T16:29:52.698465Z","steps":["trace[1677896810] 'read index received' (duration: 164.587878ms)","trace[1677896810] 'applied index is now lower than readState.Index' (duration: 46.008526ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:29:52.698575Z","caller":"traceutil/trace.go:171","msg":"trace[206500197] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"238.719937ms","start":"2026-04-28T16:29:52.45982Z","end":"2026-04-28T16:29:52.69854Z","steps":["trace[206500197] 'process raft request' (duration: 238.553554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:52.698728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.835648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-04-28T16:29:52.69876Z","caller":"traceutil/trace.go:171","msg":"trace[1288478001] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:937; }","duration":"210.918161ms","start":"2026-04-28T16:29:52.487833Z","end":"2026-04-28T16:29:52.698751Z","steps":["trace[1288478001] 'agreement among raft nodes before linearized reading' (duration: 210.749947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:52.708325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.628149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T16:29:52.708559Z","caller":"traceutil/trace.go:171","msg":"trace[1920941952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:938; }","duration":"216.886204ms","start":"2026-04-28T16:29:52.491656Z","end":"2026-04-28T16:29:52.708542Z","steps":["trace[1920941952] 'agreement among raft nodes before linearized reading' (duration: 216.605218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:29:52.708731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.686757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-04-28T16:29:52.708807Z","caller":"traceutil/trace.go:171","msg":"trace[607720193] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:938; }","duration":"122.80009ms","start":"2026-04-28T16:29:52.585989Z","end":"2026-04-28T16:29:52.708789Z","steps":["trace[607720193] 'agreement among raft nodes before linearized reading' (duration: 122.496273ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:59.748914Z","caller":"traceutil/trace.go:171","msg":"trace[366238687] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"165.385225ms","start":"2026-04-28T16:29:59.583492Z","end":"2026-04-28T16:29:59.748878Z","steps":["trace[366238687] 'process raft request' (duration: 114.76934ms)","trace[366238687] 'compare' (duration: 50.422861ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:29:59.791043Z","caller":"traceutil/trace.go:171","msg":"trace[59285953] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"207.44424ms","start":"2026-04-28T16:29:59.583577Z","end":"2026-04-28T16:29:59.791021Z","steps":["trace[59285953] 'process raft request' (duration: 207.287457ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:59.791307Z","caller":"traceutil/trace.go:171","msg":"trace[55110149] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"207.498781ms","start":"2026-04-28T16:29:59.583783Z","end":"2026-04-28T16:29:59.791282Z","steps":["trace[55110149] 'process raft request' (duration: 207.186805ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:29:59.791393Z","caller":"traceutil/trace.go:171","msg":"trace[1782062373] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"207.183535ms","start":"2026-04-28T16:29:59.584187Z","end":"2026-04-28T16:29:59.791371Z","steps":["trace[1782062373] 'process raft request' (duration: 207.003831ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:30:00.445286Z","caller":"traceutil/trace.go:171","msg":"trace[1079594350] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"468.370839ms","start":"2026-04-28T16:29:59.976895Z","end":"2026-04-28T16:30:00.445266Z","steps":["trace[1079594350] 'process raft request' (duration: 468.229706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:30:00.445419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:29:59.976851Z","time spent":"468.499131ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T16:30:00.852594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.372861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T16:30:00.852655Z","caller":"traceutil/trace.go:171","msg":"trace[1602272802] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:983; }","duration":"330.440753ms","start":"2026-04-28T16:30:00.522199Z","end":"2026-04-28T16:30:00.85264Z","steps":["trace[1602272802] 'range keys from in-memory index tree' (duration: 330.31997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:30:00.852682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:30:00.522157Z","time spent":"330.518764ms","remote":"127.0.0.1:58918","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-28T16:30:02.084579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.776943ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-28T16:30:02.231708Z","caller":"traceutil/trace.go:171","msg":"trace[697836363] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"111.112777ms","start":"2026-04-28T16:30:02.120577Z","end":"2026-04-28T16:30:02.23169Z","steps":["trace[697836363] 'process raft request' (duration: 110.997885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:30:26.466081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.277524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T16:30:26.46616Z","caller":"traceutil/trace.go:171","msg":"trace[1855945208] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1108; }","duration":"388.898915ms","start":"2026-04-28T16:30:26.077249Z","end":"2026-04-28T16:30:26.466148Z","steps":["trace[1855945208] 'read index received' (duration: 43.931µs)","trace[1855945208] 'applied index is now lower than readState.Index' (duration: 388.854244ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:30:26.466325Z","caller":"traceutil/trace.go:171","msg":"trace[1774182474] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"501.597183ms","start":"2026-04-28T16:30:25.964717Z","end":"2026-04-28T16:30:26.466314Z","steps":["trace[1774182474] 'process raft request' (duration: 99.009713ms)","trace[1774182474] 'compare' (duration: 401.981949ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:30:26.466376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:30:25.964672Z","time spent":"501.679993ms","remote":"127.0.0.1:59254","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-28T16:30:26.466431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.613987ms","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-28T16:30:26.466463Z","caller":"traceutil/trace.go:171","msg":"trace[1313117934] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1071; }","duration":"371.686309ms","start":"2026-04-28T16:30:26.094768Z","end":"2026-04-28T16:30:26.466455Z","steps":["trace[1313117934] 'agreement among raft nodes before linearized reading' (duration: 371.567897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:30:26.466497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:30:26.094747Z","time spent":"371.74307ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T16:30:26.466518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.263811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-28T16:30:26.466605Z","caller":"traceutil/trace.go:171","msg":"trace[2030751381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1071; }","duration":"389.379922ms","start":"2026-04-28T16:30:26.077202Z","end":"2026-04-28T16:30:26.466582Z","steps":["trace[2030751381] 'agreement among raft nodes before linearized reading' (duration: 389.211359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:30:26.466679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:30:26.077181Z","time spent":"389.481035ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T16:30:26.678888Z","caller":"traceutil/trace.go:171","msg":"trace[371355703] linearizableReadLoop","detail":"{readStateIndex:1111; appliedIndex:1109; }","duration":"184.716061ms","start":"2026-04-28T16:30:26.494148Z","end":"2026-04-28T16:30:26.678864Z","steps":["trace[371355703] 'read index received' (duration: 101.087786ms)","trace[371355703] 'applied index is now lower than readState.Index' (duration: 83.627405ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:30:26.679203Z","caller":"traceutil/trace.go:171","msg":"trace[685260959] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"209.783981ms","start":"2026-04-28T16:30:26.469404Z","end":"2026-04-28T16:30:26.679188Z","steps":["trace[685260959] 'process raft request' (duration: 125.820809ms)","trace[685260959] 'compare' (duration: 83.326681ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:30:26.679367Z","caller":"traceutil/trace.go:171","msg":"trace[1787049800] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"209.15079ms","start":"2026-04-28T16:30:26.470176Z","end":"2026-04-28T16:30:26.679326Z","steps":["trace[1787049800] 'process raft request' (duration: 208.652512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:30:26.679382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.21415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T16:30:26.679456Z","caller":"traceutil/trace.go:171","msg":"trace[1721929652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1073; }","duration":"185.336001ms","start":"2026-04-28T16:30:26.494108Z","end":"2026-04-28T16:30:26.679444Z","steps":["trace[1721929652] 'agreement among raft nodes before linearized reading' (duration: 185.225739ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:30:30.874703Z","caller":"traceutil/trace.go:171","msg":"trace[178805163] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"110.979371ms","start":"2026-04-28T16:30:30.763702Z","end":"2026-04-28T16:30:30.874681Z","steps":["trace[178805163] 'process raft request' (duration: 75.271479ms)","trace[178805163] 'compare' (duration: 35.534859ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:30:32.100449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.290916ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-28T16:30:34.127024Z","caller":"traceutil/trace.go:171","msg":"trace[696318887] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"244.57924ms","start":"2026-04-28T16:30:33.882418Z","end":"2026-04-28T16:30:34.126997Z","steps":["trace[696318887] 'process raft request' (duration: 244.365586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:30:34.38314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.525504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T16:30:34.383549Z","caller":"traceutil/trace.go:171","msg":"trace[23914399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1095; }","duration":"173.973972ms","start":"2026-04-28T16:30:34.20955Z","end":"2026-04-28T16:30:34.383524Z","steps":["trace[23914399] 'range keys from in-memory index tree' (duration: 173.360882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:30:35.04644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.182837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T16:30:35.046627Z","caller":"traceutil/trace.go:171","msg":"trace[1035376447] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"161.166937ms","start":"2026-04-28T16:30:34.885444Z","end":"2026-04-28T16:30:35.04661Z","steps":["trace[1035376447] 'process raft request' (duration: 161.105176ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:30:35.046697Z","caller":"traceutil/trace.go:171","msg":"trace[1482668086] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"234.447051ms","start":"2026-04-28T16:30:34.81222Z","end":"2026-04-28T16:30:35.046667Z","steps":["trace[1482668086] 'process raft request' (duration: 78.929078ms)","trace[1482668086] 'compare' (duration: 155.039215ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:30:35.888574Z","caller":"traceutil/trace.go:171","msg":"trace[544732690] linearizableReadLoop","detail":"{readStateIndex:1141; appliedIndex:1140; }","duration":"139.036263ms","start":"2026-04-28T16:30:35.74952Z","end":"2026-04-28T16:30:35.888557Z","steps":["trace[544732690] 'read index received' (duration: 138.950152ms)","trace[544732690] 'applied index is now lower than readState.Index' (duration: 85.311µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:30:35.888625Z","caller":"traceutil/trace.go:171","msg":"trace[1755272288] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"256.532101ms","start":"2026-04-28T16:30:35.632067Z","end":"2026-04-28T16:30:35.888599Z","steps":["trace[1755272288] 'process raft request' (duration: 256.347388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:30:35.88877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.182496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T16:30:35.888836Z","caller":"traceutil/trace.go:171","msg":"trace[1576968361] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1101; }","duration":"139.339738ms","start":"2026-04-28T16:30:35.749486Z","end":"2026-04-28T16:30:35.888825Z","steps":["trace[1576968361] 'agreement among raft nodes before linearized reading' (duration: 139.189225ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:30:39.028577Z","caller":"traceutil/trace.go:171","msg":"trace[1893000506] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1152; }","duration":"104.526882ms","start":"2026-04-28T16:30:38.92402Z","end":"2026-04-28T16:30:39.028547Z","steps":["trace[1893000506] 'read index received' (duration: 33.489976ms)","trace[1893000506] 'applied index is now lower than readState.Index' (duration: 71.036036ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:30:39.028637Z","caller":"traceutil/trace.go:171","msg":"trace[1729886228] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"223.194685ms","start":"2026-04-28T16:30:38.805403Z","end":"2026-04-28T16:30:39.028598Z","steps":["trace[1729886228] 'process raft request' (duration: 152.121878ms)","trace[1729886228] 'compare' (duration: 70.880583ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:30:39.028681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.640184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T16:30:39.02889Z","caller":"traceutil/trace.go:171","msg":"trace[1897029443] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1112; }","duration":"104.891277ms","start":"2026-04-28T16:30:38.92399Z","end":"2026-04-28T16:30:39.028882Z","steps":["trace[1897029443] 'agreement among raft nodes before linearized reading' (duration: 104.642143ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:30:44.949948Z","caller":"traceutil/trace.go:171","msg":"trace[785900725] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"136.175316ms","start":"2026-04-28T16:30:44.813747Z","end":"2026-04-28T16:30:44.949922Z","steps":["trace[785900725] 'process raft request' (duration: 136.021514ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:30:47.37585Z","caller":"traceutil/trace.go:171","msg":"trace[1000126468] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"140.348368ms","start":"2026-04-28T16:30:47.235485Z","end":"2026-04-28T16:30:47.375833Z","steps":["trace[1000126468] 'process raft request' (duration: 140.053373ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:30:47.58756Z","caller":"traceutil/trace.go:171","msg":"trace[1376822599] linearizableReadLoop","detail":"{readStateIndex:1183; appliedIndex:1181; }","duration":"230.445386ms","start":"2026-04-28T16:30:47.357095Z","end":"2026-04-28T16:30:47.58754Z","steps":["trace[1376822599] 'read index received' (duration: 18.700864ms)","trace[1376822599] 'applied index is now lower than readState.Index' (duration: 211.743652ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:30:47.587675Z","caller":"traceutil/trace.go:171","msg":"trace[804803026] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"296.516955ms","start":"2026-04-28T16:30:47.291144Z","end":"2026-04-28T16:30:47.587661Z","steps":["trace[804803026] 'process raft request' (duration: 245.530243ms)","trace[804803026] 'compare' (duration: 50.791559ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:30:47.587814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.807565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T16:30:47.58789Z","caller":"traceutil/trace.go:171","msg":"trace[795880178] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1140; }","duration":"180.891266ms","start":"2026-04-28T16:30:47.40698Z","end":"2026-04-28T16:30:47.587872Z","steps":["trace[795880178] 'agreement among raft nodes before linearized reading' (duration: 180.775674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:30:47.588133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.022855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-28T16:30:47.588202Z","caller":"traceutil/trace.go:171","msg":"trace[303149850] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1140; }","duration":"231.122757ms","start":"2026-04-28T16:30:47.357054Z","end":"2026-04-28T16:30:47.588177Z","steps":["trace[303149850] 'agreement among raft nodes before linearized reading' (duration: 230.937774ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:30:47.765333Z","caller":"traceutil/trace.go:171","msg":"trace[1540355419] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"173.559911ms","start":"2026-04-28T16:30:47.591756Z","end":"2026-04-28T16:30:47.765315Z","steps":["trace[1540355419] 'process raft request' (duration: 157.883114ms)","trace[1540355419] 'compare' (duration: 15.593036ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:30:59.08254Z","caller":"traceutil/trace.go:171","msg":"trace[1759446736] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"236.686092ms","start":"2026-04-28T16:30:58.845835Z","end":"2026-04-28T16:30:59.082521Z","steps":["trace[1759446736] 'process raft request' (duration: 236.576461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:31:03.420985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.705528ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T16:31:03.421081Z","caller":"traceutil/trace.go:171","msg":"trace[148081165] linearizableReadLoop","detail":"{readStateIndex:1235; appliedIndex:1234; }","duration":"173.529879ms","start":"2026-04-28T16:31:03.247539Z","end":"2026-04-28T16:31:03.421069Z","steps":["trace[148081165] 'read index received' (duration: 69.70869ms)","trace[148081165] 'applied index is now lower than readState.Index' (duration: 103.820359ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:31:03.421131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.58979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T16:31:03.421119Z","caller":"traceutil/trace.go:171","msg":"trace[2134901589] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"253.075915ms","start":"2026-04-28T16:31:03.168015Z","end":"2026-04-28T16:31:03.421091Z","steps":["trace[2134901589] 'process raft request' (duration: 149.201255ms)","trace[2134901589] 'compare' (duration: 103.607406ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:31:03.421154Z","caller":"traceutil/trace.go:171","msg":"trace[1440682631] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1189; }","duration":"173.657811ms","start":"2026-04-28T16:31:03.247485Z","end":"2026-04-28T16:31:03.421143Z","steps":["trace[1440682631] 'agreement among raft nodes before linearized reading' (duration: 173.61668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:31:03.721907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.492989ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T16:31:03.721997Z","caller":"traceutil/trace.go:171","msg":"trace[590126362] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"223.573125ms","start":"2026-04-28T16:31:03.498409Z","end":"2026-04-28T16:31:03.721982Z","steps":["trace[590126362] 'process raft request' (duration: 61.930584ms)","trace[590126362] 'compare' (duration: 161.386418ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:31:03.97515Z","caller":"traceutil/trace.go:171","msg":"trace[12236287] linearizableReadLoop","detail":"{readStateIndex:1238; appliedIndex:1237; }","duration":"140.526406ms","start":"2026-04-28T16:31:03.834604Z","end":"2026-04-28T16:31:03.97513Z","steps":["trace[12236287] 'read index received' (duration: 111.506763ms)","trace[12236287] 'applied index is now lower than readState.Index' (duration: 29.018933ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:31:03.97528Z","caller":"traceutil/trace.go:171","msg":"trace[2131504923] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"176.585134ms","start":"2026-04-28T16:31:03.798682Z","end":"2026-04-28T16:31:03.975267Z","steps":["trace[2131504923] 'process raft request' (duration: 147.48396ms)","trace[2131504923] 'compare' (duration: 28.887881ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:31:03.975495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.878461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T16:31:03.975526Z","caller":"traceutil/trace.go:171","msg":"trace[676664487] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1192; }","duration":"140.978993ms","start":"2026-04-28T16:31:03.83454Z","end":"2026-04-28T16:31:03.975519Z","steps":["trace[676664487] 'agreement among raft nodes before linearized reading' (duration: 140.660448ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:31:04.242372Z","caller":"traceutil/trace.go:171","msg":"trace[1497306980] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"263.158535ms","start":"2026-04-28T16:31:03.979194Z","end":"2026-04-28T16:31:04.242352Z","steps":["trace[1497306980] 'process raft request' (duration: 255.618343ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:31:04.242561Z","caller":"traceutil/trace.go:171","msg":"trace[2058066261] linearizableReadLoop","detail":"{readStateIndex:1239; appliedIndex:1238; }","duration":"125.911153ms","start":"2026-04-28T16:31:04.11662Z","end":"2026-04-28T16:31:04.242531Z","steps":["trace[2058066261] 'read index received' (duration: 118.145687ms)","trace[2058066261] 'applied index is now lower than readState.Index' (duration: 7.763756ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:31:04.242671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.039285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T16:31:04.242703Z","caller":"traceutil/trace.go:171","msg":"trace[127696703] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1193; }","duration":"126.128566ms","start":"2026-04-28T16:31:04.116562Z","end":"2026-04-28T16:31:04.242691Z","steps":["trace[127696703] 'agreement among raft nodes before linearized reading' (duration: 126.055095ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:31:05.936834Z","caller":"traceutil/trace.go:171","msg":"trace[191791545] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"134.704057ms","start":"2026-04-28T16:31:05.802087Z","end":"2026-04-28T16:31:05.936791Z","steps":["trace[191791545] 'process raft request' (duration: 47.273321ms)","trace[191791545] 'compare' (duration: 87.321795ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:31:06.45983Z","caller":"traceutil/trace.go:171","msg":"trace[1913746246] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"210.175298ms","start":"2026-04-28T16:31:06.249634Z","end":"2026-04-28T16:31:06.45981Z","steps":["trace[1913746246] 'process raft request' (duration: 210.040086ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:31:26.613569Z","caller":"traceutil/trace.go:171","msg":"trace[760094111] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"101.211293ms","start":"2026-04-28T16:31:26.512332Z","end":"2026-04-28T16:31:26.613543Z","steps":["trace[760094111] 'process raft request' (duration: 100.99855ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:31:26.757345Z","caller":"traceutil/trace.go:171","msg":"trace[826972531] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"140.666687ms","start":"2026-04-28T16:31:26.616652Z","end":"2026-04-28T16:31:26.757319Z","steps":["trace[826972531] 'process raft request' (duration: 140.556455ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:31:31.117396Z","caller":"traceutil/trace.go:171","msg":"trace[1217699100] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1357; }","duration":"250.991164ms","start":"2026-04-28T16:31:30.86638Z","end":"2026-04-28T16:31:31.117372Z","steps":["trace[1217699100] 'read index received' (duration: 250.823142ms)","trace[1217699100] 'applied index is now lower than readState.Index' (duration: 166.682µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:31:31.11748Z","caller":"traceutil/trace.go:171","msg":"trace[822888192] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"337.442208ms","start":"2026-04-28T16:31:30.779995Z","end":"2026-04-28T16:31:31.117437Z","steps":["trace[822888192] 'process raft request' (duration: 337.198694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:31:31.11761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.217618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T16:31:31.117657Z","caller":"traceutil/trace.go:171","msg":"trace[706781034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1305; }","duration":"251.315589ms","start":"2026-04-28T16:31:30.866327Z","end":"2026-04-28T16:31:31.117643Z","steps":["trace[706781034] 'agreement among raft nodes before linearized reading' (duration: 251.150326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:31:31.117658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:31:30.779964Z","time spent":"337.609251ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T16:31:31.73094Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828291258535494,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T16:31:32.23116Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828291258535494,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T16:31:32.731678Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605828291258535494,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T16:31:32.817361Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.695395746s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-28T16:31:32.919953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.191809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T16:31:32.921163Z","caller":"traceutil/trace.go:171","msg":"trace[1617890924] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"1.730033014s","start":"2026-04-28T16:31:31.191107Z","end":"2026-04-28T16:31:32.92114Z","steps":["trace[1617890924] 'process raft request' (duration: 1.728963049s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:31:32.921278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:31:31.191076Z","time spent":"1.730141536s","remote":"127.0.0.1:59158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T16:31:32.921299Z","caller":"traceutil/trace.go:171","msg":"trace[387246164] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"832.051508ms","start":"2026-04-28T16:31:32.08923Z","end":"2026-04-28T16:31:32.921281Z","steps":["trace[387246164] 'process raft request' (duration: 830.935943ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:31:32.921278Z","caller":"traceutil/trace.go:171","msg":"trace[1365702808] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"1.79939148s","start":"2026-04-28T16:31:31.12184Z","end":"2026-04-28T16:31:32.921231Z","steps":["trace[1365702808] 'process raft request' (duration: 1.69563459s)","trace[1365702808] 'compare' (duration: 101.886844ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:31:32.921472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:31:31.121822Z","time spent":"1.799542391s","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T16:31:32.921518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:31:32.089202Z","time spent":"832.142589ms","remote":"127.0.0.1:59254","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-28T16:31:32.923252Z","caller":"traceutil/trace.go:171","msg":"trace[520778268] linearizableReadLoop","detail":"{readStateIndex:1362; appliedIndex:1358; }","duration":"1.692649344s","start":"2026-04-28T16:31:31.23059Z","end":"2026-04-28T16:31:32.92324Z","steps":["trace[520778268] 'read index received' (duration: 1.587021248s)","trace[520778268] 'applied index is now lower than readState.Index' (duration: 105.627306ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:31:32.923404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.556035174s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-28T16:31:32.923433Z","caller":"traceutil/trace.go:171","msg":"trace[249681778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1308; }","duration":"1.556108336s","start":"2026-04-28T16:31:31.367315Z","end":"2026-04-28T16:31:32.923424Z","steps":["trace[249681778] 'agreement among raft nodes before linearized reading' (duration: 1.556003874s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:31:32.923456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:31:31.367294Z","time spent":"1.556154576s","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T16:31:32.923449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.195098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1536"} {"level":"warn","ts":"2026-04-28T16:31:32.923471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.622124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T16:31:32.923567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.448438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-28T16:31:32.923581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.526056408s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-28T16:31:32.923575Z","caller":"traceutil/trace.go:171","msg":"trace[1256621854] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1308; }","duration":"432.750056ms","start":"2026-04-28T16:31:32.490787Z","end":"2026-04-28T16:31:32.923537Z","steps":["trace[1256621854] 'agreement among raft nodes before linearized reading' (duration: 432.624075ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:31:32.923608Z","caller":"traceutil/trace.go:171","msg":"trace[539844268] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1308; }","duration":"1.526107028s","start":"2026-04-28T16:31:31.397491Z","end":"2026-04-28T16:31:32.923598Z","steps":["trace[539844268] 'agreement among raft nodes before linearized reading' (duration: 1.526063558s)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:31:32.923606Z","caller":"traceutil/trace.go:171","msg":"trace[1614213396] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1308; }","duration":"534.519188ms","start":"2026-04-28T16:31:32.389075Z","end":"2026-04-28T16:31:32.923594Z","steps":["trace[1614213396] 'agreement among raft nodes before linearized reading' (duration: 534.447117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:31:32.923618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:31:32.490765Z","time spent":"432.844637ms","remote":"127.0.0.1:58918","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-28T16:31:32.923629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:31:31.397477Z","time spent":"1.526148339s","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-04-28T16:31:32.923646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:31:32.389058Z","time spent":"534.57769ms","remote":"127.0.0.1:59508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-04-28T16:31:32.923502Z","caller":"traceutil/trace.go:171","msg":"trace[232009826] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1308; }","duration":"767.306249ms","start":"2026-04-28T16:31:32.156178Z","end":"2026-04-28T16:31:32.923485Z","steps":["trace[232009826] 'agreement among raft nodes before linearized reading' (duration: 767.158117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:31:32.923614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.692877378s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:94214"} {"level":"warn","ts":"2026-04-28T16:31:32.923804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.736228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T16:31:32.923806Z","caller":"traceutil/trace.go:171","msg":"trace[2137721095] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1308; }","duration":"1.693256453s","start":"2026-04-28T16:31:31.23054Z","end":"2026-04-28T16:31:32.923797Z","steps":["trace[2137721095] 'agreement among raft nodes before linearized reading' (duration: 1.692762566s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:31:32.923805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:31:32.156153Z","time spent":"767.642394ms","remote":"127.0.0.1:59158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":1559,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"info","ts":"2026-04-28T16:31:32.923823Z","caller":"traceutil/trace.go:171","msg":"trace[829189471] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1308; }","duration":"840.788929ms","start":"2026-04-28T16:31:32.083029Z","end":"2026-04-28T16:31:32.923818Z","steps":["trace[829189471] 'agreement among raft nodes before linearized reading' (duration: 840.755049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:31:32.923845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:31:32.083011Z","time spent":"840.82929ms","remote":"127.0.0.1:59276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-04-28T16:31:32.923832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:31:31.230513Z","time spent":"1.693311273s","remote":"127.0.0.1:59012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":94237,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-04-28T16:31:32.924019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.294136ms","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-28T16:31:32.924048Z","caller":"traceutil/trace.go:171","msg":"trace[1489755764] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1308; }","duration":"972.362946ms","start":"2026-04-28T16:31:31.951678Z","end":"2026-04-28T16:31:32.924041Z","steps":["trace[1489755764] 'agreement among raft nodes before linearized reading' (duration: 972.240605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:31:32.924117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:31:31.951646Z","time spent":"972.464608ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T16:31:32.924411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.235626538s","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-28T16:31:32.924436Z","caller":"traceutil/trace.go:171","msg":"trace[1738633593] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1308; }","duration":"1.235684019s","start":"2026-04-28T16:31:31.688746Z","end":"2026-04-28T16:31:32.92443Z","steps":["trace[1738633593] 'agreement among raft nodes before linearized reading' (duration: 1.235595838s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:31:32.924516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:31:31.688725Z","time spent":"1.235722949s","remote":"127.0.0.1:59254","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-28T16:31:37.16819Z","caller":"traceutil/trace.go:171","msg":"trace[431830659] linearizableReadLoop","detail":"{readStateIndex:1385; appliedIndex:1383; }","duration":"123.438037ms","start":"2026-04-28T16:31:37.044729Z","end":"2026-04-28T16:31:37.168167Z","steps":["trace[431830659] 'read index received' (duration: 36.506802ms)","trace[431830659] 'applied index is now lower than readState.Index' (duration: 86.929845ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:31:37.168306Z","caller":"traceutil/trace.go:171","msg":"trace[1340121490] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"132.702674ms","start":"2026-04-28T16:31:37.035573Z","end":"2026-04-28T16:31:37.168276Z","steps":["trace[1340121490] 'process raft request' (duration: 126.834253ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:31:37.168324Z","caller":"traceutil/trace.go:171","msg":"trace[1721318011] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"132.588763ms","start":"2026-04-28T16:31:37.035711Z","end":"2026-04-28T16:31:37.1683Z","steps":["trace[1721318011] 'process raft request' (duration: 132.41001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:31:37.169025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.827192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T16:31:37.169065Z","caller":"traceutil/trace.go:171","msg":"trace[572386775] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1330; }","duration":"124.352079ms","start":"2026-04-28T16:31:37.044703Z","end":"2026-04-28T16:31:37.169055Z","steps":["trace[572386775] 'agreement among raft nodes before linearized reading' (duration: 123.550708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:31:39.285923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.602255ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T16:31:39.286113Z","caller":"traceutil/trace.go:171","msg":"trace[2146050061] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"247.720611ms","start":"2026-04-28T16:31:39.038378Z","end":"2026-04-28T16:31:39.286099Z","steps":["trace[2146050061] 'process raft request' (duration: 68.871283ms)","trace[2146050061] 'compare' (duration: 178.23579ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:31:39.286301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.196475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-28T16:31:39.286358Z","caller":"traceutil/trace.go:171","msg":"trace[1056247539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1334; }","duration":"201.303186ms","start":"2026-04-28T16:31:39.085042Z","end":"2026-04-28T16:31:39.286345Z","steps":["trace[1056247539] 'agreement among raft nodes before linearized reading' (duration: 201.153684ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:31:39.286055Z","caller":"traceutil/trace.go:171","msg":"trace[1523790027] linearizableReadLoop","detail":"{readStateIndex:1389; appliedIndex:1388; }","duration":"200.93879ms","start":"2026-04-28T16:31:39.0851Z","end":"2026-04-28T16:31:39.286039Z","steps":["trace[1523790027] 'read index received' (duration: 22.146073ms)","trace[1523790027] 'applied index is now lower than readState.Index' (duration: 178.791677ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:31:39.286737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.081802ms","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-28T16:31:39.286802Z","caller":"traceutil/trace.go:171","msg":"trace[479803661] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1334; }","duration":"114.148633ms","start":"2026-04-28T16:31:39.172646Z","end":"2026-04-28T16:31:39.286794Z","steps":["trace[479803661] 'agreement among raft nodes before linearized reading' (duration: 114.032101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:31:39.286951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.798298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-28T16:31:39.287001Z","caller":"traceutil/trace.go:171","msg":"trace[1285530769] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1334; }","duration":"113.863539ms","start":"2026-04-28T16:31:39.173125Z","end":"2026-04-28T16:31:39.286988Z","steps":["trace[1285530769] 'agreement among raft nodes before linearized reading' (duration: 113.719697ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:31:41.4907Z","caller":"traceutil/trace.go:171","msg":"trace[1657674585] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1394; }","duration":"138.349088ms","start":"2026-04-28T16:31:41.352331Z","end":"2026-04-28T16:31:41.49068Z","steps":["trace[1657674585] 'read index received' (duration: 138.193556ms)","trace[1657674585] 'applied index is now lower than readState.Index' (duration: 154.612µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T16:31:41.490877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.52872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-28T16:31:41.490899Z","caller":"traceutil/trace.go:171","msg":"trace[171276792] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1340; }","duration":"138.603341ms","start":"2026-04-28T16:31:41.352289Z","end":"2026-04-28T16:31:41.490892Z","steps":["trace[171276792] 'agreement among raft nodes before linearized reading' (duration: 138.493459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:31:41.491078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.165959ms","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-28T16:31:41.491253Z","caller":"traceutil/trace.go:171","msg":"trace[583457708] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1340; }","duration":"117.388311ms","start":"2026-04-28T16:31:41.373848Z","end":"2026-04-28T16:31:41.491236Z","steps":["trace[583457708] 'agreement among raft nodes before linearized reading' (duration: 117.077327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:31:41.491077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.30164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-28T16:31:41.491366Z","caller":"traceutil/trace.go:171","msg":"trace[959302923] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1340; }","duration":"117.623895ms","start":"2026-04-28T16:31:41.373726Z","end":"2026-04-28T16:31:41.49135Z","steps":["trace[959302923] 'agreement among raft nodes before linearized reading' (duration: 117.196889ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:31:41.490882Z","caller":"traceutil/trace.go:171","msg":"trace[413560833] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"197.248461ms","start":"2026-04-28T16:31:41.293548Z","end":"2026-04-28T16:31:41.490796Z","steps":["trace[413560833] 'process raft request' (duration: 197.026208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:31:41.491664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.138188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T16:31:41.491711Z","caller":"traceutil/trace.go:171","msg":"trace[444339485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1340; }","duration":"139.205889ms","start":"2026-04-28T16:31:41.352492Z","end":"2026-04-28T16:31:41.491698Z","steps":["trace[444339485] 'agreement among raft nodes before linearized reading' (duration: 138.372798ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:31:41.66184Z","caller":"traceutil/trace.go:171","msg":"trace[216249533] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"166.801884ms","start":"2026-04-28T16:31:41.494961Z","end":"2026-04-28T16:31:41.661763Z","steps":["trace[216249533] 'process raft request' (duration: 74.375354ms)","trace[216249533] 'compare' (duration: 92.289738ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T16:31:41.70118Z","caller":"traceutil/trace.go:171","msg":"trace[7226809] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"205.991948ms","start":"2026-04-28T16:31:41.495164Z","end":"2026-04-28T16:31:41.701156Z","steps":["trace[7226809] 'process raft request' (duration: 205.946968ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:31:41.701541Z","caller":"traceutil/trace.go:171","msg":"trace[521920113] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"206.426705ms","start":"2026-04-28T16:31:41.495102Z","end":"2026-04-28T16:31:41.701529Z","steps":["trace[521920113] 'process raft request' (duration: 205.960478ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:31:41.701552Z","caller":"traceutil/trace.go:171","msg":"trace[330710010] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"206.494355ms","start":"2026-04-28T16:31:41.495026Z","end":"2026-04-28T16:31:41.70152Z","steps":["trace[330710010] 'process raft request' (duration: 205.923758ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:31:43.228161Z","caller":"traceutil/trace.go:171","msg":"trace[1979137681] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"131.072422ms","start":"2026-04-28T16:31:43.097066Z","end":"2026-04-28T16:31:43.228139Z","steps":["trace[1979137681] 'process raft request' (duration: 130.548275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:31:50.606058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.890738ms","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-28T16:31:50.606308Z","caller":"traceutil/trace.go:171","msg":"trace[250583059] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1374; }","duration":"843.220323ms","start":"2026-04-28T16:31:49.763073Z","end":"2026-04-28T16:31:50.606293Z","steps":["trace[250583059] 'range keys from in-memory index tree' (duration: 842.739336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:31:50.606338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:31:49.763051Z","time spent":"843.280073ms","remote":"127.0.0.1:59254","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-28T16:31:50.606341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.060718ms","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-28T16:31:50.606428Z","caller":"traceutil/trace.go:171","msg":"trace[2129320933] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1374; }","duration":"903.192469ms","start":"2026-04-28T16:31:49.703213Z","end":"2026-04-28T16:31:50.606405Z","steps":["trace[2129320933] 'range keys from in-memory index tree' (duration: 902.651573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:31:50.606496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:31:49.70319Z","time spent":"903.291281ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T16:31:50.606631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.223482ms","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-28T16:31:50.606619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.40717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T16:31:50.606533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.99357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-28T16:31:50.606662Z","caller":"traceutil/trace.go:171","msg":"trace[1744949499] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1374; }","duration":"840.274073ms","start":"2026-04-28T16:31:49.76638Z","end":"2026-04-28T16:31:50.606654Z","steps":["trace[1744949499] 'range keys from in-memory index tree' (duration: 840.07688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:31:50.606744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:31:49.76637Z","time spent":"840.352715ms","remote":"127.0.0.1:59254","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-28T16:31:50.606743Z","caller":"traceutil/trace.go:171","msg":"trace[513182660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1374; }","duration":"843.219593ms","start":"2026-04-28T16:31:49.763502Z","end":"2026-04-28T16:31:50.606722Z","steps":["trace[513182660] 'range keys from in-memory index tree' (duration: 842.903699ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:31:50.606762Z","caller":"traceutil/trace.go:171","msg":"trace[229931815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1374; }","duration":"113.166501ms","start":"2026-04-28T16:31:50.493546Z","end":"2026-04-28T16:31:50.606713Z","steps":["trace[229931815] 'range keys from in-memory index tree' (duration: 112.319909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T16:31:50.60683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T16:31:49.763479Z","time spent":"843.313954ms","remote":"127.0.0.1:59254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T16:31:50.607346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.686719ms","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-28T16:31:50.607502Z","caller":"traceutil/trace.go:171","msg":"trace[1588556145] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1374; }","duration":"323.865101ms","start":"2026-04-28T16:31:50.283618Z","end":"2026-04-28T16:31:50.607483Z","steps":["trace[1588556145] 'range keys from in-memory index tree' (duration: 323.670598ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:31:50.743882Z","caller":"traceutil/trace.go:171","msg":"trace[1558035003] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"132.105495ms","start":"2026-04-28T16:31:50.611758Z","end":"2026-04-28T16:31:50.743864Z","steps":["trace[1558035003] 'process raft request' (duration: 131.802881ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:31:50.743951Z","caller":"traceutil/trace.go:171","msg":"trace[1374976926] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"132.214316ms","start":"2026-04-28T16:31:50.611714Z","end":"2026-04-28T16:31:50.743928Z","steps":["trace[1374976926] 'process raft request' (duration: 131.731829ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T16:31:50.743901Z","caller":"traceutil/trace.go:171","msg":"trace[144963862] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"132.144595ms","start":"2026-04-28T16:31:50.611713Z","end":"2026-04-28T16:31:50.743857Z","steps":["trace[144963862] 'process raft request' (duration: 131.825031ms)"],"step_count":1}