{"level":"warn","ts":"2026-04-18T09:05:08.95847Z","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-18T09:05:08.958545Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162: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.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162: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-18T09:05:08.958606Z","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-18T09:05:08.958619Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-04-18T09:05:08.958639Z","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-18T09:05:08.959231Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-04-18T09:05:08.9593Z","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.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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-18T09:05:09.043695Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"76.066966ms"} {"level":"info","ts":"2026-04-18T09:05:09.166071Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-04-18T09:05:09.166189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-04-18T09:05:09.16623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-04-18T09:05:09.166255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-18T09:05:09.166272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-04-18T09:05:09.166338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-04-18T09:05:09.269158Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-18T09:05:09.317461Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-18T09:05:09.33918Z","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-18T09:05:09.344056Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-18T09:05:09.344485Z","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-18T09:05:09.344547Z","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-18T09:05:09.344563Z","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-18T09:05:09.344477Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-18T09:05:09.348849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-04-18T09:05:09.349026Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-04-18T09:05:09.35036Z","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-18T09:05:09.350498Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-04-18T09:05:09.350523Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-04-18T09:05:09.350761Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-18T09:05:09.350846Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-18T09:05:09.473843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-04-18T09:05:09.473937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-04-18T09:05:09.473969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-04-18T09:05:09.473991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-04-18T09:05:09.474002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-04-18T09:05:09.474019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-04-18T09:05:09.474032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-04-18T09:05:09.487011Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-04-18T09:05:09.487177Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T09:05:09.487324Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T09:05:09.487352Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T09:05:09.488622Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-18T09:05:09.488683Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-18T09:05:09.489443Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T09:05:09.48965Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T09:05:09.489742Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T09:05:09.491313Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-18T09:05:09.492804Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-04-18T09:05:11.513659Z","caller":"traceutil/trace.go:171","msg":"trace[168114871] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"124.351486ms","start":"2026-04-18T09:05:11.38928Z","end":"2026-04-18T09:05:11.513631Z","steps":["trace[168114871] 'process raft request' (duration: 75.053736ms)","trace[168114871] 'compare' (duration: 49.022975ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:05:11.513755Z","caller":"traceutil/trace.go:171","msg":"trace[275919906] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"123.837357ms","start":"2026-04-18T09:05:11.389889Z","end":"2026-04-18T09:05:11.513727Z","steps":["trace[275919906] 'process raft request' (duration: 123.654284ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:11.514017Z","caller":"traceutil/trace.go:171","msg":"trace[788825007] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"123.602484ms","start":"2026-04-18T09:05:11.390399Z","end":"2026-04-18T09:05:11.514002Z","steps":["trace[788825007] 'process raft request' (duration: 123.202637ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:11.514124Z","caller":"traceutil/trace.go:171","msg":"trace[1836568919] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"123.211117ms","start":"2026-04-18T09:05:11.390891Z","end":"2026-04-18T09:05:11.514103Z","steps":["trace[1836568919] 'process raft request' (duration: 122.792041ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:11.514161Z","caller":"traceutil/trace.go:171","msg":"trace[1488925225] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"123.534712ms","start":"2026-04-18T09:05:11.390591Z","end":"2026-04-18T09:05:11.514126Z","steps":["trace[1488925225] 'process raft request' (duration: 123.038935ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:11.514345Z","caller":"traceutil/trace.go:171","msg":"trace[1374731700] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"122.912663ms","start":"2026-04-18T09:05:11.391416Z","end":"2026-04-18T09:05:11.514329Z","steps":["trace[1374731700] 'process raft request' (duration: 122.298103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:11.514569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.756848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-18T09:05:11.514657Z","caller":"traceutil/trace.go:171","msg":"trace[1750608964] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:28; }","duration":"125.91066ms","start":"2026-04-18T09:05:11.388707Z","end":"2026-04-18T09:05:11.514637Z","steps":["trace[1750608964] 'agreement among raft nodes before linearized reading' (duration: 125.715447ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:11.514817Z","caller":"traceutil/trace.go:171","msg":"trace[158328254] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"125.056906ms","start":"2026-04-18T09:05:11.389705Z","end":"2026-04-18T09:05:11.514762Z","steps":["trace[158328254] 'process raft request' (duration: 123.778956ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:12.07636Z","caller":"traceutil/trace.go:171","msg":"trace[952078167] transaction","detail":"{read_only:false; response_revision:61; number_of_response:1; }","duration":"192.706766ms","start":"2026-04-18T09:05:11.883565Z","end":"2026-04-18T09:05:12.076272Z","steps":["trace[952078167] 'process raft request' (duration: 126.704984ms)","trace[952078167] 'compare' (duration: 65.821469ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:05:12.076594Z","caller":"traceutil/trace.go:171","msg":"trace[599297430] linearizableReadLoop","detail":"{readStateIndex:67; appliedIndex:65; }","duration":"187.639794ms","start":"2026-04-18T09:05:11.888925Z","end":"2026-04-18T09:05:12.076565Z","steps":["trace[599297430] 'read index received' (duration: 121.282426ms)","trace[599297430] 'applied index is now lower than readState.Index' (duration: 66.356358ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:05:12.076626Z","caller":"traceutil/trace.go:171","msg":"trace[1175986584] transaction","detail":"{read_only:false; response_revision:62; number_of_response:1; }","duration":"190.334858ms","start":"2026-04-18T09:05:11.886266Z","end":"2026-04-18T09:05:12.076601Z","steps":["trace[1175986584] 'process raft request' (duration: 189.969301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:12.076852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.905579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a76829e62f1e6b\" ","response":"range_response_count:1 size:654"} {"level":"info","ts":"2026-04-18T09:05:12.076908Z","caller":"traceutil/trace.go:171","msg":"trace[900064560] range","detail":"{range_begin:/registry/events/default/instance.18a76829e62f1e6b; range_end:; response_count:1; response_revision:62; }","duration":"187.99501ms","start":"2026-04-18T09:05:11.8889Z","end":"2026-04-18T09:05:12.076895Z","steps":["trace[900064560] 'agreement among raft nodes before linearized reading' (duration: 187.768536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:12.473962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.502408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-18T09:05:12.47419Z","caller":"traceutil/trace.go:171","msg":"trace[434133969] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:74; }","duration":"335.227026ms","start":"2026-04-18T09:05:12.138941Z","end":"2026-04-18T09:05:12.474168Z","steps":["trace[434133969] 'read index received' (duration: 120.841524ms)","trace[434133969] 'applied index is now lower than readState.Index' (duration: 214.384242ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:05:12.474204Z","caller":"traceutil/trace.go:171","msg":"trace[1040262553] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"350.233219ms","start":"2026-04-18T09:05:12.123934Z","end":"2026-04-18T09:05:12.474167Z","steps":["trace[1040262553] 'process raft request' (duration: 135.865677ms)","trace[1040262553] 'compare' (duration: 213.358866ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:05:12.474343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:12.123913Z","time spent":"350.357081ms","remote":"127.0.0.1:37790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":565,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T09:05:12.474383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.42851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a76829e62f1e6b\" ","response":"range_response_count:1 size:654"} {"level":"info","ts":"2026-04-18T09:05:12.474388Z","caller":"traceutil/trace.go:171","msg":"trace[411712207] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"350.2991ms","start":"2026-04-18T09:05:12.124054Z","end":"2026-04-18T09:05:12.474353Z","steps":["trace[411712207] 'process raft request' (duration: 350.022226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:12.474496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.724012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-18T09:05:12.474464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.235848ms","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-18T09:05:12.474596Z","caller":"traceutil/trace.go:171","msg":"trace[2008194556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"245.800074ms","start":"2026-04-18T09:05:12.228735Z","end":"2026-04-18T09:05:12.474535Z","steps":["trace[2008194556] 'agreement among raft nodes before linearized reading' (duration: 245.725273ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:12.4746Z","caller":"traceutil/trace.go:171","msg":"trace[87398771] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:71; }","duration":"292.38241ms","start":"2026-04-18T09:05:12.182166Z","end":"2026-04-18T09:05:12.474548Z","steps":["trace[87398771] 'agreement among raft nodes before linearized reading' (duration: 292.221388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:12.474585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.987908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-18T09:05:12.474712Z","caller":"traceutil/trace.go:171","msg":"trace[669869493] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:71; }","duration":"147.221062ms","start":"2026-04-18T09:05:12.327472Z","end":"2026-04-18T09:05:12.474693Z","steps":["trace[669869493] 'agreement among raft nodes before linearized reading' (duration: 147.011128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:12.474608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.633456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-18T09:05:12.474929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:12.124044Z","time spent":"350.408782ms","remote":"127.0.0.1:37784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1116,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:05:12.474951Z","caller":"traceutil/trace.go:171","msg":"trace[2035353100] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:71; }","duration":"290.091642ms","start":"2026-04-18T09:05:12.184847Z","end":"2026-04-18T09:05:12.474938Z","steps":["trace[2035353100] 'agreement among raft nodes before linearized reading' (duration: 289.639135ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:12.475002Z","caller":"traceutil/trace.go:171","msg":"trace[981719333] range","detail":"{range_begin:/registry/events/default/instance.18a76829e62f1e6b; range_end:; response_count:1; response_revision:71; }","duration":"335.48119ms","start":"2026-04-18T09:05:12.138935Z","end":"2026-04-18T09:05:12.474416Z","steps":["trace[981719333] 'agreement among raft nodes before linearized reading' (duration: 335.380209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:12.475292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:12.138878Z","time spent":"336.347304ms","remote":"127.0.0.1:37434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":676,"request content":"key:\"/registry/events/default/instance.18a76829e62f1e6b\" "} {"level":"info","ts":"2026-04-18T09:05:12.669942Z","caller":"traceutil/trace.go:171","msg":"trace[1383092621] transaction","detail":"{read_only:false; response_revision:80; number_of_response:1; }","duration":"104.993676ms","start":"2026-04-18T09:05:12.564486Z","end":"2026-04-18T09:05:12.66948Z","steps":["trace[1383092621] 'process raft request' (duration: 104.555199ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:12.670599Z","caller":"traceutil/trace.go:171","msg":"trace[295824782] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"106.170095ms","start":"2026-04-18T09:05:12.564171Z","end":"2026-04-18T09:05:12.670341Z","steps":["trace[295824782] 'process raft request' (duration: 66.706524ms)","trace[295824782] 'compare' (duration: 37.973807ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:05:12.670643Z","caller":"traceutil/trace.go:171","msg":"trace[1681740187] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"106.242396ms","start":"2026-04-18T09:05:12.564334Z","end":"2026-04-18T09:05:12.670576Z","steps":["trace[1681740187] 'process raft request' (duration: 104.65238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:12.671945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.380584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-18T09:05:12.672073Z","caller":"traceutil/trace.go:171","msg":"trace[1581967497] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:80; }","duration":"102.513756ms","start":"2026-04-18T09:05:12.569518Z","end":"2026-04-18T09:05:12.672032Z","steps":["trace[1581967497] 'agreement among raft nodes before linearized reading' (duration: 99.580528ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:12.843486Z","caller":"traceutil/trace.go:171","msg":"trace[921065609] transaction","detail":"{read_only:false; response_revision:85; number_of_response:1; }","duration":"110.575946ms","start":"2026-04-18T09:05:12.73289Z","end":"2026-04-18T09:05:12.843466Z","steps":["trace[921065609] 'process raft request' (duration: 110.518335ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:12.843499Z","caller":"traceutil/trace.go:171","msg":"trace[872848876] linearizableReadLoop","detail":"{readStateIndex:89; appliedIndex:88; }","duration":"111.942219ms","start":"2026-04-18T09:05:12.731526Z","end":"2026-04-18T09:05:12.843468Z","steps":["trace[872848876] 'read index received' (duration: 83.709591ms)","trace[872848876] 'applied index is now lower than readState.Index' (duration: 28.229838ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:05:12.843492Z","caller":"traceutil/trace.go:171","msg":"trace[1203078938] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"112.138122ms","start":"2026-04-18T09:05:12.731307Z","end":"2026-04-18T09:05:12.843445Z","steps":["trace[1203078938] 'process raft request' (duration: 83.896583ms)","trace[1203078938] 'compare' (duration: 28.103747ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:05:12.843806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.176694ms","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-18T09:05:12.843863Z","caller":"traceutil/trace.go:171","msg":"trace[1355938365] range","detail":"{range_begin:/registry/clusterroles/cluster-admin; range_end:; response_count:0; response_revision:85; }","duration":"112.382806ms","start":"2026-04-18T09:05:12.731464Z","end":"2026-04-18T09:05:12.843846Z","steps":["trace[1355938365] 'agreement among raft nodes before linearized reading' (duration: 112.126692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:13.103433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.856803ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-18T09:05:13.103572Z","caller":"traceutil/trace.go:171","msg":"trace[1942205215] linearizableReadLoop","detail":"{readStateIndex:95; appliedIndex:94; }","duration":"180.523906ms","start":"2026-04-18T09:05:12.923006Z","end":"2026-04-18T09:05:13.10353Z","steps":["trace[1942205215] 'read index received' (duration: 39.500591ms)","trace[1942205215] 'applied index is now lower than readState.Index' (duration: 141.021975ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:05:13.10359Z","caller":"traceutil/trace.go:171","msg":"trace[1965209494] transaction","detail":"{read_only:false; response_revision:90; number_of_response:1; }","duration":"180.76359ms","start":"2026-04-18T09:05:12.922798Z","end":"2026-04-18T09:05:13.103561Z","steps":["trace[1965209494] 'process raft request' (duration: 39.692435ms)","trace[1965209494] 'compare' (duration: 140.673709ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:05:13.103713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.698418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a76829e62f1e6b\" ","response":"range_response_count:1 size:654"} {"level":"info","ts":"2026-04-18T09:05:13.103753Z","caller":"traceutil/trace.go:171","msg":"trace[189978506] range","detail":"{range_begin:/registry/events/default/instance.18a76829e62f1e6b; range_end:; response_count:1; response_revision:90; }","duration":"180.740919ms","start":"2026-04-18T09:05:12.922999Z","end":"2026-04-18T09:05:13.10374Z","steps":["trace[189978506] 'agreement among raft nodes before linearized reading' (duration: 180.603777ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:13.504538Z","caller":"traceutil/trace.go:171","msg":"trace[652638290] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"126.863876ms","start":"2026-04-18T09:05:13.37765Z","end":"2026-04-18T09:05:13.504514Z","steps":["trace[652638290] 'process raft request' (duration: 126.788415ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:13.581524Z","caller":"traceutil/trace.go:171","msg":"trace[1078474332] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"140.62047ms","start":"2026-04-18T09:05:13.440882Z","end":"2026-04-18T09:05:13.581503Z","steps":["trace[1078474332] 'process raft request' (duration: 122.198209ms)","trace[1078474332] 'compare' (duration: 18.315188ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:05:14.137243Z","caller":"traceutil/trace.go:171","msg":"trace[1864075524] transaction","detail":"{read_only:false; response_revision:136; number_of_response:1; }","duration":"229.544392ms","start":"2026-04-18T09:05:13.907671Z","end":"2026-04-18T09:05:14.137216Z","steps":["trace[1864075524] 'process raft request' (duration: 221.605863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:14.649464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.958058ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:05:14.649703Z","caller":"traceutil/trace.go:171","msg":"trace[1855147833] transaction","detail":"{read_only:false; response_revision:169; number_of_response:1; }","duration":"215.852872ms","start":"2026-04-18T09:05:14.43381Z","end":"2026-04-18T09:05:14.649663Z","steps":["trace[1855147833] 'process raft request' (duration: 49.594329ms)","trace[1855147833] 'compare' (duration: 165.790186ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:05:15.114823Z","caller":"traceutil/trace.go:171","msg":"trace[694585017] transaction","detail":"{read_only:false; response_revision:191; number_of_response:1; }","duration":"118.119853ms","start":"2026-04-18T09:05:14.996678Z","end":"2026-04-18T09:05:15.114798Z","steps":["trace[694585017] 'process raft request' (duration: 118.075372ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:15.114887Z","caller":"traceutil/trace.go:171","msg":"trace[1251125235] transaction","detail":"{read_only:false; response_revision:190; number_of_response:1; }","duration":"119.762579ms","start":"2026-04-18T09:05:14.995083Z","end":"2026-04-18T09:05:15.114845Z","steps":["trace[1251125235] 'process raft request' (duration: 74.236994ms)","trace[1251125235] 'compare' (duration: 45.303882ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:05:17.126897Z","caller":"traceutil/trace.go:171","msg":"trace[660482481] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"290.351094ms","start":"2026-04-18T09:05:16.836522Z","end":"2026-04-18T09:05:17.126873Z","steps":["trace[660482481] 'process raft request' (duration: 290.227242ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:17.131792Z","caller":"traceutil/trace.go:171","msg":"trace[410285373] linearizableReadLoop","detail":"{readStateIndex:276; appliedIndex:275; }","duration":"252.271669ms","start":"2026-04-18T09:05:16.879496Z","end":"2026-04-18T09:05:17.131768Z","steps":["trace[410285373] 'read index received' (duration: 247.487981ms)","trace[410285373] 'applied index is now lower than readState.Index' (duration: 4.782268ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:05:17.131867Z","caller":"traceutil/trace.go:171","msg":"trace[1346638178] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"294.369329ms","start":"2026-04-18T09:05:16.837454Z","end":"2026-04-18T09:05:17.131824Z","steps":["trace[1346638178] 'process raft request' (duration: 294.188656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:17.131954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.448642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3709"} {"level":"info","ts":"2026-04-18T09:05:17.13231Z","caller":"traceutil/trace.go:171","msg":"trace[1816684426] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:270; }","duration":"252.846449ms","start":"2026-04-18T09:05:16.879428Z","end":"2026-04-18T09:05:17.132274Z","steps":["trace[1816684426] 'agreement among raft nodes before linearized reading' (duration: 252.466862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:18.228529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.672357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-18T09:05:18.228672Z","caller":"traceutil/trace.go:171","msg":"trace[1212642170] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:313; }","duration":"142.887651ms","start":"2026-04-18T09:05:18.085752Z","end":"2026-04-18T09:05:18.228639Z","steps":["trace[1212642170] 'range keys from in-memory index tree' (duration: 142.493375ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:24.504122Z","caller":"traceutil/trace.go:171","msg":"trace[1846898278] linearizableReadLoop","detail":"{readStateIndex:416; appliedIndex:415; }","duration":"120.124101ms","start":"2026-04-18T09:05:24.383958Z","end":"2026-04-18T09:05:24.504082Z","steps":["trace[1846898278] 'read index received' (duration: 119.836495ms)","trace[1846898278] 'applied index is now lower than readState.Index' (duration: 285.946µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:05:24.504186Z","caller":"traceutil/trace.go:171","msg":"trace[1231931624] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"437.509127ms","start":"2026-04-18T09:05:24.066628Z","end":"2026-04-18T09:05:24.504137Z","steps":["trace[1231931624] 'process raft request' (duration: 437.220721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:24.504334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.315064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:05:24.504459Z","caller":"traceutil/trace.go:171","msg":"trace[321922918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:402; }","duration":"120.508567ms","start":"2026-04-18T09:05:24.383908Z","end":"2026-04-18T09:05:24.504416Z","steps":["trace[321922918] 'agreement among raft nodes before linearized reading' (duration: 120.314794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:24.504655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:24.066603Z","time spent":"437.800541ms","remote":"127.0.0.1:37522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5321,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:05:25.676791Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744094373885840,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-18T09:05:25.892973Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.375927313s","expected-duration":"1s"} {"level":"info","ts":"2026-04-18T09:05:25.893428Z","caller":"traceutil/trace.go:171","msg":"trace[1457721716] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:416; }","duration":"716.80152ms","start":"2026-04-18T09:05:25.176599Z","end":"2026-04-18T09:05:25.893401Z","steps":["trace[1457721716] 'read index received' (duration: 716.521126ms)","trace[1457721716] 'applied index is now lower than readState.Index' (duration: 279.324µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:05:25.893493Z","caller":"traceutil/trace.go:171","msg":"trace[1427456432] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"1.376508192s","start":"2026-04-18T09:05:24.516947Z","end":"2026-04-18T09:05:25.893455Z","steps":["trace[1427456432] 'process raft request' (duration: 1.376274449s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:25.893596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.969743ms","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-18T09:05:25.893653Z","caller":"traceutil/trace.go:171","msg":"trace[2021940268] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:403; }","duration":"717.037674ms","start":"2026-04-18T09:05:25.176592Z","end":"2026-04-18T09:05:25.89363Z","steps":["trace[2021940268] 'agreement among raft nodes before linearized reading' (duration: 716.937633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:25.893668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:24.516923Z","time spent":"1.376651754s","remote":"127.0.0.1:37522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:05:25.893735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:25.176521Z","time spent":"717.194617ms","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-18T09:05:25.893973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.730558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-18T09:05:25.894069Z","caller":"traceutil/trace.go:171","msg":"trace[894088390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:403; }","duration":"713.85355ms","start":"2026-04-18T09:05:25.180201Z","end":"2026-04-18T09:05:25.894055Z","steps":["trace[894088390] 'agreement among raft nodes before linearized reading' (duration: 713.714968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:25.894374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.313896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-18T09:05:25.894407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:25.180191Z","time spent":"714.201006ms","remote":"127.0.0.1:37606","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-18T09:05:25.894431Z","caller":"traceutil/trace.go:171","msg":"trace[412233496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:403; }","duration":"706.400797ms","start":"2026-04-18T09:05:25.188013Z","end":"2026-04-18T09:05:25.894414Z","steps":["trace[412233496] 'agreement among raft nodes before linearized reading' (duration: 706.293515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:25.894718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:25.187999Z","time spent":"706.59076ms","remote":"127.0.0.1:37606","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-18T09:05:25.89434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.097833ms","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-18T09:05:25.894795Z","caller":"traceutil/trace.go:171","msg":"trace[936279801] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:403; }","duration":"597.586951ms","start":"2026-04-18T09:05:25.297188Z","end":"2026-04-18T09:05:25.894775Z","steps":["trace[936279801] 'agreement among raft nodes before linearized reading' (duration: 597.104794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:25.894854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:25.297151Z","time spent":"597.687272ms","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-18T09:05:26.355483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.28742ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-04-18T09:05:26.355617Z","caller":"traceutil/trace.go:171","msg":"trace[124131862] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:417; }","duration":"457.327352ms","start":"2026-04-18T09:05:25.898272Z","end":"2026-04-18T09:05:26.3556Z","steps":["trace[124131862] 'read index received' (duration: 147.90145ms)","trace[124131862] 'applied index is now lower than readState.Index' (duration: 309.424302ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:05:26.355789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:25.89577Z","time spent":"460.007166ms","remote":"127.0.0.1:37390","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-18T09:05:26.355868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.580246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-04-18T09:05:26.356185Z","caller":"traceutil/trace.go:171","msg":"trace[1597256964] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:403; }","duration":"457.904831ms","start":"2026-04-18T09:05:25.898245Z","end":"2026-04-18T09:05:26.35615Z","steps":["trace[1597256964] 'agreement among raft nodes before linearized reading' (duration: 457.528585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:26.356277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:25.898232Z","time spent":"458.002943ms","remote":"127.0.0.1:37522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4348,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-04-18T09:05:26.887495Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744094373885850,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-18T09:05:27.272868Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.226668802s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-18T09:05:27.864915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.736586ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:05:27.865301Z","caller":"traceutil/trace.go:171","msg":"trace[1197116784] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"1.965547188s","start":"2026-04-18T09:05:25.899733Z","end":"2026-04-18T09:05:27.86528Z","steps":["trace[1197116784] 'process raft request' (duration: 1.965489487s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:27.865388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:25.89971Z","time spent":"1.96564197s","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:05:27.865514Z","caller":"traceutil/trace.go:171","msg":"trace[118908593] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"1.965956755s","start":"2026-04-18T09:05:25.899525Z","end":"2026-04-18T09:05:27.865482Z","steps":["trace[118908593] 'process raft request' (duration: 1.965561759s)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:27.865573Z","caller":"traceutil/trace.go:171","msg":"trace[1594709156] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"1.967091953s","start":"2026-04-18T09:05:25.898435Z","end":"2026-04-18T09:05:27.865527Z","steps":["trace[1594709156] 'process raft request' (duration: 1.376631386s)","trace[1594709156] 'compare' (duration: 589.586413ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:05:27.865666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:25.899519Z","time spent":"1.966061978s","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:05:27.865676Z","caller":"traceutil/trace.go:171","msg":"trace[318910693] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"1.966027807s","start":"2026-04-18T09:05:25.899608Z","end":"2026-04-18T09:05:27.865636Z","steps":["trace[318910693] 'process raft request' (duration: 1.965570198s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:27.865772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:25.898419Z","time spent":"1.967244936s","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:05:27.865848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:25.899603Z","time spent":"1.966157609s","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:05:28.386807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000048885s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-18T09:05:28.386908Z","caller":"traceutil/trace.go:171","msg":"trace[1344565522] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000200558s","start":"2026-04-18T09:05:26.386684Z","end":"2026-04-18T09:05:28.386885Z","steps":["trace[1344565522] 'agreement among raft nodes before linearized reading' (duration: 2.000068036s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:28.386968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:26.386675Z","time spent":"2.000282049s","remote":"127.0.0.1:37370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T09:05:28.491675Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.218561941s","expected-duration":"1s"} {"level":"info","ts":"2026-04-18T09:05:28.520783Z","caller":"traceutil/trace.go:171","msg":"trace[24342218] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"2.157137488s","start":"2026-04-18T09:05:26.36359Z","end":"2026-04-18T09:05:28.520727Z","steps":["trace[24342218] 'process raft request' (duration: 2.156980195s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:28.520965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:26.363575Z","time spent":"2.157288721s","remote":"127.0.0.1:37522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:05:28.521147Z","caller":"traceutil/trace.go:171","msg":"trace[1594113817] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"795.195081ms","start":"2026-04-18T09:05:27.725933Z","end":"2026-04-18T09:05:28.521128Z","steps":["trace[1594113817] 'process raft request' (duration: 794.739523ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:28.521233Z","caller":"traceutil/trace.go:171","msg":"trace[2130802642] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"2.162279744s","start":"2026-04-18T09:05:26.35834Z","end":"2026-04-18T09:05:28.52062Z","steps":["trace[2130802642] 'process raft request' (duration: 2.133776106s)","trace[2130802642] 'compare' (duration: 28.288645ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:05:28.521313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:27.72589Z","time spent":"795.311963ms","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:05:28.521388Z","caller":"traceutil/trace.go:171","msg":"trace[1622994766] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:418; }","duration":"2.134597702s","start":"2026-04-18T09:05:26.38672Z","end":"2026-04-18T09:05:28.521318Z","steps":["trace[1622994766] 'read index received' (duration: 887.252489ms)","trace[1622994766] 'applied index is now lower than readState.Index' (duration: 1.247340433s)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:05:28.5215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:26.358311Z","time spent":"2.163070687s","remote":"127.0.0.1:37390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:05:28.521603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.942236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:05:28.521639Z","caller":"traceutil/trace.go:171","msg":"trace[832323449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:410; }","duration":"373.058868ms","start":"2026-04-18T09:05:28.148569Z","end":"2026-04-18T09:05:28.521628Z","steps":["trace[832323449] 'agreement among raft nodes before linearized reading' (duration: 372.953557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:28.521644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.897526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T09:05:28.521666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:28.148537Z","time spent":"373.122479ms","remote":"127.0.0.1:37370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-18T09:05:28.521833Z","caller":"traceutil/trace.go:171","msg":"trace[2079680834] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:410; }","duration":"133.987438ms","start":"2026-04-18T09:05:28.387685Z","end":"2026-04-18T09:05:28.521672Z","steps":["trace[2079680834] 'agreement among raft nodes before linearized reading' (duration: 133.867276ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:46.124609Z","caller":"traceutil/trace.go:171","msg":"trace[1903497171] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"158.099003ms","start":"2026-04-18T09:05:45.966479Z","end":"2026-04-18T09:05:46.124578Z","steps":["trace[1903497171] 'read index received' (duration: 153.212959ms)","trace[1903497171] 'applied index is now lower than readState.Index' (duration: 4.884904ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:05:46.12466Z","caller":"traceutil/trace.go:171","msg":"trace[716986000] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"170.867503ms","start":"2026-04-18T09:05:45.953751Z","end":"2026-04-18T09:05:46.124618Z","steps":["trace[716986000] 'process raft request' (duration: 165.870188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:46.124846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.351128ms","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-18T09:05:46.124914Z","caller":"traceutil/trace.go:171","msg":"trace[2026031318] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"158.461121ms","start":"2026-04-18T09:05:45.966434Z","end":"2026-04-18T09:05:46.124896Z","steps":["trace[2026031318] 'agreement among raft nodes before linearized reading' (duration: 158.248717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:46.138791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.332586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-18T09:05:46.138804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.772938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-04-18T09:05:46.138907Z","caller":"traceutil/trace.go:171","msg":"trace[459174740] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:588; }","duration":"168.91861ms","start":"2026-04-18T09:05:45.969967Z","end":"2026-04-18T09:05:46.138885Z","steps":["trace[459174740] 'agreement among raft nodes before linearized reading' (duration: 168.618814ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:46.138931Z","caller":"traceutil/trace.go:171","msg":"trace[851035398] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"126.670033ms","start":"2026-04-18T09:05:46.012249Z","end":"2026-04-18T09:05:46.138919Z","steps":["trace[851035398] 'process raft request' (duration: 125.88708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:46.139144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.549397ms","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-18T09:05:46.139189Z","caller":"traceutil/trace.go:171","msg":"trace[1527697233] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:588; }","duration":"167.609838ms","start":"2026-04-18T09:05:45.97157Z","end":"2026-04-18T09:05:46.139179Z","steps":["trace[1527697233] 'agreement among raft nodes before linearized reading' (duration: 167.493466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:46.139262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.171307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-18T09:05:46.139333Z","caller":"traceutil/trace.go:171","msg":"trace[1392578785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:588; }","duration":"168.269469ms","start":"2026-04-18T09:05:45.971048Z","end":"2026-04-18T09:05:46.139317Z","steps":["trace[1392578785] 'agreement among raft nodes before linearized reading' (duration: 168.115726ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:46.138855Z","caller":"traceutil/trace.go:171","msg":"trace[953789678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:588; }","duration":"166.410857ms","start":"2026-04-18T09:05:45.972428Z","end":"2026-04-18T09:05:46.138839Z","steps":["trace[953789678] 'agreement among raft nodes before linearized reading' (duration: 166.212273ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:48.306183Z","caller":"traceutil/trace.go:171","msg":"trace[1560485943] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"117.926508ms","start":"2026-04-18T09:05:48.188238Z","end":"2026-04-18T09:05:48.306164Z","steps":["trace[1560485943] 'process raft request' (duration: 76.38906ms)","trace[1560485943] 'compare' (duration: 41.408975ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:05:48.306533Z","caller":"traceutil/trace.go:171","msg":"trace[1012001] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"116.277119ms","start":"2026-04-18T09:05:48.190229Z","end":"2026-04-18T09:05:48.306506Z","steps":["trace[1012001] 'process raft request' (duration: 116.150726ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:48.306601Z","caller":"traceutil/trace.go:171","msg":"trace[339809842] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"117.335567ms","start":"2026-04-18T09:05:48.189234Z","end":"2026-04-18T09:05:48.306569Z","steps":["trace[339809842] 'process raft request' (duration: 116.902119ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:54.235228Z","caller":"traceutil/trace.go:171","msg":"trace[1122419011] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"491.930482ms","start":"2026-04-18T09:05:53.743273Z","end":"2026-04-18T09:05:54.235204Z","steps":["trace[1122419011] 'process raft request' (duration: 491.77662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:54.235385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:53.743238Z","time spent":"492.066515ms","remote":"127.0.0.1:37522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:05:54.663413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.725664ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T09:05:54.66373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.289898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:05:54.663825Z","caller":"traceutil/trace.go:171","msg":"trace[1110462366] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"336.217258ms","start":"2026-04-18T09:05:54.327594Z","end":"2026-04-18T09:05:54.663811Z","steps":["trace[1110462366] 'read index received' (duration: 179.666466ms)","trace[1110462366] 'applied index is now lower than readState.Index' (duration: 156.549542ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:05:54.663745Z","caller":"traceutil/trace.go:171","msg":"trace[2076887500] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:635; }","duration":"308.09686ms","start":"2026-04-18T09:05:54.35562Z","end":"2026-04-18T09:05:54.663717Z","steps":["trace[2076887500] 'range keys from in-memory index tree' (duration: 307.694543ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:54.664032Z","caller":"traceutil/trace.go:171","msg":"trace[1786893440] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"416.995844ms","start":"2026-04-18T09:05:54.246905Z","end":"2026-04-18T09:05:54.663901Z","steps":["trace[1786893440] 'process raft request' (duration: 260.441031ms)","trace[1786893440] 'compare' (duration: 156.102815ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:05:54.664138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.281105ms","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-18T09:05:54.664029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.439623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-18T09:05:54.664232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.914387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:05:54.664267Z","caller":"traceutil/trace.go:171","msg":"trace[66434356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:636; }","duration":"336.722068ms","start":"2026-04-18T09:05:54.327534Z","end":"2026-04-18T09:05:54.664256Z","steps":["trace[66434356] 'agreement among raft nodes before linearized reading' (duration: 336.377871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:54.664267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:54.246885Z","time spent":"417.254359ms","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:05:54.66432Z","caller":"traceutil/trace.go:171","msg":"trace[322018814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"281.013499ms","start":"2026-04-18T09:05:54.383288Z","end":"2026-04-18T09:05:54.664302Z","steps":["trace[322018814] 'agreement among raft nodes before linearized reading' (duration: 280.868646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:54.664329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:54.327518Z","time spent":"336.79881ms","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-18T09:05:54.664372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.550584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-18T09:05:54.664421Z","caller":"traceutil/trace.go:171","msg":"trace[1350832300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:636; }","duration":"333.619834ms","start":"2026-04-18T09:05:54.33079Z","end":"2026-04-18T09:05:54.66441Z","steps":["trace[1350832300] 'agreement among raft nodes before linearized reading' (duration: 333.485722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:54.664459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:54.33078Z","time spent":"333.664675ms","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-18T09:05:54.664241Z","caller":"traceutil/trace.go:171","msg":"trace[577442851] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:636; }","duration":"334.434588ms","start":"2026-04-18T09:05:54.329779Z","end":"2026-04-18T09:05:54.664214Z","steps":["trace[577442851] 'agreement among raft nodes before linearized reading' (duration: 334.170703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:54.664657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:54.329768Z","time spent":"334.845175ms","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-18T09:05:55.179513Z","caller":"traceutil/trace.go:171","msg":"trace[1643933011] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"509.925936ms","start":"2026-04-18T09:05:54.669563Z","end":"2026-04-18T09:05:55.179489Z","steps":["trace[1643933011] 'process raft request' (duration: 509.865695ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:55.179583Z","caller":"traceutil/trace.go:171","msg":"trace[1921785891] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"509.969606ms","start":"2026-04-18T09:05:54.669554Z","end":"2026-04-18T09:05:55.179524Z","steps":["trace[1921785891] 'process raft request' (duration: 509.696681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:55.179698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:54.669546Z","time spent":"510.067568ms","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:05:55.179773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:54.669526Z","time spent":"510.140139ms","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:05:55.226679Z","caller":"traceutil/trace.go:171","msg":"trace[262919132] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"557.024944ms","start":"2026-04-18T09:05:54.669633Z","end":"2026-04-18T09:05:55.226658Z","steps":["trace[262919132] 'process raft request' (duration: 556.935363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:55.226882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:54.669611Z","time spent":"557.139847ms","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:05:56.085678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.701733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-18T09:05:56.08577Z","caller":"traceutil/trace.go:171","msg":"trace[1578952182] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:639; }","duration":"252.837065ms","start":"2026-04-18T09:05:55.832915Z","end":"2026-04-18T09:05:56.085752Z","steps":["trace[1578952182] 'range keys from in-memory index tree' (duration: 252.54423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:57.360585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.545678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-18T09:05:57.360669Z","caller":"traceutil/trace.go:171","msg":"trace[1151868764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"130.64951ms","start":"2026-04-18T09:05:57.23Z","end":"2026-04-18T09:05:57.36065Z","steps":["trace[1151868764] 'range keys from in-memory index tree' (duration: 130.276594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:57.361418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.751522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:05:57.361466Z","caller":"traceutil/trace.go:171","msg":"trace[1088055851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"977.829464ms","start":"2026-04-18T09:05:56.383626Z","end":"2026-04-18T09:05:57.361455Z","steps":["trace[1088055851] 'range keys from in-memory index tree' (duration: 977.648591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:57.3615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:56.383611Z","time spent":"977.876865ms","remote":"127.0.0.1:37370","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-18T09:05:57.362043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.997988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-18T09:05:57.362107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.415443ms","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-18T09:05:57.362135Z","caller":"traceutil/trace.go:171","msg":"trace[803216534] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:640; }","duration":"693.137711ms","start":"2026-04-18T09:05:56.668973Z","end":"2026-04-18T09:05:57.36211Z","steps":["trace[803216534] 'range keys from in-memory index tree' (duration: 692.798625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:57.362184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:56.668947Z","time spent":"693.227363ms","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-18T09:05:57.362269Z","caller":"traceutil/trace.go:171","msg":"trace[1895319715] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:640; }","duration":"178.512735ms","start":"2026-04-18T09:05:57.18362Z","end":"2026-04-18T09:05:57.362133Z","steps":["trace[1895319715] 'range keys from in-memory index tree' (duration: 178.153829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:57.362576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.047197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-18T09:05:57.362615Z","caller":"traceutil/trace.go:171","msg":"trace[1354336508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"178.144668ms","start":"2026-04-18T09:05:57.184461Z","end":"2026-04-18T09:05:57.362606Z","steps":["trace[1354336508] 'range keys from in-memory index tree' (duration: 177.855444ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:58.079585Z","caller":"traceutil/trace.go:171","msg":"trace[690806081] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"712.340164ms","start":"2026-04-18T09:05:57.367195Z","end":"2026-04-18T09:05:58.079535Z","steps":["trace[690806081] 'process raft request' (duration: 712.133721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:58.079751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:57.367171Z","time spent":"712.491266ms","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:05:58.243592Z","caller":"traceutil/trace.go:171","msg":"trace[1786867444] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"875.447224ms","start":"2026-04-18T09:05:57.368084Z","end":"2026-04-18T09:05:58.243531Z","steps":["trace[1786867444] 'process raft request' (duration: 875.325512ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:05:58.243643Z","caller":"traceutil/trace.go:171","msg":"trace[256026937] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"875.651317ms","start":"2026-04-18T09:05:57.367959Z","end":"2026-04-18T09:05:58.24361Z","steps":["trace[256026937] 'process raft request' (duration: 875.321902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:58.243773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:57.368074Z","time spent":"875.599716ms","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:05:58.243826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:57.367938Z","time spent":"875.791419ms","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:05:58.24377Z","caller":"traceutil/trace.go:171","msg":"trace[1714933829] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"875.269701ms","start":"2026-04-18T09:05:57.368379Z","end":"2026-04-18T09:05:58.243648Z","steps":["trace[1714933829] 'process raft request' (duration: 875.074157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:05:58.244059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:57.368367Z","time spent":"875.565335ms","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:06:00.39843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.551846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T09:06:00.398542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.338782ms","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-18T09:06:00.398625Z","caller":"traceutil/trace.go:171","msg":"trace[1819226404] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:668; }","duration":"486.80427ms","start":"2026-04-18T09:05:59.911795Z","end":"2026-04-18T09:06:00.398599Z","steps":["trace[1819226404] 'range keys from in-memory index tree' (duration: 486.449624ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:06:00.398681Z","caller":"traceutil/trace.go:171","msg":"trace[1396671155] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:668; }","duration":"314.538216ms","start":"2026-04-18T09:06:00.08412Z","end":"2026-04-18T09:06:00.398658Z","steps":["trace[1396671155] 'range keys from in-memory index tree' (duration: 314.077209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:06:00.398771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:06:00.084095Z","time spent":"314.662749ms","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-18T09:06:00.398818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:05:59.911767Z","time spent":"486.971222ms","remote":"127.0.0.1:37410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-04-18T09:06:00.399515Z","caller":"traceutil/trace.go:171","msg":"trace[677153547] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:694; }","duration":"151.226938ms","start":"2026-04-18T09:06:00.248257Z","end":"2026-04-18T09:06:00.399484Z","steps":["trace[677153547] 'read index received' (duration: 151.077685ms)","trace[677153547] 'applied index is now lower than readState.Index' (duration: 147.283µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:06:00.399811Z","caller":"traceutil/trace.go:171","msg":"trace[1409755843] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"285.433387ms","start":"2026-04-18T09:06:00.114196Z","end":"2026-04-18T09:06:00.399629Z","steps":["trace[1409755843] 'process raft request' (duration: 285.044941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:06:00.400107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.805157ms","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-18T09:06:00.400169Z","caller":"traceutil/trace.go:171","msg":"trace[170096128] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:669; }","duration":"151.95978ms","start":"2026-04-18T09:06:00.248194Z","end":"2026-04-18T09:06:00.400154Z","steps":["trace[170096128] 'agreement among raft nodes before linearized reading' (duration: 151.456572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:06:00.4055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.910839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-18T09:06:00.405557Z","caller":"traceutil/trace.go:171","msg":"trace[1620484145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:670; }","duration":"155.984491ms","start":"2026-04-18T09:06:00.249563Z","end":"2026-04-18T09:06:00.405548Z","steps":["trace[1620484145] 'agreement among raft nodes before linearized reading' (duration: 155.845748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:06:00.405739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.117933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-18T09:06:00.405786Z","caller":"traceutil/trace.go:171","msg":"trace[45509485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:670; }","duration":"156.166274ms","start":"2026-04-18T09:06:00.249612Z","end":"2026-04-18T09:06:00.405779Z","steps":["trace[45509485] 'agreement among raft nodes before linearized reading' (duration: 156.055102ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:06:10.764972Z","caller":"traceutil/trace.go:171","msg":"trace[1890885104] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"301.818573ms","start":"2026-04-18T09:06:10.463126Z","end":"2026-04-18T09:06:10.764945Z","steps":["trace[1890885104] 'process raft request' (duration: 301.683221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:06:10.765144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:06:10.463102Z","time spent":"301.958106ms","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:06:10.768245Z","caller":"traceutil/trace.go:171","msg":"trace[2079171498] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"303.243338ms","start":"2026-04-18T09:06:10.464975Z","end":"2026-04-18T09:06:10.768218Z","steps":["trace[2079171498] 'process raft request' (duration: 303.046924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:06:10.768423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:06:10.464926Z","time spent":"303.407311ms","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:06:10.768552Z","caller":"traceutil/trace.go:171","msg":"trace[659884644] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"303.280279ms","start":"2026-04-18T09:06:10.465255Z","end":"2026-04-18T09:06:10.768535Z","steps":["trace[659884644] 'process raft request' (duration: 302.915552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:06:10.768643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:06:10.465245Z","time spent":"303.36076ms","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:06:10.768482Z","caller":"traceutil/trace.go:171","msg":"trace[59412646] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:806; }","duration":"300.737044ms","start":"2026-04-18T09:06:10.467726Z","end":"2026-04-18T09:06:10.768463Z","steps":["trace[59412646] 'read index received' (duration: 297.081939ms)","trace[59412646] 'applied index is now lower than readState.Index' (duration: 3.653365ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:06:10.768813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.09046ms","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-18T09:06:10.768879Z","caller":"traceutil/trace.go:171","msg":"trace[84801665] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:780; }","duration":"301.179592ms","start":"2026-04-18T09:06:10.467688Z","end":"2026-04-18T09:06:10.768867Z","steps":["trace[84801665] 'agreement among raft nodes before linearized reading' (duration: 300.964398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:06:10.768954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:06:10.467679Z","time spent":"301.266063ms","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-18T09:06:13.269915Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744094373886631,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-18T09:06:14.453769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065275747s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:06:14.453895Z","caller":"traceutil/trace.go:171","msg":"trace[1940129519] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:821; }","duration":"1.684057615s","start":"2026-04-18T09:06:12.769819Z","end":"2026-04-18T09:06:14.453877Z","steps":["trace[1940129519] 'read index received' (duration: 618.504323ms)","trace[1940129519] 'applied index is now lower than readState.Index' (duration: 1.065551532s)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:06:14.454105Z","caller":"traceutil/trace.go:171","msg":"trace[110333178] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"1.738799483s","start":"2026-04-18T09:06:12.715293Z","end":"2026-04-18T09:06:14.454093Z","steps":["trace[110333178] 'process raft request' (duration: 673.105909ms)","trace[110333178] 'compare' (duration: 1.064644716s)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:06:14.454178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:06:12.715265Z","time spent":"1.738875804s","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:06:14.454435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.684621084s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-18T09:06:14.454468Z","caller":"traceutil/trace.go:171","msg":"trace[724112068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:791; }","duration":"1.684692656s","start":"2026-04-18T09:06:12.769767Z","end":"2026-04-18T09:06:14.454459Z","steps":["trace[724112068] 'agreement among raft nodes before linearized reading' (duration: 1.684574264s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:06:14.454496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:06:12.769748Z","time spent":"1.684741576s","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-18T09:06:14.454911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.682623419s","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-18T09:06:14.454992Z","caller":"traceutil/trace.go:171","msg":"trace[2089890318] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:791; }","duration":"1.682726911s","start":"2026-04-18T09:06:12.772247Z","end":"2026-04-18T09:06:14.454974Z","steps":["trace[2089890318] 'agreement among raft nodes before linearized reading' (duration: 1.682513127s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:06:14.455042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:06:12.772237Z","time spent":"1.682793182s","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-18T09:06:14.455282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.678026868s","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-18T09:06:14.455365Z","caller":"traceutil/trace.go:171","msg":"trace[204492326] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:791; }","duration":"1.678147711s","start":"2026-04-18T09:06:12.777196Z","end":"2026-04-18T09:06:14.455344Z","steps":["trace[204492326] 'agreement among raft nodes before linearized reading' (duration: 1.677733824s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:06:14.455405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:06:12.777181Z","time spent":"1.678217022s","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-18T09:06:14.455442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.682165841s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-18T09:06:14.45543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.622977783s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-w6czd\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-18T09:06:14.455487Z","caller":"traceutil/trace.go:171","msg":"trace[471852336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:791; }","duration":"1.682249723s","start":"2026-04-18T09:06:12.773228Z","end":"2026-04-18T09:06:14.455478Z","steps":["trace[471852336] 'agreement among raft nodes before linearized reading' (duration: 1.682154111s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:06:14.455526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:06:12.773203Z","time spent":"1.682314084s","remote":"127.0.0.1:37606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-18T09:06:14.455573Z","caller":"traceutil/trace.go:171","msg":"trace[1358789842] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-w6czd; range_end:; response_count:1; response_revision:791; }","duration":"1.623118325s","start":"2026-04-18T09:06:12.832392Z","end":"2026-04-18T09:06:14.455511Z","steps":["trace[1358789842] 'agreement among raft nodes before linearized reading' (duration: 1.622586116s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:06:14.455584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.07171232s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:06:14.455633Z","caller":"traceutil/trace.go:171","msg":"trace[1022437671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"1.071792911s","start":"2026-04-18T09:06:13.383823Z","end":"2026-04-18T09:06:14.455616Z","steps":["trace[1022437671] 'agreement among raft nodes before linearized reading' (duration: 1.071679709s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:06:14.455628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:06:12.832292Z","time spent":"1.623326899s","remote":"127.0.0.1:37522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-w6czd\" "} {"level":"warn","ts":"2026-04-18T09:06:14.455665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:06:13.383729Z","time spent":"1.071929565s","remote":"127.0.0.1:37370","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-18T09:06:14.456235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.240806596s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:06:14.456285Z","caller":"traceutil/trace.go:171","msg":"trace[1546367933] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:791; }","duration":"1.242305842s","start":"2026-04-18T09:06:13.213967Z","end":"2026-04-18T09:06:14.456273Z","steps":["trace[1546367933] 'agreement among raft nodes before linearized reading' (duration: 1.240835036s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:06:14.456313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:06:13.213939Z","time spent":"1.242366443s","remote":"127.0.0.1:37808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-04-18T09:06:14.456802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.046026ms","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-18T09:06:14.456994Z","caller":"traceutil/trace.go:171","msg":"trace[617458493] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:791; }","duration":"670.28597ms","start":"2026-04-18T09:06:13.786697Z","end":"2026-04-18T09:06:14.456983Z","steps":["trace[617458493] 'agreement among raft nodes before linearized reading' (duration: 670.021175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:06:14.457024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:06:13.786674Z","time spent":"670.340631ms","remote":"127.0.0.1:37606","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\" "}