{"level":"warn","ts":"2026-02-25T17:04:35.404007Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T17:04:35.404109Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.201:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.201:2380","--initial-cluster=instance=https://199.19.213.201:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.201:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.201: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-02-25T17:04:35.404181Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T17:04:35.404194Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.201:2380"]} {"level":"info","ts":"2026-02-25T17:04:35.404212Z","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-02-25T17:04:35.405172Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.201:2379"]} {"level":"info","ts":"2026-02-25T17:04:35.405264Z","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":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.201:2380"],"listen-peer-urls":["https://199.19.213.201:2380"],"advertise-client-urls":["https://199.19.213.201:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.201:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.201: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-02-25T17:04:35.409024Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.509918ms"} {"level":"info","ts":"2026-02-25T17:04:35.415261Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"61bc451777564e43","cluster-id":"b1814cb801e33ddc"} {"level":"info","ts":"2026-02-25T17:04:35.415336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T17:04:35.415602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became follower at term 0"} {"level":"info","ts":"2026-02-25T17:04:35.415615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 61bc451777564e43 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T17:04:35.415623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became follower at term 1"} {"level":"info","ts":"2026-02-25T17:04:35.415662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 switched to configuration voters=(7042579884389322307)"} {"level":"warn","ts":"2026-02-25T17:04:35.421035Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T17:04:35.422341Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T17:04:35.423428Z","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-02-25T17:04:35.42563Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"61bc451777564e43","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T17:04:35.4258Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"61bc451777564e43","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T17:04:35.425945Z","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-02-25T17:04:35.426074Z","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-02-25T17:04:35.426094Z","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-02-25T17:04:35.427032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 switched to configuration voters=(7042579884389322307)"} {"level":"info","ts":"2026-02-25T17:04:35.427525Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b1814cb801e33ddc","local-member-id":"61bc451777564e43","added-peer-id":"61bc451777564e43","added-peer-peer-urls":["https://199.19.213.201:2380"]} {"level":"info","ts":"2026-02-25T17:04:35.42944Z","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-02-25T17:04:35.42958Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.201:2380"} {"level":"info","ts":"2026-02-25T17:04:35.429651Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.201:2380"} {"level":"info","ts":"2026-02-25T17:04:35.429844Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"61bc451777564e43","initial-advertise-peer-urls":["https://199.19.213.201:2380"],"listen-peer-urls":["https://199.19.213.201:2380"],"advertise-client-urls":["https://199.19.213.201:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.201:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T17:04:35.429907Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T17:04:35.516669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T17:04:35.51676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T17:04:35.516821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 received MsgPreVoteResp from 61bc451777564e43 at term 1"} {"level":"info","ts":"2026-02-25T17:04:35.516845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became candidate at term 2"} {"level":"info","ts":"2026-02-25T17:04:35.516857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 received MsgVoteResp from 61bc451777564e43 at term 2"} {"level":"info","ts":"2026-02-25T17:04:35.516872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became leader at term 2"} {"level":"info","ts":"2026-02-25T17:04:35.516886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 61bc451777564e43 elected leader 61bc451777564e43 at term 2"} {"level":"info","ts":"2026-02-25T17:04:35.518123Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T17:04:35.518976Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T17:04:35.51897Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"61bc451777564e43","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.201:2379]}","request-path":"/0/members/61bc451777564e43/attributes","cluster-id":"b1814cb801e33ddc","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T17:04:35.519082Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T17:04:35.519341Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T17:04:35.519402Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T17:04:35.519527Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b1814cb801e33ddc","local-member-id":"61bc451777564e43","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T17:04:35.519753Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T17:04:35.51981Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T17:04:35.522542Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T17:04:35.528657Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.201:2379"} {"level":"info","ts":"2026-02-25T17:04:37.404962Z","caller":"traceutil/trace.go:171","msg":"trace[1465345898] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"294.749795ms","start":"2026-02-25T17:04:37.110191Z","end":"2026-02-25T17:04:37.404941Z","steps":["trace[1465345898] 'process raft request' (duration: 294.51047ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:37.445944Z","caller":"traceutil/trace.go:171","msg":"trace[628823600] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"335.553742ms","start":"2026-02-25T17:04:37.11035Z","end":"2026-02-25T17:04:37.445904Z","steps":["trace[628823600] 'process raft request' (duration: 332.078965ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:37.446128Z","caller":"traceutil/trace.go:171","msg":"trace[495753692] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"253.321736ms","start":"2026-02-25T17:04:37.192738Z","end":"2026-02-25T17:04:37.44606Z","steps":["trace[495753692] 'process raft request' (duration: 253.0482ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:37.446224Z","caller":"traceutil/trace.go:171","msg":"trace[1062957289] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"253.360917ms","start":"2026-02-25T17:04:37.19277Z","end":"2026-02-25T17:04:37.446131Z","steps":["trace[1062957289] 'process raft request' (duration: 253.04765ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:37.446299Z","caller":"traceutil/trace.go:171","msg":"trace[357456058] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"254.003181ms","start":"2026-02-25T17:04:37.192199Z","end":"2026-02-25T17:04:37.446202Z","steps":["trace[357456058] 'process raft request' (duration: 253.547851ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:37.446336Z","caller":"traceutil/trace.go:171","msg":"trace[1116540273] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"253.49235ms","start":"2026-02-25T17:04:37.192771Z","end":"2026-02-25T17:04:37.446263Z","steps":["trace[1116540273] 'process raft request' (duration: 253.075231ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:37.44652Z","caller":"traceutil/trace.go:171","msg":"trace[562007970] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"253.599552ms","start":"2026-02-25T17:04:37.192894Z","end":"2026-02-25T17:04:37.446494Z","steps":["trace[562007970] 'process raft request' (duration: 252.977469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:37.446735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:37.11032Z","time spent":"335.707995ms","remote":"127.0.0.1:33966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:37.446833Z","caller":"traceutil/trace.go:171","msg":"trace[1916329246] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"253.839307ms","start":"2026-02-25T17:04:37.192978Z","end":"2026-02-25T17:04:37.446817Z","steps":["trace[1916329246] 'process raft request' (duration: 253.465229ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:37.447438Z","caller":"traceutil/trace.go:171","msg":"trace[49283567] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"244.05736ms","start":"2026-02-25T17:04:37.203344Z","end":"2026-02-25T17:04:37.447401Z","steps":["trace[49283567] 'process raft request' (duration: 243.814405ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:37.447481Z","caller":"traceutil/trace.go:171","msg":"trace[649653430] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"245.84952ms","start":"2026-02-25T17:04:37.20158Z","end":"2026-02-25T17:04:37.44743Z","steps":["trace[649653430] 'process raft request' (duration: 245.166825ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:37.447453Z","caller":"traceutil/trace.go:171","msg":"trace[512979228] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"327.313369ms","start":"2026-02-25T17:04:37.120029Z","end":"2026-02-25T17:04:37.447343Z","steps":["trace[512979228] 'read index received' (duration: 284.722333ms)","trace[512979228] 'applied index is now lower than readState.Index' (duration: 42.590246ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:37.447769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.824911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T17:04:37.447903Z","caller":"traceutil/trace.go:171","msg":"trace[1528855649] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"288.052766ms","start":"2026-02-25T17:04:37.159836Z","end":"2026-02-25T17:04:37.447889Z","steps":["trace[1528855649] 'agreement among raft nodes before linearized reading' (duration: 287.817401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:37.448016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.316516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T17:04:37.448109Z","caller":"traceutil/trace.go:171","msg":"trace[30140386] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"254.473281ms","start":"2026-02-25T17:04:37.193596Z","end":"2026-02-25T17:04:37.44807Z","steps":["trace[30140386] 'agreement among raft nodes before linearized reading' (duration: 254.309577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:37.448161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.334503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T17:04:37.448195Z","caller":"traceutil/trace.go:171","msg":"trace[139855240] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"284.395025ms","start":"2026-02-25T17:04:37.16379Z","end":"2026-02-25T17:04:37.448185Z","steps":["trace[139855240] 'agreement among raft nodes before linearized reading' (duration: 284.341654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:37.448164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.100349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.201\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T17:04:37.44826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.444127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T17:04:37.448298Z","caller":"traceutil/trace.go:171","msg":"trace[856923723] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"284.483978ms","start":"2026-02-25T17:04:37.163802Z","end":"2026-02-25T17:04:37.448286Z","steps":["trace[856923723] 'agreement among raft nodes before linearized reading' (duration: 284.408026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:37.448289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.837442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T17:04:37.448378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.953619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T17:04:37.44839Z","caller":"traceutil/trace.go:171","msg":"trace[1247967157] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"192.963135ms","start":"2026-02-25T17:04:37.255409Z","end":"2026-02-25T17:04:37.448372Z","steps":["trace[1247967157] 'agreement among raft nodes before linearized reading' (duration: 192.825841ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:37.448397Z","caller":"traceutil/trace.go:171","msg":"trace[1628042102] range","detail":"{range_begin:/registry/masterleases/199.19.213.201; range_end:; response_count:0; response_revision:11; }","duration":"257.279913ms","start":"2026-02-25T17:04:37.190983Z","end":"2026-02-25T17:04:37.448263Z","steps":["trace[1628042102] 'agreement among raft nodes before linearized reading' (duration: 257.021197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:37.44847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.69976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T17:04:37.448442Z","caller":"traceutil/trace.go:171","msg":"trace[630654076] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"204.055311ms","start":"2026-02-25T17:04:37.244367Z","end":"2026-02-25T17:04:37.448423Z","steps":["trace[630654076] 'agreement among raft nodes before linearized reading' (duration: 203.930739ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:37.448593Z","caller":"traceutil/trace.go:171","msg":"trace[1529858204] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"256.855974ms","start":"2026-02-25T17:04:37.191706Z","end":"2026-02-25T17:04:37.448562Z","steps":["trace[1529858204] 'agreement among raft nodes before linearized reading' (duration: 256.595269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:37.448739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.753561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T17:04:37.448795Z","caller":"traceutil/trace.go:171","msg":"trace[1094227456] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"328.847973ms","start":"2026-02-25T17:04:37.11993Z","end":"2026-02-25T17:04:37.448778Z","steps":["trace[1094227456] 'agreement among raft nodes before linearized reading' (duration: 327.551794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:37.448826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:37.119909Z","time spent":"328.909395ms","remote":"127.0.0.1:34326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-02-25T17:04:37.995841Z","caller":"traceutil/trace.go:171","msg":"trace[582281787] linearizableReadLoop","detail":"{readStateIndex:79; appliedIndex:77; }","duration":"111.805423ms","start":"2026-02-25T17:04:37.884012Z","end":"2026-02-25T17:04:37.995817Z","steps":["trace[582281787] 'read index received' (duration: 74.357342ms)","trace[582281787] 'applied index is now lower than readState.Index' (duration: 37.447201ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:37.995942Z","caller":"traceutil/trace.go:171","msg":"trace[2139878648] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"120.927365ms","start":"2026-02-25T17:04:37.874969Z","end":"2026-02-25T17:04:37.995896Z","steps":["trace[2139878648] 'process raft request' (duration: 83.471124ms)","trace[2139878648] 'compare' (duration: 37.152184ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:37.996012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.978697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18978c26501e4df7\" ","response":"range_response_count:1 size:654"} {"level":"info","ts":"2026-02-25T17:04:37.996044Z","caller":"traceutil/trace.go:171","msg":"trace[1888528740] range","detail":"{range_begin:/registry/events/default/instance.18978c26501e4df7; range_end:; response_count:1; response_revision:74; }","duration":"112.052918ms","start":"2026-02-25T17:04:37.883979Z","end":"2026-02-25T17:04:37.996032Z","steps":["trace[1888528740] 'agreement among raft nodes before linearized reading' (duration: 111.979306ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:37.99604Z","caller":"traceutil/trace.go:171","msg":"trace[2054819108] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"119.938034ms","start":"2026-02-25T17:04:37.87606Z","end":"2026-02-25T17:04:37.995999Z","steps":["trace[2054819108] 'process raft request' (duration: 119.699559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:38.247092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.928735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-25T17:04:38.247324Z","caller":"traceutil/trace.go:171","msg":"trace[117852481] linearizableReadLoop","detail":"{readStateIndex:84; appliedIndex:82; }","duration":"156.076426ms","start":"2026-02-25T17:04:38.091227Z","end":"2026-02-25T17:04:38.247303Z","steps":["trace[117852481] 'read index received' (duration: 21.759654ms)","trace[117852481] 'applied index is now lower than readState.Index' (duration: 134.315802ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:38.247617Z","caller":"traceutil/trace.go:171","msg":"trace[263103563] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"190.263445ms","start":"2026-02-25T17:04:38.057329Z","end":"2026-02-25T17:04:38.247593Z","steps":["trace[263103563] 'process raft request' (duration: 55.564034ms)","trace[263103563] 'compare' (duration: 133.769951ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:38.247833Z","caller":"traceutil/trace.go:171","msg":"trace[1566660766] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"188.23227ms","start":"2026-02-25T17:04:38.059558Z","end":"2026-02-25T17:04:38.24779Z","steps":["trace[1566660766] 'process raft request' (duration: 187.679318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:38.24809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.866913ms","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-02-25T17:04:38.248166Z","caller":"traceutil/trace.go:171","msg":"trace[772275708] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:79; }","duration":"156.948625ms","start":"2026-02-25T17:04:38.091204Z","end":"2026-02-25T17:04:38.248153Z","steps":["trace[772275708] 'agreement among raft nodes before linearized reading' (duration: 156.831552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:38.248079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.305161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T17:04:38.248308Z","caller":"traceutil/trace.go:171","msg":"trace[856761483] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:79; }","duration":"156.565136ms","start":"2026-02-25T17:04:38.091695Z","end":"2026-02-25T17:04:38.24826Z","steps":["trace[856761483] 'agreement among raft nodes before linearized reading' (duration: 156.2883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:38.548076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.865806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-25T17:04:38.548407Z","caller":"traceutil/trace.go:171","msg":"trace[714925682] transaction","detail":"{read_only:false; response_revision:82; number_of_response:1; }","duration":"236.328818ms","start":"2026-02-25T17:04:38.312051Z","end":"2026-02-25T17:04:38.54838Z","steps":["trace[714925682] 'process raft request' (duration: 236.184835ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:38.548427Z","caller":"traceutil/trace.go:171","msg":"trace[926777003] transaction","detail":"{read_only:false; response_revision:81; number_of_response:1; }","duration":"294.901548ms","start":"2026-02-25T17:04:38.253481Z","end":"2026-02-25T17:04:38.548383Z","steps":["trace[926777003] 'process raft request' (duration: 145.604693ms)","trace[926777003] 'compare' (duration: 148.693772ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:38.548427Z","caller":"traceutil/trace.go:171","msg":"trace[1945153690] linearizableReadLoop","detail":"{readStateIndex:86; appliedIndex:85; }","duration":"238.928795ms","start":"2026-02-25T17:04:38.309454Z","end":"2026-02-25T17:04:38.548382Z","steps":["trace[1945153690] 'read index received' (duration: 89.648651ms)","trace[1945153690] 'applied index is now lower than readState.Index' (duration: 149.276634ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:38.548609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.118863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T17:04:38.548688Z","caller":"traceutil/trace.go:171","msg":"trace[1410651410] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:82; }","duration":"295.247347ms","start":"2026-02-25T17:04:38.253417Z","end":"2026-02-25T17:04:38.548665Z","steps":["trace[1410651410] 'agreement among raft nodes before linearized reading' (duration: 295.048712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:38.548806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.725617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-cluster-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T17:04:38.54887Z","caller":"traceutil/trace.go:171","msg":"trace[1392521225] range","detail":"{range_begin:/registry/priorityclasses/system-cluster-critical; range_end:; response_count:0; response_revision:82; }","duration":"236.825869ms","start":"2026-02-25T17:04:38.312027Z","end":"2026-02-25T17:04:38.548853Z","steps":["trace[1392521225] 'agreement among raft nodes before linearized reading' (duration: 236.713146ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:38.737809Z","caller":"traceutil/trace.go:171","msg":"trace[52089462] transaction","detail":"{read_only:false; response_revision:85; number_of_response:1; }","duration":"151.041724ms","start":"2026-02-25T17:04:38.586731Z","end":"2026-02-25T17:04:38.737772Z","steps":["trace[52089462] 'process raft request' (duration: 141.666035ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:38.737882Z","caller":"traceutil/trace.go:171","msg":"trace[1067243715] transaction","detail":"{read_only:false; response_revision:86; number_of_response:1; }","duration":"129.080286ms","start":"2026-02-25T17:04:38.608726Z","end":"2026-02-25T17:04:38.737806Z","steps":["trace[1067243715] 'process raft request' (duration: 129.023105ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:38.737867Z","caller":"traceutil/trace.go:171","msg":"trace[1269446955] linearizableReadLoop","detail":"{readStateIndex:90; appliedIndex:89; }","duration":"130.783135ms","start":"2026-02-25T17:04:38.607014Z","end":"2026-02-25T17:04:38.737797Z","steps":["trace[1269446955] 'read index received' (duration: 121.353335ms)","trace[1269446955] 'applied index is now lower than readState.Index' (duration: 9.42743ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:38.738055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.02563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T17:04:38.7381Z","caller":"traceutil/trace.go:171","msg":"trace[128672533] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:86; }","duration":"131.125072ms","start":"2026-02-25T17:04:38.606964Z","end":"2026-02-25T17:04:38.738089Z","steps":["trace[128672533] 'agreement among raft nodes before linearized reading' (duration: 130.964539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:39.066579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.259666ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-25T17:04:39.066762Z","caller":"traceutil/trace.go:171","msg":"trace[1851538510] transaction","detail":"{read_only:false; response_revision:99; number_of_response:1; }","duration":"111.602519ms","start":"2026-02-25T17:04:38.955142Z","end":"2026-02-25T17:04:39.066745Z","steps":["trace[1851538510] 'process raft request' (duration: 111.551988ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:39.06686Z","caller":"traceutil/trace.go:171","msg":"trace[1051889334] transaction","detail":"{read_only:false; response_revision:98; number_of_response:1; }","duration":"114.411821ms","start":"2026-02-25T17:04:38.952396Z","end":"2026-02-25T17:04:39.066808Z","steps":["trace[1051889334] 'process raft request' (duration: 13.834968ms)","trace[1051889334] 'compare' (duration: 100.123334ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:40.049364Z","caller":"traceutil/trace.go:171","msg":"trace[1358566978] linearizableReadLoop","detail":"{readStateIndex:227; appliedIndex:226; }","duration":"102.093313ms","start":"2026-02-25T17:04:39.947239Z","end":"2026-02-25T17:04:40.049332Z","steps":["trace[1358566978] 'read index received' (duration: 99.204408ms)","trace[1358566978] 'applied index is now lower than readState.Index' (duration: 2.887735ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:40.049493Z","caller":"traceutil/trace.go:171","msg":"trace[1337827274] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"103.591786ms","start":"2026-02-25T17:04:39.945851Z","end":"2026-02-25T17:04:40.049442Z","steps":["trace[1337827274] 'process raft request' (duration: 100.675581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:40.049714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.45013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-25T17:04:40.049779Z","caller":"traceutil/trace.go:171","msg":"trace[72668525] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:222; }","duration":"102.560842ms","start":"2026-02-25T17:04:39.9472Z","end":"2026-02-25T17:04:40.04976Z","steps":["trace[72668525] 'agreement among raft nodes before linearized reading' (duration: 102.261506ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:41.779523Z","caller":"traceutil/trace.go:171","msg":"trace[1378759789] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"111.202821ms","start":"2026-02-25T17:04:41.668294Z","end":"2026-02-25T17:04:41.779497Z","steps":["trace[1378759789] 'process raft request' (duration: 100.272208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:42.275715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.575094ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:04:42.27585Z","caller":"traceutil/trace.go:171","msg":"trace[345253763] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"211.831508ms","start":"2026-02-25T17:04:42.063997Z","end":"2026-02-25T17:04:42.275828Z","steps":["trace[345253763] 'process raft request' (duration: 94.0654ms)","trace[345253763] 'compare' (duration: 117.436361ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:42.290687Z","caller":"traceutil/trace.go:171","msg":"trace[853251882] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"224.708925ms","start":"2026-02-25T17:04:42.065955Z","end":"2026-02-25T17:04:42.290664Z","steps":["trace[853251882] 'process raft request' (duration: 224.478049ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:42.290798Z","caller":"traceutil/trace.go:171","msg":"trace[1668413384] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"189.816156ms","start":"2026-02-25T17:04:42.100944Z","end":"2026-02-25T17:04:42.290761Z","steps":["trace[1668413384] 'process raft request' (duration: 189.618101ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:42.290815Z","caller":"traceutil/trace.go:171","msg":"trace[1874713069] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"171.258492ms","start":"2026-02-25T17:04:42.119546Z","end":"2026-02-25T17:04:42.290804Z","steps":["trace[1874713069] 'process raft request' (duration: 171.139289ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:42.29075Z","caller":"traceutil/trace.go:171","msg":"trace[1027006348] transaction","detail":"{read_only:false; number_of_response:0; response_revision:280; }","duration":"189.470939ms","start":"2026-02-25T17:04:42.101259Z","end":"2026-02-25T17:04:42.29073Z","steps":["trace[1027006348] 'process raft request' (duration: 189.346536ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:42.290963Z","caller":"traceutil/trace.go:171","msg":"trace[1781408708] transaction","detail":"{read_only:false; number_of_response:0; response_revision:281; }","duration":"171.271302ms","start":"2026-02-25T17:04:42.119606Z","end":"2026-02-25T17:04:42.290878Z","steps":["trace[1781408708] 'process raft request' (duration: 171.136569ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:42.290799Z","caller":"traceutil/trace.go:171","msg":"trace[637547197] transaction","detail":"{read_only:false; number_of_response:0; response_revision:280; }","duration":"171.358504ms","start":"2026-02-25T17:04:42.119427Z","end":"2026-02-25T17:04:42.290786Z","steps":["trace[637547197] 'process raft request' (duration: 171.202641ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:42.290824Z","caller":"traceutil/trace.go:171","msg":"trace[1718135535] transaction","detail":"{read_only:false; number_of_response:0; response_revision:280; }","duration":"171.252431ms","start":"2026-02-25T17:04:42.119527Z","end":"2026-02-25T17:04:42.290779Z","steps":["trace[1718135535] 'process raft request' (duration: 171.134338ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:42.290827Z","caller":"traceutil/trace.go:171","msg":"trace[1532061226] transaction","detail":"{read_only:false; number_of_response:0; response_revision:281; }","duration":"170.421934ms","start":"2026-02-25T17:04:42.120386Z","end":"2026-02-25T17:04:42.290808Z","steps":["trace[1532061226] 'process raft request' (duration: 170.392603ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:43.310268Z","caller":"traceutil/trace.go:171","msg":"trace[1851680913] linearizableReadLoop","detail":"{readStateIndex:302; appliedIndex:301; }","duration":"273.33318ms","start":"2026-02-25T17:04:43.036914Z","end":"2026-02-25T17:04:43.310248Z","steps":["trace[1851680913] 'read index received' (duration: 273.194727ms)","trace[1851680913] 'applied index is now lower than readState.Index' (duration: 137.763µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:43.310376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.468072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-02-25T17:04:43.310399Z","caller":"traceutil/trace.go:171","msg":"trace[779906635] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:290; }","duration":"273.712438ms","start":"2026-02-25T17:04:43.036678Z","end":"2026-02-25T17:04:43.310391Z","steps":["trace[779906635] 'agreement among raft nodes before linearized reading' (duration: 273.649697ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:43.310431Z","caller":"traceutil/trace.go:171","msg":"trace[13693226] transaction","detail":"{read_only:false; number_of_response:0; response_revision:290; }","duration":"273.686328ms","start":"2026-02-25T17:04:43.036651Z","end":"2026-02-25T17:04:43.310337Z","steps":["trace[13693226] 'process raft request' (duration: 273.520074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:43.740461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.46495ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:04:43.740574Z","caller":"traceutil/trace.go:171","msg":"trace[1347663053] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"367.224515ms","start":"2026-02-25T17:04:43.373332Z","end":"2026-02-25T17:04:43.740556Z","steps":["trace[1347663053] 'process raft request' (duration: 159.585771ms)","trace[1347663053] 'compare' (duration: 207.293806ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:43.740784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:43.373308Z","time spent":"367.388648ms","remote":"127.0.0.1:34118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4245,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:43.74379Z","caller":"traceutil/trace.go:171","msg":"trace[168343679] linearizableReadLoop","detail":"{readStateIndex:306; appliedIndex:304; }","duration":"368.227927ms","start":"2026-02-25T17:04:43.375544Z","end":"2026-02-25T17:04:43.743772Z","steps":["trace[168343679] 'read index received' (duration: 157.422463ms)","trace[168343679] 'applied index is now lower than readState.Index' (duration: 210.804544ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:43.743815Z","caller":"traceutil/trace.go:171","msg":"trace[478711173] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"368.410781ms","start":"2026-02-25T17:04:43.375376Z","end":"2026-02-25T17:04:43.743787Z","steps":["trace[478711173] 'process raft request' (duration: 368.094254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:43.743904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.35984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T17:04:43.743928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:43.375349Z","time spent":"368.516083ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T17:04:43.743954Z","caller":"traceutil/trace.go:171","msg":"trace[450404007] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:294; }","duration":"368.421642ms","start":"2026-02-25T17:04:43.375511Z","end":"2026-02-25T17:04:43.743933Z","steps":["trace[450404007] 'agreement among raft nodes before linearized reading' (duration: 368.324279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:43.74398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:43.3755Z","time spent":"368.475093ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T17:04:43.744053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.904992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:43.74415Z","caller":"traceutil/trace.go:171","msg":"trace[1360241005] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:0; response_revision:294; }","duration":"360.024345ms","start":"2026-02-25T17:04:43.384101Z","end":"2026-02-25T17:04:43.744125Z","steps":["trace[1360241005] 'agreement among raft nodes before linearized reading' (duration: 359.862171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:43.74421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:43.384075Z","time spent":"360.121396ms","remote":"127.0.0.1:34144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-02-25T17:04:44.203341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.742696ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:04:44.203457Z","caller":"traceutil/trace.go:171","msg":"trace[927745617] linearizableReadLoop","detail":"{readStateIndex:309; appliedIndex:308; }","duration":"438.929835ms","start":"2026-02-25T17:04:43.76451Z","end":"2026-02-25T17:04:44.20344Z","steps":["trace[927745617] 'read index received' (duration: 286.925734ms)","trace[927745617] 'applied index is now lower than readState.Index' (duration: 152.002991ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:44.20352Z","caller":"traceutil/trace.go:171","msg":"trace[1778578142] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"448.707982ms","start":"2026-02-25T17:04:43.754751Z","end":"2026-02-25T17:04:44.203459Z","steps":["trace[1778578142] 'process raft request' (duration: 296.749242ms)","trace[1778578142] 'compare' (duration: 151.599953ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:44.203596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.357403ms","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-02-25T17:04:44.20366Z","caller":"traceutil/trace.go:171","msg":"trace[1964879578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:297; }","duration":"404.478295ms","start":"2026-02-25T17:04:43.799163Z","end":"2026-02-25T17:04:44.203642Z","steps":["trace[1964879578] 'agreement among raft nodes before linearized reading' (duration: 404.365223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:44.203658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.119389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T17:04:44.203713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:43.799141Z","time spent":"404.557637ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T17:04:44.203679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:43.754723Z","time spent":"448.905597ms","remote":"127.0.0.1:34118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4583,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:44.203766Z","caller":"traceutil/trace.go:171","msg":"trace[316558178] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-controller; range_end:; response_count:0; response_revision:297; }","duration":"439.276973ms","start":"2026-02-25T17:04:43.764463Z","end":"2026-02-25T17:04:44.20374Z","steps":["trace[316558178] 'agreement among raft nodes before linearized reading' (duration: 439.061818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:44.203823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:43.764447Z","time spent":"439.367294ms","remote":"127.0.0.1:34144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/service-controller\" "} {"level":"warn","ts":"2026-02-25T17:04:44.204046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.87432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T17:04:44.204102Z","caller":"traceutil/trace.go:171","msg":"trace[1558542585] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:297; }","duration":"324.981472ms","start":"2026-02-25T17:04:43.879105Z","end":"2026-02-25T17:04:44.204087Z","steps":["trace[1558542585] 'agreement among raft nodes before linearized reading' (duration: 324.861029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:44.204149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:43.879082Z","time spent":"325.046353ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T17:04:44.391726Z","caller":"traceutil/trace.go:171","msg":"trace[927500425] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"180.20061ms","start":"2026-02-25T17:04:44.211478Z","end":"2026-02-25T17:04:44.391678Z","steps":["trace[927500425] 'process raft request' (duration: 146.673212ms)","trace[927500425] 'compare' (duration: 33.072678ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:44.39194Z","caller":"traceutil/trace.go:171","msg":"trace[1283786354] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"180.290933ms","start":"2026-02-25T17:04:44.211559Z","end":"2026-02-25T17:04:44.39185Z","steps":["trace[1283786354] 'process raft request' (duration: 179.966196ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:44.391991Z","caller":"traceutil/trace.go:171","msg":"trace[1431610] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"177.172763ms","start":"2026-02-25T17:04:44.214771Z","end":"2026-02-25T17:04:44.391943Z","steps":["trace[1431610] 'process raft request' (duration: 176.870557ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:44.559596Z","caller":"traceutil/trace.go:171","msg":"trace[1306332758] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"106.106418ms","start":"2026-02-25T17:04:44.453469Z","end":"2026-02-25T17:04:44.559575Z","steps":["trace[1306332758] 'process raft request' (duration: 106.054427ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:44.56Z","caller":"traceutil/trace.go:171","msg":"trace[963079424] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"108.381519ms","start":"2026-02-25T17:04:44.451606Z","end":"2026-02-25T17:04:44.559987Z","steps":["trace[963079424] 'process raft request' (duration: 67.961508ms)","trace[963079424] 'compare' (duration: 39.758927ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:48.797583Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523325424589289,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:04:49.2174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.449551237s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T17:04:49.217767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.55347599s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-25T17:04:49.218029Z","caller":"traceutil/trace.go:171","msg":"trace[749496961] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:328; }","duration":"2.553768786s","start":"2026-02-25T17:04:46.664228Z","end":"2026-02-25T17:04:49.217997Z","steps":["trace[749496961] 'range keys from in-memory index tree' (duration: 2.553379018s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.218102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:46.664213Z","time spent":"2.553872329s","remote":"127.0.0.1:34032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-25T17:04:49.218088Z","caller":"traceutil/trace.go:171","msg":"trace[501101987] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:328; }","duration":"1.449660369s","start":"2026-02-25T17:04:47.767812Z","end":"2026-02-25T17:04:49.217472Z","steps":["trace[501101987] 'range keys from in-memory index tree' (duration: 1.449454864s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.218106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.176271383s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-25T17:04:49.21821Z","caller":"traceutil/trace.go:171","msg":"trace[933763110] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:328; }","duration":"2.176417856s","start":"2026-02-25T17:04:47.041773Z","end":"2026-02-25T17:04:49.218191Z","steps":["trace[933763110] 'range keys from in-memory index tree' (duration: 2.17613524s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.218225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:47.767767Z","time spent":"1.450414976s","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T17:04:49.218261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:47.041751Z","time spent":"2.176499378s","remote":"127.0.0.1:34118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6921,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-25T17:04:49.298022Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523325424589289,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:04:49.551264Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.480749262s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T17:04:49.551624Z","caller":"traceutil/trace.go:171","msg":"trace[270828383] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"1.481193743s","start":"2026-02-25T17:04:48.070388Z","end":"2026-02-25T17:04:49.551582Z","steps":["trace[270828383] 'process raft request' (duration: 1.481028929s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.551745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:48.070359Z","time spent":"1.481295834s","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:49.85436Z","caller":"traceutil/trace.go:171","msg":"trace[1374118280] linearizableReadLoop","detail":"{readStateIndex:344; appliedIndex:340; }","duration":"1.557387783s","start":"2026-02-25T17:04:48.296925Z","end":"2026-02-25T17:04:49.854313Z","steps":["trace[1374118280] 'read index received' (duration: 1.254616427s)","trace[1374118280] 'applied index is now lower than readState.Index' (duration: 302.770196ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:49.854363Z","caller":"traceutil/trace.go:171","msg":"trace[1718363885] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"632.108826ms","start":"2026-02-25T17:04:49.222195Z","end":"2026-02-25T17:04:49.854304Z","steps":["trace[1718363885] 'process raft request' (duration: 631.86742ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:49.854462Z","caller":"traceutil/trace.go:171","msg":"trace[380426109] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"631.374108ms","start":"2026-02-25T17:04:49.223039Z","end":"2026-02-25T17:04:49.854413Z","steps":["trace[380426109] 'process raft request' (duration: 631.182924ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:49.854599Z","caller":"traceutil/trace.go:171","msg":"trace[1406953067] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"626.579022ms","start":"2026-02-25T17:04:49.22798Z","end":"2026-02-25T17:04:49.854559Z","steps":["trace[1406953067] 'process raft request' (duration: 626.291196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.854641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:49.223015Z","time spent":"631.534662ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:04:49.854655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:49.222166Z","time spent":"632.386972ms","remote":"127.0.0.1:34144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":209,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T17:04:49.85476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:49.227956Z","time spent":"626.705074ms","remote":"127.0.0.1:34118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:04:49.854799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.384241768s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T17:04:49.854799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.557853053s","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-02-25T17:04:49.854894Z","caller":"traceutil/trace.go:171","msg":"trace[753469557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:332; }","duration":"1.557986976s","start":"2026-02-25T17:04:48.296886Z","end":"2026-02-25T17:04:49.854873Z","steps":["trace[753469557] 'agreement among raft nodes before linearized reading' (duration: 1.557785002s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.854932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:48.296862Z","time spent":"1.558064437s","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T17:04:49.854838Z","caller":"traceutil/trace.go:171","msg":"trace[99243485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:332; }","duration":"1.38431108s","start":"2026-02-25T17:04:48.470516Z","end":"2026-02-25T17:04:49.854827Z","steps":["trace[99243485] 'agreement among raft nodes before linearized reading' (duration: 1.384221078s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.854965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.448556544s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T17:04:49.855043Z","caller":"traceutil/trace.go:171","msg":"trace[1328310625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:332; }","duration":"1.448734668s","start":"2026-02-25T17:04:48.406289Z","end":"2026-02-25T17:04:49.855024Z","steps":["trace[1328310625] 'agreement among raft nodes before linearized reading' (duration: 1.448248488s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.855033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:48.470496Z","time spent":"1.384512035s","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T17:04:49.855086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:48.406267Z","time spent":"1.44881084s","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T17:04:50.036584Z","caller":"traceutil/trace.go:171","msg":"trace[1361106988] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"124.090229ms","start":"2026-02-25T17:04:49.912454Z","end":"2026-02-25T17:04:50.036545Z","steps":["trace[1361106988] 'process raft request' (duration: 24.402295ms)","trace[1361106988] 'compare' (duration: 99.541011ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:50.281359Z","caller":"traceutil/trace.go:171","msg":"trace[212187836] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"212.506691ms","start":"2026-02-25T17:04:50.068804Z","end":"2026-02-25T17:04:50.281311Z","steps":["trace[212187836] 'process raft request' (duration: 195.652954ms)","trace[212187836] 'compare' (duration: 16.630672ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:51.02601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.047105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:04:51.026154Z","caller":"traceutil/trace.go:171","msg":"trace[1955802876] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:363; }","duration":"366.408645ms","start":"2026-02-25T17:04:50.659725Z","end":"2026-02-25T17:04:51.026133Z","steps":["trace[1955802876] 'read index received' (duration: 161.151785ms)","trace[1955802876] 'applied index is now lower than readState.Index' (duration: 205.25532ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:51.02645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.857322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-25T17:04:51.026572Z","caller":"traceutil/trace.go:171","msg":"trace[1735982337] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:351; }","duration":"404.039897ms","start":"2026-02-25T17:04:50.622504Z","end":"2026-02-25T17:04:51.026544Z","steps":["trace[1735982337] 'agreement among raft nodes before linearized reading' (duration: 403.685218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:51.026624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:50.622473Z","time spent":"404.142339ms","remote":"127.0.0.1:34144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"info","ts":"2026-02-25T17:04:51.027087Z","caller":"traceutil/trace.go:171","msg":"trace[704180407] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"375.257624ms","start":"2026-02-25T17:04:50.650907Z","end":"2026-02-25T17:04:51.026164Z","steps":["trace[704180407] 'process raft request' (duration: 169.955483ms)","trace[704180407] 'compare' (duration: 204.761619ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:51.027205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:50.650874Z","time spent":"376.264377ms","remote":"127.0.0.1:34018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1739,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T17:04:51.056323Z","caller":"traceutil/trace.go:171","msg":"trace[2125660753] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"392.360645ms","start":"2026-02-25T17:04:50.663922Z","end":"2026-02-25T17:04:51.056282Z","steps":["trace[2125660753] 'process raft request' (duration: 392.1194ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:51.056465Z","caller":"traceutil/trace.go:171","msg":"trace[1307211533] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"389.424499ms","start":"2026-02-25T17:04:50.667015Z","end":"2026-02-25T17:04:51.056439Z","steps":["trace[1307211533] 'process raft request' (duration: 389.191624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:51.056554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.873559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-25T17:04:51.056625Z","caller":"traceutil/trace.go:171","msg":"trace[1969370152] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:355; }","duration":"255.970731ms","start":"2026-02-25T17:04:50.800636Z","end":"2026-02-25T17:04:51.056607Z","steps":["trace[1969370152] 'agreement among raft nodes before linearized reading' (duration: 255.821798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:51.056647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:50.666994Z","time spent":"389.542652ms","remote":"127.0.0.1:34394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3669,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:04:51.056655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.750864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-25T17:04:51.056713Z","caller":"traceutil/trace.go:171","msg":"trace[1595701541] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:355; }","duration":"271.857936ms","start":"2026-02-25T17:04:50.784841Z","end":"2026-02-25T17:04:51.056699Z","steps":["trace[1595701541] 'agreement among raft nodes before linearized reading' (duration: 271.760854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:51.05669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.766821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T17:04:51.056784Z","caller":"traceutil/trace.go:171","msg":"trace[1874150181] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:355; }","duration":"274.913554ms","start":"2026-02-25T17:04:50.781849Z","end":"2026-02-25T17:04:51.056763Z","steps":["trace[1874150181] 'agreement among raft nodes before linearized reading' (duration: 274.69081ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:51.056392Z","caller":"traceutil/trace.go:171","msg":"trace[1619566517] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"391.325433ms","start":"2026-02-25T17:04:50.665039Z","end":"2026-02-25T17:04:51.056364Z","steps":["trace[1619566517] 'process raft request' (duration: 391.108288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:51.056874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:50.664834Z","time spent":"392.000707ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T17:04:51.056894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.049775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-25T17:04:51.056945Z","caller":"traceutil/trace.go:171","msg":"trace[394179739] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:355; }","duration":"235.131686ms","start":"2026-02-25T17:04:50.821799Z","end":"2026-02-25T17:04:51.056931Z","steps":["trace[394179739] 'agreement among raft nodes before linearized reading' (duration: 235.051224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:51.056564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:50.663894Z","time spent":"392.57388ms","remote":"127.0.0.1:34144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":172,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T17:04:51.057024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.281315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"warn","ts":"2026-02-25T17:04:51.056944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.479229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T17:04:51.057125Z","caller":"traceutil/trace.go:171","msg":"trace[1371177290] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:355; }","duration":"393.403258ms","start":"2026-02-25T17:04:50.663689Z","end":"2026-02-25T17:04:51.057092Z","steps":["trace[1371177290] 'agreement among raft nodes before linearized reading' (duration: 393.076581ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:51.057168Z","caller":"traceutil/trace.go:171","msg":"trace[970163916] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:355; }","duration":"393.792506ms","start":"2026-02-25T17:04:50.663362Z","end":"2026-02-25T17:04:51.057154Z","steps":["trace[970163916] 'agreement among raft nodes before linearized reading' (duration: 393.454549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:51.057193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:50.663669Z","time spent":"393.510481ms","remote":"127.0.0.1:34144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"info","ts":"2026-02-25T17:04:51.057203Z","caller":"traceutil/trace.go:171","msg":"trace[611878800] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"389.743067ms","start":"2026-02-25T17:04:50.667445Z","end":"2026-02-25T17:04:51.057188Z","steps":["trace[611878800] 'process raft request' (duration: 388.847027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:51.057213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:50.663338Z","time spent":"393.859718ms","remote":"127.0.0.1:34144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-02-25T17:04:51.057311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:50.667417Z","time spent":"389.828289ms","remote":"127.0.0.1:34200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2267,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:51.308543Z","caller":"traceutil/trace.go:171","msg":"trace[380677796] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"278.081325ms","start":"2026-02-25T17:04:51.030435Z","end":"2026-02-25T17:04:51.308516Z","steps":["trace[380677796] 'process raft request' (duration: 253.956157ms)","trace[380677796] 'compare' (duration: 23.848462ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:51.308756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.24495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-25T17:04:51.308844Z","caller":"traceutil/trace.go:171","msg":"trace[1341657433] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:356; }","duration":"279.383813ms","start":"2026-02-25T17:04:51.029442Z","end":"2026-02-25T17:04:51.308826Z","steps":["trace[1341657433] 'agreement among raft nodes before linearized reading' (duration: 279.197279ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:51.308433Z","caller":"traceutil/trace.go:171","msg":"trace[630976468] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:368; }","duration":"252.007444ms","start":"2026-02-25T17:04:51.056397Z","end":"2026-02-25T17:04:51.308404Z","steps":["trace[630976468] 'read index received' (duration: 228.088871ms)","trace[630976468] 'applied index is now lower than readState.Index' (duration: 23.917413ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:51.312035Z","caller":"traceutil/trace.go:171","msg":"trace[197280420] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"249.791444ms","start":"2026-02-25T17:04:51.062224Z","end":"2026-02-25T17:04:51.312016Z","steps":["trace[197280420] 'process raft request' (duration: 249.59052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:51.312151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.446319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-25T17:04:51.312247Z","caller":"traceutil/trace.go:171","msg":"trace[1002279537] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:358; }","duration":"250.596032ms","start":"2026-02-25T17:04:51.061631Z","end":"2026-02-25T17:04:51.312227Z","steps":["trace[1002279537] 'agreement among raft nodes before linearized reading' (duration: 250.399878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:51.312276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.709912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-25T17:04:51.312295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.919397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T17:04:51.312378Z","caller":"traceutil/trace.go:171","msg":"trace[1422957171] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:358; }","duration":"250.01853ms","start":"2026-02-25T17:04:51.062346Z","end":"2026-02-25T17:04:51.312365Z","steps":["trace[1422957171] 'agreement among raft nodes before linearized reading' (duration: 249.898966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:51.312453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.843976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-25T17:04:51.312377Z","caller":"traceutil/trace.go:171","msg":"trace[2113386976] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:358; }","duration":"249.826186ms","start":"2026-02-25T17:04:51.062533Z","end":"2026-02-25T17:04:51.312359Z","steps":["trace[2113386976] 'agreement among raft nodes before linearized reading' (duration: 249.625921ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:51.312509Z","caller":"traceutil/trace.go:171","msg":"trace[1817514162] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:358; }","duration":"249.913418ms","start":"2026-02-25T17:04:51.06258Z","end":"2026-02-25T17:04:51.312493Z","steps":["trace[1817514162] 'agreement among raft nodes before linearized reading' (duration: 249.826906ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:51.312453Z","caller":"traceutil/trace.go:171","msg":"trace[1874580183] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"247.293898ms","start":"2026-02-25T17:04:51.065134Z","end":"2026-02-25T17:04:51.312427Z","steps":["trace[1874580183] 'process raft request' (duration: 246.809687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:51.312481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.139552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-25T17:04:51.31356Z","caller":"traceutil/trace.go:171","msg":"trace[1758399987] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:358; }","duration":"251.225017ms","start":"2026-02-25T17:04:51.062314Z","end":"2026-02-25T17:04:51.313539Z","steps":["trace[1758399987] 'agreement among raft nodes before linearized reading' (duration: 250.120663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:51.312315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.598778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3684"} {"level":"warn","ts":"2026-02-25T17:04:51.312626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.154297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-25T17:04:51.31377Z","caller":"traceutil/trace.go:171","msg":"trace[290970253] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:358; }","duration":"250.025059ms","start":"2026-02-25T17:04:51.063658Z","end":"2026-02-25T17:04:51.313683Z","steps":["trace[290970253] 'agreement among raft nodes before linearized reading' (duration: 248.476144ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:51.313814Z","caller":"traceutil/trace.go:171","msg":"trace[1041680761] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:358; }","duration":"153.361722ms","start":"2026-02-25T17:04:51.16043Z","end":"2026-02-25T17:04:51.313791Z","steps":["trace[1041680761] 'agreement among raft nodes before linearized reading' (duration: 152.161006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:51.312568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.440319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-02-25T17:04:51.313956Z","caller":"traceutil/trace.go:171","msg":"trace[1999528756] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:358; }","duration":"152.848071ms","start":"2026-02-25T17:04:51.161088Z","end":"2026-02-25T17:04:51.313937Z","steps":["trace[1999528756] 'agreement among raft nodes before linearized reading' (duration: 151.399199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:51.524782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.780231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:04:51.525277Z","caller":"traceutil/trace.go:171","msg":"trace[864258542] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"139.947103ms","start":"2026-02-25T17:04:51.385307Z","end":"2026-02-25T17:04:51.525254Z","steps":["trace[864258542] 'process raft request' (duration: 139.889632ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:51.525468Z","caller":"traceutil/trace.go:171","msg":"trace[309947086] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"152.294009ms","start":"2026-02-25T17:04:51.373153Z","end":"2026-02-25T17:04:51.525447Z","steps":["trace[309947086] 'process raft request' (duration: 151.922629ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:51.525556Z","caller":"traceutil/trace.go:171","msg":"trace[1662967095] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"148.939684ms","start":"2026-02-25T17:04:51.376595Z","end":"2026-02-25T17:04:51.525534Z","steps":["trace[1662967095] 'process raft request' (duration: 148.530215ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:51.525643Z","caller":"traceutil/trace.go:171","msg":"trace[1494696799] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"152.609545ms","start":"2026-02-25T17:04:51.373009Z","end":"2026-02-25T17:04:51.525618Z","steps":["trace[1494696799] 'process raft request' (duration: 152.003511ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:51.525638Z","caller":"traceutil/trace.go:171","msg":"trace[1103093647] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:386; }","duration":"152.863091ms","start":"2026-02-25T17:04:51.372731Z","end":"2026-02-25T17:04:51.525594Z","steps":["trace[1103093647] 'read index received' (duration: 9.88027ms)","trace[1103093647] 'applied index is now lower than readState.Index' (duration: 142.980561ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:51.525785Z","caller":"traceutil/trace.go:171","msg":"trace[1994048017] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"152.958143ms","start":"2026-02-25T17:04:51.372805Z","end":"2026-02-25T17:04:51.525763Z","steps":["trace[1994048017] 'process raft request' (duration: 152.104774ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:51.525884Z","caller":"traceutil/trace.go:171","msg":"trace[2061891753] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"158.367724ms","start":"2026-02-25T17:04:51.367484Z","end":"2026-02-25T17:04:51.525852Z","steps":["trace[2061891753] 'process raft request' (duration: 55.442157ms)","trace[2061891753] 'compare' (duration: 96.34647ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:51.526263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.512756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:1 size:2108"} {"level":"info","ts":"2026-02-25T17:04:51.527541Z","caller":"traceutil/trace.go:171","msg":"trace[262496841] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:1; response_revision:379; }","duration":"154.834664ms","start":"2026-02-25T17:04:51.372693Z","end":"2026-02-25T17:04:51.527528Z","steps":["trace[262496841] 'agreement among raft nodes before linearized reading' (duration: 153.218839ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:54.607461Z","caller":"traceutil/trace.go:171","msg":"trace[243261402] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"184.926476ms","start":"2026-02-25T17:04:54.422513Z","end":"2026-02-25T17:04:54.607439Z","steps":["trace[243261402] 'process raft request' (duration: 184.813754ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:58.055975Z","caller":"traceutil/trace.go:171","msg":"trace[1500508368] linearizableReadLoop","detail":"{readStateIndex:466; appliedIndex:465; }","duration":"126.950243ms","start":"2026-02-25T17:04:57.928994Z","end":"2026-02-25T17:04:58.055944Z","steps":["trace[1500508368] 'read index received' (duration: 87.004602ms)","trace[1500508368] 'applied index is now lower than readState.Index' (duration: 39.944331ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:58.056068Z","caller":"traceutil/trace.go:171","msg":"trace[149161270] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"136.897375ms","start":"2026-02-25T17:04:57.919117Z","end":"2026-02-25T17:04:58.056014Z","steps":["trace[149161270] 'process raft request' (duration: 96.920823ms)","trace[149161270] 'compare' (duration: 39.684375ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:58.056179Z","caller":"traceutil/trace.go:171","msg":"trace[1987465570] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"122.588486ms","start":"2026-02-25T17:04:57.933557Z","end":"2026-02-25T17:04:58.056146Z","steps":["trace[1987465570] 'process raft request' (duration: 122.34967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:58.056269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.487921ms","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-02-25T17:04:58.056313Z","caller":"traceutil/trace.go:171","msg":"trace[1349667269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:452; }","duration":"138.581492ms","start":"2026-02-25T17:04:57.917718Z","end":"2026-02-25T17:04:58.0563Z","steps":["trace[1349667269] 'agreement among raft nodes before linearized reading' (duration: 138.352247ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:59.781364Z","caller":"traceutil/trace.go:171","msg":"trace[1491438212] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"123.402993ms","start":"2026-02-25T17:04:59.657926Z","end":"2026-02-25T17:04:59.781329Z","steps":["trace[1491438212] 'process raft request' (duration: 123.151088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:00.562846Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523325424589510,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T17:05:00.661154Z","caller":"traceutil/trace.go:171","msg":"trace[1009784170] linearizableReadLoop","detail":"{readStateIndex:470; appliedIndex:469; }","duration":"598.919034ms","start":"2026-02-25T17:05:00.062202Z","end":"2026-02-25T17:05:00.661121Z","steps":["trace[1009784170] 'read index received' (duration: 598.72047ms)","trace[1009784170] 'applied index is now lower than readState.Index' (duration: 197.834µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:00.66125Z","caller":"traceutil/trace.go:171","msg":"trace[1240449232] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"724.022406ms","start":"2026-02-25T17:04:59.937184Z","end":"2026-02-25T17:05:00.661207Z","steps":["trace[1240449232] 'process raft request' (duration: 723.798991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:00.661338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.109678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T17:05:00.661381Z","caller":"traceutil/trace.go:171","msg":"trace[518407608] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:455; }","duration":"599.17367ms","start":"2026-02-25T17:05:00.062196Z","end":"2026-02-25T17:05:00.66137Z","steps":["trace[518407608] 'agreement among raft nodes before linearized reading' (duration: 599.076688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:00.661404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:59.937156Z","time spent":"724.16222ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:05:00.661445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:00.062139Z","time spent":"599.277403ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T17:05:00.661527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.218986ms","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-02-25T17:05:00.661599Z","caller":"traceutil/trace.go:171","msg":"trace[1451265671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:455; }","duration":"597.294437ms","start":"2026-02-25T17:05:00.064286Z","end":"2026-02-25T17:05:00.66158Z","steps":["trace[1451265671] 'agreement among raft nodes before linearized reading' (duration: 597.146104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:00.661636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:00.064234Z","time spent":"597.395151ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T17:05:00.661525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.242362ms","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-02-25T17:05:00.661719Z","caller":"traceutil/trace.go:171","msg":"trace[756115120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:455; }","duration":"599.462016ms","start":"2026-02-25T17:05:00.062242Z","end":"2026-02-25T17:05:00.661704Z","steps":["trace[756115120] 'agreement among raft nodes before linearized reading' (duration: 599.15891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:00.661527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.647867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.201\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-25T17:05:00.661769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:00.062235Z","time spent":"599.520167ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T17:05:00.661801Z","caller":"traceutil/trace.go:171","msg":"trace[205908144] range","detail":"{range_begin:/registry/masterleases/199.19.213.201; range_end:; response_count:1; response_revision:455; }","duration":"463.960924ms","start":"2026-02-25T17:05:00.197817Z","end":"2026-02-25T17:05:00.661778Z","steps":["trace[205908144] 'agreement among raft nodes before linearized reading' (duration: 463.597446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:00.661835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:00.197798Z","time spent":"464.026625ms","remote":"127.0.0.1:33924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.201\" "} {"level":"warn","ts":"2026-02-25T17:05:00.946659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.481342ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-25T17:05:00.94796Z","caller":"traceutil/trace.go:171","msg":"trace[149352252] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"282.209958ms","start":"2026-02-25T17:05:00.665724Z","end":"2026-02-25T17:05:00.947934Z","steps":["trace[149352252] 'process raft request' (duration: 282.034014ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:00.948275Z","caller":"traceutil/trace.go:171","msg":"trace[967659556] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"282.235588ms","start":"2026-02-25T17:05:00.666019Z","end":"2026-02-25T17:05:00.948255Z","steps":["trace[967659556] 'process raft request' (duration: 281.87124ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:00.948603Z","caller":"traceutil/trace.go:171","msg":"trace[1755340792] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"282.33718ms","start":"2026-02-25T17:05:00.666179Z","end":"2026-02-25T17:05:00.948516Z","steps":["trace[1755340792] 'process raft request' (duration: 281.925791ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:03.069795Z","caller":"traceutil/trace.go:171","msg":"trace[561493698] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"111.1524ms","start":"2026-02-25T17:05:02.958595Z","end":"2026-02-25T17:05:03.069747Z","steps":["trace[561493698] 'process raft request' (duration: 100.234127ms)","trace[561493698] 'compare' (duration: 10.570465ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:03.070048Z","caller":"traceutil/trace.go:171","msg":"trace[2144620320] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"110.877014ms","start":"2026-02-25T17:05:02.959141Z","end":"2026-02-25T17:05:03.070018Z","steps":["trace[2144620320] 'process raft request' (duration: 110.751382ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:05.115565Z","caller":"traceutil/trace.go:171","msg":"trace[1125197049] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"144.520446ms","start":"2026-02-25T17:05:04.971016Z","end":"2026-02-25T17:05:05.115536Z","steps":["trace[1125197049] 'process raft request' (duration: 144.367222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:05.527584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.754833ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:05:05.527733Z","caller":"traceutil/trace.go:171","msg":"trace[131762369] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"407.488772ms","start":"2026-02-25T17:05:05.12023Z","end":"2026-02-25T17:05:05.527719Z","steps":["trace[131762369] 'process raft request' (duration: 407.450281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:05.527779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:05.12021Z","time spent":"407.547824ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:05:05.527818Z","caller":"traceutil/trace.go:171","msg":"trace[1950098145] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"407.77345ms","start":"2026-02-25T17:05:05.119999Z","end":"2026-02-25T17:05:05.527773Z","steps":["trace[1950098145] 'process raft request' (duration: 110.755532ms)","trace[1950098145] 'compare' (duration: 296.650909ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:05.528026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:05.119971Z","time spent":"407.949444ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:05:05.774055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.579117ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T17:05:12.221283Z","caller":"traceutil/trace.go:171","msg":"trace[660014958] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"226.854572ms","start":"2026-02-25T17:05:11.994408Z","end":"2026-02-25T17:05:12.221263Z","steps":["trace[660014958] 'process raft request' (duration: 226.73446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:19.274152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.329858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:19.274266Z","caller":"traceutil/trace.go:171","msg":"trace[2111120980] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:557; }","duration":"372.726617ms","start":"2026-02-25T17:05:18.901516Z","end":"2026-02-25T17:05:19.274242Z","steps":["trace[2111120980] 'range keys from in-memory index tree' (duration: 372.218106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:19.274309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:18.901489Z","time spent":"372.808389ms","remote":"127.0.0.1:34010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"info","ts":"2026-02-25T17:05:19.435058Z","caller":"traceutil/trace.go:171","msg":"trace[394502667] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"155.689574ms","start":"2026-02-25T17:05:19.279329Z","end":"2026-02-25T17:05:19.435019Z","steps":["trace[394502667] 'process raft request' (duration: 155.478149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:20.129286Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523325424589735,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:05:20.630007Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523325424589735,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:05:21.131046Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523325424589735,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:05:21.629606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000364397s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T17:05:21.629708Z","caller":"traceutil/trace.go:171","msg":"trace[1498404120] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000530791s","start":"2026-02-25T17:05:19.629155Z","end":"2026-02-25T17:05:21.629686Z","steps":["trace[1498404120] 'agreement among raft nodes before linearized reading' (duration: 2.000391378s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:21.629759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:19.629133Z","time spent":"2.000615652s","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 17:05:21 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T17:05:21.631774Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523325424589735,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:05:21.719685Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.098013166s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T17:05:22.251314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.335177ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:05:22.251416Z","caller":"traceutil/trace.go:171","msg":"trace[1643037387] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"2.632319809s","start":"2026-02-25T17:05:19.619079Z","end":"2026-02-25T17:05:22.251398Z","steps":["trace[1643037387] 'process raft request' (duration: 2.100820768s)","trace[1643037387] 'compare' (duration: 531.206605ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:22.251475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:19.619045Z","time spent":"2.632400821s","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:05:22.27695Z","caller":"traceutil/trace.go:171","msg":"trace[985829140] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:578; }","duration":"2.647721223s","start":"2026-02-25T17:05:19.629203Z","end":"2026-02-25T17:05:22.276924Z","steps":["trace[985829140] 'read index received' (duration: 2.090710523s)","trace[985829140] 'applied index is now lower than readState.Index' (duration: 557.00961ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:22.277115Z","caller":"traceutil/trace.go:171","msg":"trace[550347845] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"2.2850367s","start":"2026-02-25T17:05:19.992029Z","end":"2026-02-25T17:05:22.277065Z","steps":["trace[550347845] 'process raft request' (duration: 2.284717073s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:22.277326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.079127715s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.201\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T17:05:22.277425Z","caller":"traceutil/trace.go:171","msg":"trace[2004895828] range","detail":"{range_begin:/registry/masterleases/199.19.213.201; range_end:; response_count:1; response_revision:561; }","duration":"2.079238087s","start":"2026-02-25T17:05:20.198127Z","end":"2026-02-25T17:05:22.277365Z","steps":["trace[2004895828] 'agreement among raft nodes before linearized reading' (duration: 2.078959251s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:22.277474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:20.198102Z","time spent":"2.079360419s","remote":"127.0.0.1:33924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.201\" "} {"level":"warn","ts":"2026-02-25T17:05:22.277484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.889277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:22.27753Z","caller":"traceutil/trace.go:171","msg":"trace[1740593522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"647.953089ms","start":"2026-02-25T17:05:21.629564Z","end":"2026-02-25T17:05:22.277517Z","steps":["trace[1740593522] 'agreement among raft nodes before linearized reading' (duration: 647.856746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:22.277556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:21.629553Z","time spent":"647.996489ms","remote":"127.0.0.1:33886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:05:22.277523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.77422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T17:05:22.27757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.643871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T17:05:22.277607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.935918ms","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-02-25T17:05:22.277622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.427305549s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T17:05:22.277678Z","caller":"traceutil/trace.go:171","msg":"trace[411944256] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-cainjector; range_end:; response_count:0; response_revision:561; }","duration":"724.791473ms","start":"2026-02-25T17:05:21.552858Z","end":"2026-02-25T17:05:22.27765Z","steps":["trace[411944256] 'agreement among raft nodes before linearized reading' (duration: 724.61005ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:22.277725Z","caller":"traceutil/trace.go:171","msg":"trace[1316314366] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:561; }","duration":"1.427452823s","start":"2026-02-25T17:05:20.850244Z","end":"2026-02-25T17:05:22.277697Z","steps":["trace[1316314366] 'agreement among raft nodes before linearized reading' (duration: 1.427108785s)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:22.277717Z","caller":"traceutil/trace.go:171","msg":"trace[88746922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:561; }","duration":"838.085171ms","start":"2026-02-25T17:05:21.439602Z","end":"2026-02-25T17:05:22.277687Z","steps":["trace[88746922] 'agreement among raft nodes before linearized reading' (duration: 837.698763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:22.277733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:21.552841Z","time spent":"724.882976ms","remote":"127.0.0.1:34144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" "} {"level":"info","ts":"2026-02-25T17:05:22.277637Z","caller":"traceutil/trace.go:171","msg":"trace[364448740] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:561; }","duration":"658.938144ms","start":"2026-02-25T17:05:21.618669Z","end":"2026-02-25T17:05:22.277608Z","steps":["trace[364448740] 'agreement among raft nodes before linearized reading' (duration: 658.563495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:22.277777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:20.850224Z","time spent":"1.427541194s","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T17:05:22.277781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:21.439585Z","time spent":"838.186523ms","remote":"127.0.0.1:34212","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-02-25T17:05:22.277796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:21.61865Z","time spent":"659.133137ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T17:05:22.277946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:19.992Z","time spent":"2.285228334s","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:05:23.483392Z","caller":"traceutil/trace.go:171","msg":"trace[921851916] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"242.522403ms","start":"2026-02-25T17:05:23.240836Z","end":"2026-02-25T17:05:23.483359Z","steps":["trace[921851916] 'process raft request' (duration: 238.542443ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:23.577838Z","caller":"traceutil/trace.go:171","msg":"trace[451593725] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"172.693433ms","start":"2026-02-25T17:05:23.405051Z","end":"2026-02-25T17:05:23.577744Z","steps":["trace[451593725] 'process raft request' (duration: 133.477678ms)","trace[451593725] 'compare' (duration: 38.98392ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:25.287511Z","caller":"traceutil/trace.go:171","msg":"trace[1029051329] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"115.854635ms","start":"2026-02-25T17:05:25.17163Z","end":"2026-02-25T17:05:25.287485Z","steps":["trace[1029051329] 'process raft request' (duration: 115.65502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:27.939027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.660598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:27.93911Z","caller":"traceutil/trace.go:171","msg":"trace[140204483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:710; }","duration":"304.803272ms","start":"2026-02-25T17:05:27.634288Z","end":"2026-02-25T17:05:27.939091Z","steps":["trace[140204483] 'range keys from in-memory index tree' (duration: 304.556846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:27.939154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:27.634267Z","time spent":"304.878933ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T17:05:28.569504Z","caller":"traceutil/trace.go:171","msg":"trace[2036188328] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:741; }","duration":"125.748446ms","start":"2026-02-25T17:05:28.443728Z","end":"2026-02-25T17:05:28.569476Z","steps":["trace[2036188328] 'read index received' (duration: 93.064927ms)","trace[2036188328] 'applied index is now lower than readState.Index' (duration: 32.682239ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:28.569531Z","caller":"traceutil/trace.go:171","msg":"trace[1245401393] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"148.729599ms","start":"2026-02-25T17:05:28.42077Z","end":"2026-02-25T17:05:28.5695Z","steps":["trace[1245401393] 'process raft request' (duration: 115.925087ms)","trace[1245401393] 'compare' (duration: 32.661819ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:28.569817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.405239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T17:05:28.569847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.526624ms","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-02-25T17:05:28.569912Z","caller":"traceutil/trace.go:171","msg":"trace[2086640324] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:721; }","duration":"147.546821ms","start":"2026-02-25T17:05:28.422342Z","end":"2026-02-25T17:05:28.569889Z","steps":["trace[2086640324] 'agreement among raft nodes before linearized reading' (duration: 147.220354ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:28.56992Z","caller":"traceutil/trace.go:171","msg":"trace[2090283436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:721; }","duration":"148.670958ms","start":"2026-02-25T17:05:28.421228Z","end":"2026-02-25T17:05:28.569899Z","steps":["trace[2090283436] 'agreement among raft nodes before linearized reading' (duration: 148.361831ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:28.578863Z","caller":"traceutil/trace.go:171","msg":"trace[1436088766] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"131.263268ms","start":"2026-02-25T17:05:28.447573Z","end":"2026-02-25T17:05:28.578836Z","steps":["trace[1436088766] 'process raft request' (duration: 130.953261ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:28.578946Z","caller":"traceutil/trace.go:171","msg":"trace[1301188390] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"131.147037ms","start":"2026-02-25T17:05:28.447778Z","end":"2026-02-25T17:05:28.578925Z","steps":["trace[1301188390] 'process raft request' (duration: 130.87336ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:28.579101Z","caller":"traceutil/trace.go:171","msg":"trace[1377574482] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"130.243877ms","start":"2026-02-25T17:05:28.44884Z","end":"2026-02-25T17:05:28.579084Z","steps":["trace[1377574482] 'process raft request' (duration: 130.011961ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:28.730532Z","caller":"traceutil/trace.go:171","msg":"trace[1435636452] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"146.199293ms","start":"2026-02-25T17:05:28.584309Z","end":"2026-02-25T17:05:28.730508Z","steps":["trace[1435636452] 'process raft request' (duration: 146.05876ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:28.785013Z","caller":"traceutil/trace.go:171","msg":"trace[280234831] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"165.761548ms","start":"2026-02-25T17:05:28.619222Z","end":"2026-02-25T17:05:28.784984Z","steps":["trace[280234831] 'process raft request' (duration: 165.564424ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:29.078686Z","caller":"traceutil/trace.go:171","msg":"trace[691492564] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"120.413457ms","start":"2026-02-25T17:05:28.958248Z","end":"2026-02-25T17:05:29.078662Z","steps":["trace[691492564] 'process raft request' (duration: 120.259033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:34.544296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.225632ms","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-02-25T17:05:34.544432Z","caller":"traceutil/trace.go:171","msg":"trace[1156353276] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:815; }","duration":"107.409956ms","start":"2026-02-25T17:05:34.437003Z","end":"2026-02-25T17:05:34.544413Z","steps":["trace[1156353276] 'range keys from in-memory index tree' (duration: 106.968306ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:35.838934Z","caller":"traceutil/trace.go:171","msg":"trace[87719570] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"102.046696ms","start":"2026-02-25T17:05:35.736857Z","end":"2026-02-25T17:05:35.838904Z","steps":["trace[87719570] 'process raft request' (duration: 101.464154ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:43.556617Z","caller":"traceutil/trace.go:171","msg":"trace[2126036826] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"281.00373ms","start":"2026-02-25T17:05:43.275594Z","end":"2026-02-25T17:05:43.556598Z","steps":["trace[2126036826] 'process raft request' (duration: 280.883807ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:44.039991Z","caller":"traceutil/trace.go:171","msg":"trace[100077449] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:947; }","duration":"419.526862ms","start":"2026-02-25T17:05:43.620421Z","end":"2026-02-25T17:05:44.039948Z","steps":["trace[100077449] 'read index received' (duration: 419.150613ms)","trace[100077449] 'applied index is now lower than readState.Index' (duration: 374.889µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:44.040053Z","caller":"traceutil/trace.go:171","msg":"trace[106356471] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"422.108509ms","start":"2026-02-25T17:05:43.617874Z","end":"2026-02-25T17:05:44.039982Z","steps":["trace[106356471] 'process raft request' (duration: 421.783981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:44.040184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.733347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T17:05:44.040229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:43.617851Z","time spent":"422.279323ms","remote":"127.0.0.1:34394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3853,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:05:44.040288Z","caller":"traceutil/trace.go:171","msg":"trace[1102576870] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:922; }","duration":"419.852819ms","start":"2026-02-25T17:05:43.620411Z","end":"2026-02-25T17:05:44.040264Z","steps":["trace[1102576870] 'agreement among raft nodes before linearized reading' (duration: 419.688026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:44.040383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:43.620367Z","time spent":"419.998032ms","remote":"127.0.0.1:34032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":6,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-02-25T17:05:44.128681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.247148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:44.128769Z","caller":"traceutil/trace.go:171","msg":"trace[1392605283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:923; }","duration":"498.381211ms","start":"2026-02-25T17:05:43.630368Z","end":"2026-02-25T17:05:44.128749Z","steps":["trace[1392605283] 'agreement among raft nodes before linearized reading' (duration: 498.240548ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:44.128763Z","caller":"traceutil/trace.go:171","msg":"trace[1597135512] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"476.787158ms","start":"2026-02-25T17:05:43.651935Z","end":"2026-02-25T17:05:44.128722Z","steps":["trace[1597135512] 'process raft request' (duration: 476.516882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:44.128812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:43.630344Z","time spent":"498.458313ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:05:44.128942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:43.651911Z","time spent":"476.947912ms","remote":"127.0.0.1:34106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4899,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:05:44.701783Z","caller":"traceutil/trace.go:171","msg":"trace[724165084] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"233.283446ms","start":"2026-02-25T17:05:44.467958Z","end":"2026-02-25T17:05:44.701241Z","steps":["trace[724165084] 'process raft request' (duration: 233.041381ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:45.052035Z","caller":"traceutil/trace.go:171","msg":"trace[945356157] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"174.414151ms","start":"2026-02-25T17:05:44.87759Z","end":"2026-02-25T17:05:45.052004Z","steps":["trace[945356157] 'process raft request' (duration: 90.932438ms)","trace[945356157] 'compare' (duration: 83.293319ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:45.061624Z","caller":"traceutil/trace.go:171","msg":"trace[412995448] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:952; }","duration":"118.30442ms","start":"2026-02-25T17:05:44.943297Z","end":"2026-02-25T17:05:45.061601Z","steps":["trace[412995448] 'read index received' (duration: 25.308005ms)","trace[412995448] 'applied index is now lower than readState.Index' (duration: 92.995445ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:45.061786Z","caller":"traceutil/trace.go:171","msg":"trace[413378084] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"183.607657ms","start":"2026-02-25T17:05:44.878069Z","end":"2026-02-25T17:05:45.061676Z","steps":["trace[413378084] 'process raft request' (duration: 183.430483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:45.061802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.491004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:45.061864Z","caller":"traceutil/trace.go:171","msg":"trace[2002218239] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:928; }","duration":"118.591876ms","start":"2026-02-25T17:05:44.943252Z","end":"2026-02-25T17:05:45.061844Z","steps":["trace[2002218239] 'agreement among raft nodes before linearized reading' (duration: 118.434643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:46.418685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.587706ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T17:05:46.418824Z","caller":"traceutil/trace.go:171","msg":"trace[807473410] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:954; }","duration":"786.830263ms","start":"2026-02-25T17:05:45.631969Z","end":"2026-02-25T17:05:46.418799Z","steps":["trace[807473410] 'read index received' (duration: 174.064412ms)","trace[807473410] 'applied index is now lower than readState.Index' (duration: 612.764061ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:46.418947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.962476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:46.419013Z","caller":"traceutil/trace.go:171","msg":"trace[451979652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:928; }","duration":"787.063178ms","start":"2026-02-25T17:05:45.631935Z","end":"2026-02-25T17:05:46.418998Z","steps":["trace[451979652] 'agreement among raft nodes before linearized reading' (duration: 786.941305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:46.419043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:45.631914Z","time spent":"787.12268ms","remote":"127.0.0.1:33886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:05:46.419107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.80727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-webhook-6fc5ff4798-xvxvp\" ","response":"range_response_count:1 size:5283"} {"level":"warn","ts":"2026-02-25T17:05:46.41897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.334704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:46.419172Z","caller":"traceutil/trace.go:171","msg":"trace[1153648412] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-webhook-6fc5ff4798-xvxvp; range_end:; response_count:1; response_revision:928; }","duration":"768.908092ms","start":"2026-02-25T17:05:45.650246Z","end":"2026-02-25T17:05:46.419154Z","steps":["trace[1153648412] 'agreement among raft nodes before linearized reading' (duration: 768.704757ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:46.419184Z","caller":"traceutil/trace.go:171","msg":"trace[78481188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:928; }","duration":"407.58038ms","start":"2026-02-25T17:05:46.011594Z","end":"2026-02-25T17:05:46.419174Z","steps":["trace[78481188] 'agreement among raft nodes before linearized reading' (duration: 407.352305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:46.419199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:45.650226Z","time spent":"768.968163ms","remote":"127.0.0.1:34118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":5306,"request content":"key:\"/registry/pods/cert-manager/cert-manager-webhook-6fc5ff4798-xvxvp\" "} {"level":"warn","ts":"2026-02-25T17:05:46.419208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:46.011574Z","time spent":"407.627671ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T17:05:46.686219Z","caller":"traceutil/trace.go:171","msg":"trace[2110121020] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"143.223071ms","start":"2026-02-25T17:05:46.542971Z","end":"2026-02-25T17:05:46.686194Z","steps":["trace[2110121020] 'process raft request' (duration: 136.574982ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:55.336942Z","caller":"traceutil/trace.go:171","msg":"trace[1671715257] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"164.247492ms","start":"2026-02-25T17:05:55.172663Z","end":"2026-02-25T17:05:55.33691Z","steps":["trace[1671715257] 'process raft request' (duration: 96.427282ms)","trace[1671715257] 'compare' (duration: 67.680987ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:57.419998Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523325424590525,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T17:05:57.738365Z","caller":"traceutil/trace.go:171","msg":"trace[1611615856] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1018; }","duration":"818.617266ms","start":"2026-02-25T17:05:56.919721Z","end":"2026-02-25T17:05:57.738339Z","steps":["trace[1611615856] 'read index received' (duration: 818.309069ms)","trace[1611615856] 'applied index is now lower than readState.Index' (duration: 306.987µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:57.738413Z","caller":"traceutil/trace.go:171","msg":"trace[1350603778] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"955.436784ms","start":"2026-02-25T17:05:56.782956Z","end":"2026-02-25T17:05:57.738393Z","steps":["trace[1350603778] 'process raft request' (duration: 955.159657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:57.738577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:56.782935Z","time spent":"955.565927ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:05:57.738732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.887185ms","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-02-25T17:05:57.73877Z","caller":"traceutil/trace.go:171","msg":"trace[83552826] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:989; }","duration":"803.950587ms","start":"2026-02-25T17:05:56.93481Z","end":"2026-02-25T17:05:57.73876Z","steps":["trace[83552826] 'agreement among raft nodes before linearized reading' (duration: 803.694111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:57.738799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:56.934798Z","time spent":"803.993558ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T17:05:57.7388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.709255ms","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-02-25T17:05:57.73884Z","caller":"traceutil/trace.go:171","msg":"trace[1950641450] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:989; }","duration":"565.792287ms","start":"2026-02-25T17:05:57.173037Z","end":"2026-02-25T17:05:57.738829Z","steps":["trace[1950641450] 'agreement among raft nodes before linearized reading' (duration: 565.675224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:57.738845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.917182ms","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-02-25T17:05:57.738909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.192859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T17:05:57.738947Z","caller":"traceutil/trace.go:171","msg":"trace[1790833452] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:989; }","duration":"819.266021ms","start":"2026-02-25T17:05:56.91967Z","end":"2026-02-25T17:05:57.738936Z","steps":["trace[1790833452] 'agreement among raft nodes before linearized reading' (duration: 818.78159ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:57.738935Z","caller":"traceutil/trace.go:171","msg":"trace[32462485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:989; }","duration":"398.053705ms","start":"2026-02-25T17:05:57.340857Z","end":"2026-02-25T17:05:57.738911Z","steps":["trace[32462485] 'agreement among raft nodes before linearized reading' (duration: 397.80964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:57.738975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:56.919644Z","time spent":"819.324403ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T17:05:57.739017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:57.340835Z","time spent":"398.165078ms","remote":"127.0.0.1:34212","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-02-25T17:05:57.739005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.876556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-02-25T17:05:57.739131Z","caller":"traceutil/trace.go:171","msg":"trace[1449335748] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:989; }","duration":"107.068781ms","start":"2026-02-25T17:05:57.632034Z","end":"2026-02-25T17:05:57.739103Z","steps":["trace[1449335748] 'agreement among raft nodes before linearized reading' (duration: 106.707553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:57.738873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:57.173009Z","time spent":"565.856458ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T17:06:00.993902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.864008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:2 size:213783"} {"level":"info","ts":"2026-02-25T17:06:00.994014Z","caller":"traceutil/trace.go:171","msg":"trace[1359367516] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:2; response_revision:1015; }","duration":"112.023092ms","start":"2026-02-25T17:06:00.881961Z","end":"2026-02-25T17:06:00.993984Z","steps":["trace[1359367516] 'range keys from in-memory index tree' (duration: 111.388157ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:04.48552Z","caller":"traceutil/trace.go:171","msg":"trace[1081493293] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"118.697621ms","start":"2026-02-25T17:06:04.366802Z","end":"2026-02-25T17:06:04.4855Z","steps":["trace[1081493293] 'process raft request' (duration: 118.563838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:05.786725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.470485ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T17:06:05.786804Z","caller":"traceutil/trace.go:171","msg":"trace[950405874] linearizableReadLoop","detail":"{readStateIndex:1095; appliedIndex:1094; }","duration":"155.257832ms","start":"2026-02-25T17:06:05.631533Z","end":"2026-02-25T17:06:05.786791Z","steps":["trace[950405874] 'read index received' (duration: 42.031µs)","trace[950405874] 'applied index is now lower than readState.Index' (duration: 155.214701ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:05.786897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.363754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:05.786924Z","caller":"traceutil/trace.go:171","msg":"trace[1859591949] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1060; }","duration":"155.430026ms","start":"2026-02-25T17:06:05.631485Z","end":"2026-02-25T17:06:05.786915Z","steps":["trace[1859591949] 'agreement among raft nodes before linearized reading' (duration: 155.339124ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:06.186794Z","caller":"traceutil/trace.go:171","msg":"trace[2068582874] linearizableReadLoop","detail":"{readStateIndex:1096; appliedIndex:1095; }","duration":"299.630389ms","start":"2026-02-25T17:06:05.887139Z","end":"2026-02-25T17:06:06.18677Z","steps":["trace[2068582874] 'read index received' (duration: 299.531217ms)","trace[2068582874] 'applied index is now lower than readState.Index' (duration: 97.992µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:06.186888Z","caller":"traceutil/trace.go:171","msg":"trace[904890518] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"376.376069ms","start":"2026-02-25T17:06:05.81045Z","end":"2026-02-25T17:06:06.186826Z","steps":["trace[904890518] 'process raft request' (duration: 376.163264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:06.187058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.911825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T17:06:06.187075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:05.810432Z","time spent":"376.553843ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:06:06.187105Z","caller":"traceutil/trace.go:171","msg":"trace[1921520547] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1061; }","duration":"300.006317ms","start":"2026-02-25T17:06:05.887086Z","end":"2026-02-25T17:06:06.187092Z","steps":["trace[1921520547] 'agreement among raft nodes before linearized reading' (duration: 299.846734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:06.18712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.991608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-25T17:06:06.187138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:05.887064Z","time spent":"300.066568ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T17:06:06.187167Z","caller":"traceutil/trace.go:171","msg":"trace[372704161] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1061; }","duration":"243.08751ms","start":"2026-02-25T17:06:05.944066Z","end":"2026-02-25T17:06:06.187153Z","steps":["trace[372704161] 'agreement among raft nodes before linearized reading' (duration: 242.956338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:06.187278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.229293ms","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-02-25T17:06:06.187376Z","caller":"traceutil/trace.go:171","msg":"trace[396415040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1061; }","duration":"243.364727ms","start":"2026-02-25T17:06:05.943985Z","end":"2026-02-25T17:06:06.187349Z","steps":["trace[396415040] 'agreement among raft nodes before linearized reading' (duration: 243.058149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:06.187401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.488221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T17:06:06.187512Z","caller":"traceutil/trace.go:171","msg":"trace[2040353445] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1061; }","duration":"260.624834ms","start":"2026-02-25T17:06:05.926864Z","end":"2026-02-25T17:06:06.187489Z","steps":["trace[2040353445] 'agreement among raft nodes before linearized reading' (duration: 260.120492ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:06.821032Z","caller":"traceutil/trace.go:171","msg":"trace[127135760] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"628.997115ms","start":"2026-02-25T17:06:06.192002Z","end":"2026-02-25T17:06:06.820999Z","steps":["trace[127135760] 'process raft request' (duration: 628.80029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:06.821802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:06.19198Z","time spent":"629.137237ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:06:07.025694Z","caller":"traceutil/trace.go:171","msg":"trace[1852955187] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"833.081091ms","start":"2026-02-25T17:06:06.192586Z","end":"2026-02-25T17:06:07.025667Z","steps":["trace[1852955187] 'process raft request' (duration: 787.034148ms)","trace[1852955187] 'compare' (duration: 45.861049ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:07.025865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:06.192576Z","time spent":"833.190533ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:06:07.025997Z","caller":"traceutil/trace.go:171","msg":"trace[1365706379] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"833.393368ms","start":"2026-02-25T17:06:06.192577Z","end":"2026-02-25T17:06:07.025971Z","steps":["trace[1365706379] 'process raft request' (duration: 833.04383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:07.026168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:06.192553Z","time spent":"833.5284ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:06:07.026241Z","caller":"traceutil/trace.go:171","msg":"trace[1590827546] linearizableReadLoop","detail":"{readStateIndex:1100; appliedIndex:1096; }","duration":"393.182836ms","start":"2026-02-25T17:06:06.633018Z","end":"2026-02-25T17:06:07.026201Z","steps":["trace[1590827546] 'read index received' (duration: 187.78792ms)","trace[1590827546] 'applied index is now lower than readState.Index' (duration: 205.394196ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:07.026298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.278839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:07.026325Z","caller":"traceutil/trace.go:171","msg":"trace[658569304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1065; }","duration":"393.344409ms","start":"2026-02-25T17:06:06.632974Z","end":"2026-02-25T17:06:07.026319Z","steps":["trace[658569304] 'agreement among raft nodes before linearized reading' (duration: 393.298388ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:07.026291Z","caller":"traceutil/trace.go:171","msg":"trace[58731776] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"833.613122ms","start":"2026-02-25T17:06:06.192641Z","end":"2026-02-25T17:06:07.026254Z","steps":["trace[58731776] 'process raft request' (duration: 833.248724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:07.026348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:06.632953Z","time spent":"393.390611ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:06:07.026429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:06.192622Z","time spent":"833.740685ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:06:07.985112Z","caller":"traceutil/trace.go:171","msg":"trace[1255417239] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"291.265641ms","start":"2026-02-25T17:06:07.693825Z","end":"2026-02-25T17:06:07.98509Z","steps":["trace[1255417239] 'process raft request' (duration: 291.093068ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:08.294539Z","caller":"traceutil/trace.go:171","msg":"trace[1060809495] linearizableReadLoop","detail":"{readStateIndex:1102; appliedIndex:1101; }","duration":"103.140783ms","start":"2026-02-25T17:06:08.191377Z","end":"2026-02-25T17:06:08.294518Z","steps":["trace[1060809495] 'read index received' (duration: 102.945149ms)","trace[1060809495] 'applied index is now lower than readState.Index' (duration: 194.844µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:08.294595Z","caller":"traceutil/trace.go:171","msg":"trace[1175512819] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"594.669855ms","start":"2026-02-25T17:06:07.699906Z","end":"2026-02-25T17:06:08.294576Z","steps":["trace[1175512819] 'process raft request' (duration: 594.491861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:08.294696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.325197ms","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-02-25T17:06:08.294723Z","caller":"traceutil/trace.go:171","msg":"trace[1252720067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1067; }","duration":"103.409449ms","start":"2026-02-25T17:06:08.191304Z","end":"2026-02-25T17:06:08.294714Z","steps":["trace[1252720067] 'agreement among raft nodes before linearized reading' (duration: 103.293777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:08.294716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:07.699863Z","time spent":"594.779828ms","remote":"127.0.0.1:48016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1093,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:06:08.444044Z","caller":"traceutil/trace.go:171","msg":"trace[168630911] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1102; }","duration":"144.813918ms","start":"2026-02-25T17:06:08.299206Z","end":"2026-02-25T17:06:08.444019Z","steps":["trace[168630911] 'read index received' (duration: 143.519379ms)","trace[168630911] 'applied index is now lower than readState.Index' (duration: 1.293449ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:08.444145Z","caller":"traceutil/trace.go:171","msg":"trace[1680412417] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"145.975273ms","start":"2026-02-25T17:06:08.298145Z","end":"2026-02-25T17:06:08.44412Z","steps":["trace[1680412417] 'process raft request' (duration: 144.542702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:08.444248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.025592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/atmosphere.18978c3bfe4527e4\" ","response":"range_response_count:1 size:688"} {"level":"info","ts":"2026-02-25T17:06:08.444287Z","caller":"traceutil/trace.go:171","msg":"trace[2022603304] range","detail":"{range_begin:/registry/events/default/atmosphere.18978c3bfe4527e4; range_end:; response_count:1; response_revision:1068; }","duration":"145.104054ms","start":"2026-02-25T17:06:08.299173Z","end":"2026-02-25T17:06:08.444277Z","steps":["trace[2022603304] 'agreement among raft nodes before linearized reading' (duration: 144.93818ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:09.195066Z","caller":"traceutil/trace.go:171","msg":"trace[5680198] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"160.716633ms","start":"2026-02-25T17:06:09.034328Z","end":"2026-02-25T17:06:09.195044Z","steps":["trace[5680198] 'process raft request' (duration: 160.675182ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:09.195105Z","caller":"traceutil/trace.go:171","msg":"trace[545981410] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"160.838456ms","start":"2026-02-25T17:06:09.034221Z","end":"2026-02-25T17:06:09.19506Z","steps":["trace[545981410] 'process raft request' (duration: 160.641722ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:10.562293Z","caller":"traceutil/trace.go:171","msg":"trace[1410088165] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1109; }","duration":"114.473037ms","start":"2026-02-25T17:06:10.447795Z","end":"2026-02-25T17:06:10.562268Z","steps":["trace[1410088165] 'read index received' (duration: 101.716651ms)","trace[1410088165] 'applied index is now lower than readState.Index' (duration: 12.754896ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:10.562359Z","caller":"traceutil/trace.go:171","msg":"trace[940477819] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"127.10373ms","start":"2026-02-25T17:06:10.435208Z","end":"2026-02-25T17:06:10.562312Z","steps":["trace[940477819] 'process raft request' (duration: 114.343134ms)","trace[940477819] 'compare' (duration: 12.587162ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:10.562621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.783464ms","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-02-25T17:06:10.562723Z","caller":"traceutil/trace.go:171","msg":"trace[432339731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1074; }","duration":"114.954278ms","start":"2026-02-25T17:06:10.447746Z","end":"2026-02-25T17:06:10.5627Z","steps":["trace[432339731] 'agreement among raft nodes before linearized reading' (duration: 114.5971ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:15.012561Z","caller":"traceutil/trace.go:171","msg":"trace[1481010415] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1151; }","duration":"123.631801ms","start":"2026-02-25T17:06:14.888901Z","end":"2026-02-25T17:06:15.012533Z","steps":["trace[1481010415] 'read index received' (duration: 123.486138ms)","trace[1481010415] 'applied index is now lower than readState.Index' (duration: 144.423µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:15.012734Z","caller":"traceutil/trace.go:171","msg":"trace[794096733] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"140.49502ms","start":"2026-02-25T17:06:14.872209Z","end":"2026-02-25T17:06:15.012704Z","steps":["trace[794096733] 'process raft request' (duration: 140.085941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:15.012788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.869217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/ingress-nginx-admission\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:15.013225Z","caller":"traceutil/trace.go:171","msg":"trace[57798281] range","detail":"{range_begin:/registry/secrets/ingress-nginx/ingress-nginx-admission; range_end:; response_count:0; response_revision:1116; }","duration":"124.354308ms","start":"2026-02-25T17:06:14.888836Z","end":"2026-02-25T17:06:15.01319Z","steps":["trace[57798281] 'agreement among raft nodes before linearized reading' (duration: 123.873287ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:15.158593Z","caller":"traceutil/trace.go:171","msg":"trace[1505018586] linearizableReadLoop","detail":"{readStateIndex:1154; appliedIndex:1152; }","duration":"145.917202ms","start":"2026-02-25T17:06:15.012647Z","end":"2026-02-25T17:06:15.158564Z","steps":["trace[1505018586] 'read index received' (duration: 145.661146ms)","trace[1505018586] 'applied index is now lower than readState.Index' (duration: 254.536µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:15.158643Z","caller":"traceutil/trace.go:171","msg":"trace[830225967] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"169.0379ms","start":"2026-02-25T17:06:14.989573Z","end":"2026-02-25T17:06:15.158611Z","steps":["trace[830225967] 'process raft request' (duration: 168.759774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:15.158807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.729498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T17:06:15.158878Z","caller":"traceutil/trace.go:171","msg":"trace[1897622747] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1118; }","duration":"211.858701ms","start":"2026-02-25T17:06:14.947Z","end":"2026-02-25T17:06:15.158859Z","steps":["trace[1897622747] 'agreement among raft nodes before linearized reading' (duration: 211.705708ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:15.158728Z","caller":"traceutil/trace.go:171","msg":"trace[1167134010] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"166.454652ms","start":"2026-02-25T17:06:14.992234Z","end":"2026-02-25T17:06:15.158689Z","steps":["trace[1167134010] 'process raft request' (duration: 166.270998ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:15.209278Z","caller":"traceutil/trace.go:171","msg":"trace[171320480] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"190.090253ms","start":"2026-02-25T17:06:15.019151Z","end":"2026-02-25T17:06:15.209241Z","steps":["trace[171320480] 'process raft request' (duration: 189.858378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:15.209366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.575429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T17:06:15.20944Z","caller":"traceutil/trace.go:171","msg":"trace[1416332200] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1119; }","duration":"110.698072ms","start":"2026-02-25T17:06:15.098724Z","end":"2026-02-25T17:06:15.209422Z","steps":["trace[1416332200] 'agreement among raft nodes before linearized reading' (duration: 110.486097ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:15.587391Z","caller":"traceutil/trace.go:171","msg":"trace[1313885454] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"176.739982ms","start":"2026-02-25T17:06:15.410615Z","end":"2026-02-25T17:06:15.587355Z","steps":["trace[1313885454] 'process raft request' (duration: 176.486167ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:20.094454Z","caller":"traceutil/trace.go:171","msg":"trace[139181072] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"201.868656ms","start":"2026-02-25T17:06:19.892566Z","end":"2026-02-25T17:06:20.094435Z","steps":["trace[139181072] 'process raft request' (duration: 201.729173ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:20.200088Z","caller":"traceutil/trace.go:171","msg":"trace[1608303352] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"239.162933ms","start":"2026-02-25T17:06:19.960897Z","end":"2026-02-25T17:06:20.20006Z","steps":["trace[1608303352] 'process raft request' (duration: 235.555622ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:20.286023Z","caller":"traceutil/trace.go:171","msg":"trace[331901638] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"187.107936ms","start":"2026-02-25T17:06:20.098884Z","end":"2026-02-25T17:06:20.285992Z","steps":["trace[331901638] 'process raft request' (duration: 186.906872ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:20.28652Z","caller":"traceutil/trace.go:171","msg":"trace[1080546172] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"187.509563ms","start":"2026-02-25T17:06:20.09899Z","end":"2026-02-25T17:06:20.2865Z","steps":["trace[1080546172] 'process raft request' (duration: 186.928211ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:20.410534Z","caller":"traceutil/trace.go:171","msg":"trace[1989707831] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"203.095045ms","start":"2026-02-25T17:06:20.207419Z","end":"2026-02-25T17:06:20.410514Z","steps":["trace[1989707831] 'process raft request' (duration: 203.040154ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:20.410638Z","caller":"traceutil/trace.go:171","msg":"trace[1853558130] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"206.626514ms","start":"2026-02-25T17:06:20.203965Z","end":"2026-02-25T17:06:20.410592Z","steps":["trace[1853558130] 'process raft request' (duration: 206.274336ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:20.41068Z","caller":"traceutil/trace.go:171","msg":"trace[1732972338] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"206.122852ms","start":"2026-02-25T17:06:20.204524Z","end":"2026-02-25T17:06:20.410647Z","steps":["trace[1732972338] 'process raft request' (duration: 205.860926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:20.63896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.263783ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-25T17:06:20.639143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:20.287297Z","time spent":"351.842799ms","remote":"127.0.0.1:33924","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-25T17:06:20.639433Z","caller":"traceutil/trace.go:171","msg":"trace[1793610961] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"349.184059ms","start":"2026-02-25T17:06:20.290229Z","end":"2026-02-25T17:06:20.639413Z","steps":["trace[1793610961] 'process raft request' (duration: 348.846021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:20.639529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:20.290209Z","time spent":"349.273791ms","remote":"127.0.0.1:34444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1644,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T17:06:20.639556Z","caller":"traceutil/trace.go:171","msg":"trace[1385800453] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"348.045094ms","start":"2026-02-25T17:06:20.291503Z","end":"2026-02-25T17:06:20.639548Z","steps":["trace[1385800453] 'process raft request' (duration: 348.005723ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:20.639549Z","caller":"traceutil/trace.go:171","msg":"trace[1580174611] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"348.803801ms","start":"2026-02-25T17:06:20.290721Z","end":"2026-02-25T17:06:20.639525Z","steps":["trace[1580174611] 'process raft request' (duration: 348.595797ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:20.639557Z","caller":"traceutil/trace.go:171","msg":"trace[1372403289] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"348.139796ms","start":"2026-02-25T17:06:20.291406Z","end":"2026-02-25T17:06:20.639545Z","steps":["trace[1372403289] 'process raft request' (duration: 348.068284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:20.639668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:20.290705Z","time spent":"348.907604ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":771,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T17:06:20.639718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:20.291393Z","time spent":"348.293871ms","remote":"127.0.0.1:34118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3161,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T17:06:20.639973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:20.291494Z","time spent":"348.107525ms","remote":"127.0.0.1:34394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4084,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:06:20.645003Z","caller":"traceutil/trace.go:171","msg":"trace[739660219] linearizableReadLoop","detail":"{readStateIndex:1227; appliedIndex:1221; }","duration":"228.802289ms","start":"2026-02-25T17:06:20.416178Z","end":"2026-02-25T17:06:20.64498Z","steps":["trace[739660219] 'read index received' (duration: 54.502992ms)","trace[739660219] 'applied index is now lower than readState.Index' (duration: 174.298087ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:20.645137Z","caller":"traceutil/trace.go:171","msg":"trace[785169052] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"229.428644ms","start":"2026-02-25T17:06:20.415692Z","end":"2026-02-25T17:06:20.64512Z","steps":["trace[785169052] 'process raft request' (duration: 229.101486ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:20.645135Z","caller":"traceutil/trace.go:171","msg":"trace[538228927] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"222.651662ms","start":"2026-02-25T17:06:20.422465Z","end":"2026-02-25T17:06:20.645117Z","steps":["trace[538228927] 'process raft request' (duration: 222.444627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:20.645331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.126508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-controller-fmlfm\" ","response":"range_response_count:1 size:6031"} {"level":"info","ts":"2026-02-25T17:06:20.645418Z","caller":"traceutil/trace.go:171","msg":"trace[23102250] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-controller-fmlfm; range_end:; response_count:1; response_revision:1189; }","duration":"229.250311ms","start":"2026-02-25T17:06:20.416147Z","end":"2026-02-25T17:06:20.645398Z","steps":["trace[23102250] 'agreement among raft nodes before linearized reading' (duration: 229.034325ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:21.189047Z","caller":"traceutil/trace.go:171","msg":"trace[1686087747] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"357.193611ms","start":"2026-02-25T17:06:20.831831Z","end":"2026-02-25T17:06:21.189025Z","steps":["trace[1686087747] 'process raft request' (duration: 357.139269ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:21.18909Z","caller":"traceutil/trace.go:171","msg":"trace[554529690] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"364.872082ms","start":"2026-02-25T17:06:20.824181Z","end":"2026-02-25T17:06:21.189053Z","steps":["trace[554529690] 'process raft request' (duration: 294.800101ms)","trace[554529690] 'compare' (duration: 69.855396ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:21.189164Z","caller":"traceutil/trace.go:171","msg":"trace[215721698] linearizableReadLoop","detail":"{readStateIndex:1245; appliedIndex:1244; }","duration":"363.754097ms","start":"2026-02-25T17:06:20.825391Z","end":"2026-02-25T17:06:21.189145Z","steps":["trace[215721698] 'read index received' (duration: 293.600124ms)","trace[215721698] 'applied index is now lower than readState.Index' (duration: 70.151543ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:21.189264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:20.824151Z","time spent":"365.023856ms","remote":"127.0.0.1:34424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4177,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:06:21.189184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:20.831813Z","time spent":"357.315443ms","remote":"127.0.0.1:34144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1056,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T17:06:21.189382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.980462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend\" ","response":"range_response_count:1 size:1041"} {"level":"info","ts":"2026-02-25T17:06:21.189429Z","caller":"traceutil/trace.go:171","msg":"trace[1111563662] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend; range_end:; response_count:1; response_revision:1207; }","duration":"364.062423ms","start":"2026-02-25T17:06:20.825354Z","end":"2026-02-25T17:06:21.189416Z","steps":["trace[1111563662] 'agreement among raft nodes before linearized reading' (duration: 363.9026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:21.189382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.023313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-25T17:06:21.189464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:20.825337Z","time spent":"364.115335ms","remote":"127.0.0.1:34144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":1064,"request content":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend\" "} {"level":"info","ts":"2026-02-25T17:06:21.189516Z","caller":"traceutil/trace.go:171","msg":"trace[511751483] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1207; }","duration":"154.165457ms","start":"2026-02-25T17:06:21.035319Z","end":"2026-02-25T17:06:21.189484Z","steps":["trace[511751483] 'agreement among raft nodes before linearized reading' (duration: 154.002483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:22.02616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.858571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:06:22.026277Z","caller":"traceutil/trace.go:171","msg":"trace[1268587513] linearizableReadLoop","detail":"{readStateIndex:1260; appliedIndex:1259; }","duration":"393.991303ms","start":"2026-02-25T17:06:21.632255Z","end":"2026-02-25T17:06:22.026246Z","steps":["trace[1268587513] 'read index received' (duration: 247.915668ms)","trace[1268587513] 'applied index is now lower than readState.Index' (duration: 146.074225ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:22.026383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.119937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:22.026445Z","caller":"traceutil/trace.go:171","msg":"trace[361626138] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1221; }","duration":"394.186849ms","start":"2026-02-25T17:06:21.632225Z","end":"2026-02-25T17:06:22.026412Z","steps":["trace[361626138] 'agreement among raft nodes before linearized reading' (duration: 394.074927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:22.026482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:21.632209Z","time spent":"394.26241ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T17:06:22.026527Z","caller":"traceutil/trace.go:171","msg":"trace[51133209] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"544.863548ms","start":"2026-02-25T17:06:21.481624Z","end":"2026-02-25T17:06:22.026488Z","steps":["trace[51133209] 'process raft request' (duration: 398.605757ms)","trace[51133209] 'compare' (duration: 145.653426ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:22.02668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:21.481605Z","time spent":"545.00482ms","remote":"127.0.0.1:34260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1089,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T17:06:22.24777Z","caller":"traceutil/trace.go:171","msg":"trace[161192348] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"169.626524ms","start":"2026-02-25T17:06:22.078109Z","end":"2026-02-25T17:06:22.247735Z","steps":["trace[161192348] 'process raft request' (duration: 117.507505ms)","trace[161192348] 'compare' (duration: 51.825801ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:23.211894Z","caller":"traceutil/trace.go:171","msg":"trace[1809076906] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"126.21166ms","start":"2026-02-25T17:06:23.08565Z","end":"2026-02-25T17:06:23.211862Z","steps":["trace[1809076906] 'process raft request' (duration: 108.689116ms)","trace[1809076906] 'compare' (duration: 17.278199ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:24.061352Z","caller":"traceutil/trace.go:171","msg":"trace[705002459] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"186.02966ms","start":"2026-02-25T17:06:23.875303Z","end":"2026-02-25T17:06:24.061333Z","steps":["trace[705002459] 'process raft request' (duration: 185.912188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:24.437982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.577489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T17:06:24.438077Z","caller":"traceutil/trace.go:171","msg":"trace[168196883] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1241; }","duration":"292.714843ms","start":"2026-02-25T17:06:24.145338Z","end":"2026-02-25T17:06:24.438053Z","steps":["trace[168196883] 'count revisions from in-memory index tree' (duration: 292.485478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:25.6591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.20217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T17:06:25.659118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.991853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T17:06:25.659211Z","caller":"traceutil/trace.go:171","msg":"trace[1756415222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1243; }","duration":"225.350582ms","start":"2026-02-25T17:06:25.433836Z","end":"2026-02-25T17:06:25.659187Z","steps":["trace[1756415222] 'range keys from in-memory index tree' (duration: 225.048765ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:25.659225Z","caller":"traceutil/trace.go:171","msg":"trace[297918024] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1243; }","duration":"224.130196ms","start":"2026-02-25T17:06:25.435075Z","end":"2026-02-25T17:06:25.659205Z","steps":["trace[297918024] 'range keys from in-memory index tree' (duration: 223.856489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:25.659272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.246498ms","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-02-25T17:06:25.659336Z","caller":"traceutil/trace.go:171","msg":"trace[421453813] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1243; }","duration":"290.35157ms","start":"2026-02-25T17:06:25.368971Z","end":"2026-02-25T17:06:25.659323Z","steps":["trace[421453813] 'range keys from in-memory index tree' (duration: 290.110145ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:25.711797Z","caller":"traceutil/trace.go:171","msg":"trace[1033786561] linearizableReadLoop","detail":"{readStateIndex:1283; appliedIndex:1282; }","duration":"134.24629ms","start":"2026-02-25T17:06:25.577525Z","end":"2026-02-25T17:06:25.711771Z","steps":["trace[1033786561] 'read index received' (duration: 59.106835ms)","trace[1033786561] 'applied index is now lower than readState.Index' (duration: 75.138315ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:25.711989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.445364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T17:06:25.712072Z","caller":"traceutil/trace.go:171","msg":"trace[187448037] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1243; }","duration":"134.576338ms","start":"2026-02-25T17:06:25.57748Z","end":"2026-02-25T17:06:25.712056Z","steps":["trace[187448037] 'agreement among raft nodes before linearized reading' (duration: 134.397274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:25.749834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.610803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:25.749912Z","caller":"traceutil/trace.go:171","msg":"trace[399510641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1246; }","duration":"119.732035ms","start":"2026-02-25T17:06:25.630163Z","end":"2026-02-25T17:06:25.749895Z","steps":["trace[399510641] 'agreement among raft nodes before linearized reading' (duration: 119.50103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:31.65168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.648288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T17:06:31.651782Z","caller":"traceutil/trace.go:171","msg":"trace[1510317124] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1266; }","duration":"352.802121ms","start":"2026-02-25T17:06:31.298957Z","end":"2026-02-25T17:06:31.651759Z","steps":["trace[1510317124] 'range keys from in-memory index tree' (duration: 352.471943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:31.651828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:31.298937Z","time spent":"352.880323ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T17:06:31.652035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.285162ms","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-02-25T17:06:31.652085Z","caller":"traceutil/trace.go:171","msg":"trace[334244259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1266; }","duration":"873.358584ms","start":"2026-02-25T17:06:30.778713Z","end":"2026-02-25T17:06:31.652072Z","steps":["trace[334244259] 'range keys from in-memory index tree' (duration: 873.19624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:31.652109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:30.778696Z","time spent":"873.406825ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T17:06:32.331651Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523325424591073,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:06:32.831808Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639523325424591073,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:06:33.059845Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.3272021s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T17:06:33.060315Z","caller":"traceutil/trace.go:171","msg":"trace[862345373] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"1.402729873s","start":"2026-02-25T17:06:31.657556Z","end":"2026-02-25T17:06:33.060286Z","steps":["trace[862345373] 'process raft request' (duration: 1.402515898s)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:33.060431Z","caller":"traceutil/trace.go:171","msg":"trace[653049194] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"1.402830554s","start":"2026-02-25T17:06:31.657574Z","end":"2026-02-25T17:06:33.060404Z","steps":["trace[653049194] 'process raft request' (duration: 1.402660171s)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:33.060402Z","caller":"traceutil/trace.go:171","msg":"trace[318941844] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1307; }","duration":"1.229731724s","start":"2026-02-25T17:06:31.830647Z","end":"2026-02-25T17:06:33.060379Z","steps":["trace[318941844] 'read index received' (duration: 1.229316275s)","trace[318941844] 'applied index is now lower than readState.Index' (duration: 413.859µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:33.060527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:31.657527Z","time spent":"1.402912366s","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:06:33.06058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:31.657545Z","time spent":"1.402976058s","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:06:33.060577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.229920427s","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-02-25T17:06:33.060625Z","caller":"traceutil/trace.go:171","msg":"trace[1434592471] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1268; }","duration":"1.23002848s","start":"2026-02-25T17:06:31.830589Z","end":"2026-02-25T17:06:33.060617Z","steps":["trace[1434592471] 'agreement among raft nodes before linearized reading' (duration: 1.229838766s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:33.060653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:31.830569Z","time spent":"1.230077102s","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T17:06:33.060675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.217848367s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T17:06:33.060715Z","caller":"traceutil/trace.go:171","msg":"trace[1686498965] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1268; }","duration":"1.217910188s","start":"2026-02-25T17:06:31.842791Z","end":"2026-02-25T17:06:33.060701Z","steps":["trace[1686498965] 'agreement among raft nodes before linearized reading' (duration: 1.217799845s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:33.060748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:31.842781Z","time spent":"1.217960289s","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T17:06:33.060802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.215220798s","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-02-25T17:06:33.060964Z","caller":"traceutil/trace.go:171","msg":"trace[104917810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1268; }","duration":"1.215372162s","start":"2026-02-25T17:06:31.845535Z","end":"2026-02-25T17:06:33.060907Z","steps":["trace[104917810] 'agreement among raft nodes before linearized reading' (duration: 1.215133636s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:33.060955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041695888s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-patch-x8msx\" ","response":"range_response_count:1 size:4453"} {"level":"warn","ts":"2026-02-25T17:06:33.061063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:31.845524Z","time spent":"1.215489845s","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T17:06:33.061093Z","caller":"traceutil/trace.go:171","msg":"trace[1778002106] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-patch-x8msx; range_end:; response_count:1; response_revision:1268; }","duration":"1.041859272s","start":"2026-02-25T17:06:32.019195Z","end":"2026-02-25T17:06:33.061054Z","steps":["trace[1778002106] 'agreement among raft nodes before linearized reading' (duration: 1.041456772s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:33.061158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:32.019152Z","time spent":"1.041989695s","remote":"127.0.0.1:34118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":4476,"request content":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-patch-x8msx\" "} {"level":"info","ts":"2026-02-25T17:06:33.895453Z","caller":"traceutil/trace.go:171","msg":"trace[1844710465] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"104.424211ms","start":"2026-02-25T17:06:33.79101Z","end":"2026-02-25T17:06:33.895435Z","steps":["trace[1844710465] 'process raft request' (duration: 104.290348ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:36.68554Z","caller":"traceutil/trace.go:171","msg":"trace[21192784] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1350; }","duration":"118.040006ms","start":"2026-02-25T17:06:36.567466Z","end":"2026-02-25T17:06:36.685506Z","steps":["trace[21192784] 'read index received' (duration: 117.662358ms)","trace[21192784] 'applied index is now lower than readState.Index' (duration: 376.378µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:36.685616Z","caller":"traceutil/trace.go:171","msg":"trace[1193942738] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"175.441394ms","start":"2026-02-25T17:06:36.51012Z","end":"2026-02-25T17:06:36.685561Z","steps":["trace[1193942738] 'process raft request' (duration: 175.148997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:36.685763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.273782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/bindings.rabbitmq.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:36.685873Z","caller":"traceutil/trace.go:171","msg":"trace[306621878] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/bindings.rabbitmq.com; range_end:; response_count:0; response_revision:1308; }","duration":"118.440316ms","start":"2026-02-25T17:06:36.5674Z","end":"2026-02-25T17:06:36.68584Z","steps":["trace[306621878] 'agreement among raft nodes before linearized reading' (duration: 118.254142ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:36.821204Z","caller":"traceutil/trace.go:171","msg":"trace[1787333883] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"129.755451ms","start":"2026-02-25T17:06:36.691407Z","end":"2026-02-25T17:06:36.821162Z","steps":["trace[1787333883] 'process raft request' (duration: 90.143533ms)","trace[1787333883] 'compare' (duration: 39.418484ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:36.834672Z","caller":"traceutil/trace.go:171","msg":"trace[569225304] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"140.356557ms","start":"2026-02-25T17:06:36.694289Z","end":"2026-02-25T17:06:36.834645Z","steps":["trace[569225304] 'process raft request' (duration: 140.141723ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:36.834932Z","caller":"traceutil/trace.go:171","msg":"trace[1923512234] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"138.303072ms","start":"2026-02-25T17:06:36.696602Z","end":"2026-02-25T17:06:36.834905Z","steps":["trace[1923512234] 'process raft request' (duration: 137.980855ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:36.834925Z","caller":"traceutil/trace.go:171","msg":"trace[1076930997] linearizableReadLoop","detail":"{readStateIndex:1353; appliedIndex:1351; }","duration":"138.744132ms","start":"2026-02-25T17:06:36.696148Z","end":"2026-02-25T17:06:36.834892Z","steps":["trace[1076930997] 'read index received' (duration: 85.419306ms)","trace[1076930997] 'applied index is now lower than readState.Index' (duration: 53.220413ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:36.835051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.877685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/bindings.rabbitmq.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:36.835165Z","caller":"traceutil/trace.go:171","msg":"trace[894579274] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/bindings.rabbitmq.com; range_end:; response_count:0; response_revision:1311; }","duration":"139.021147ms","start":"2026-02-25T17:06:36.696127Z","end":"2026-02-25T17:06:36.835149Z","steps":["trace[894579274] 'agreement among raft nodes before linearized reading' (duration: 138.856544ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:36.955019Z","caller":"traceutil/trace.go:171","msg":"trace[898364014] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"104.055304ms","start":"2026-02-25T17:06:36.850941Z","end":"2026-02-25T17:06:36.954996Z","steps":["trace[898364014] 'process raft request' (duration: 104.009123ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:36.955077Z","caller":"traceutil/trace.go:171","msg":"trace[1678343266] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"111.542641ms","start":"2026-02-25T17:06:36.843502Z","end":"2026-02-25T17:06:36.955045Z","steps":["trace[1678343266] 'process raft request' (duration: 111.388947ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:36.955153Z","caller":"traceutil/trace.go:171","msg":"trace[1475327629] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"112.512284ms","start":"2026-02-25T17:06:36.842624Z","end":"2026-02-25T17:06:36.955136Z","steps":["trace[1475327629] 'process raft request' (duration: 99.480481ms)","trace[1475327629] 'compare' (duration: 12.672784ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:37.33488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.216998ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:06:37.335019Z","caller":"traceutil/trace.go:171","msg":"trace[1382517130] linearizableReadLoop","detail":"{readStateIndex:1364; appliedIndex:1363; }","duration":"294.054504ms","start":"2026-02-25T17:06:37.040943Z","end":"2026-02-25T17:06:37.334998Z","steps":["trace[1382517130] 'read index received' (duration: 143.56849ms)","trace[1382517130] 'applied index is now lower than readState.Index' (duration: 150.484134ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:37.335103Z","caller":"traceutil/trace.go:171","msg":"trace[107160000] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"297.026551ms","start":"2026-02-25T17:06:37.03805Z","end":"2026-02-25T17:06:37.335076Z","steps":["trace[107160000] 'process raft request' (duration: 146.506196ms)","trace[107160000] 'compare' (duration: 150.013513ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:37.335152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.200347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/federations.rabbitmq.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:37.335197Z","caller":"traceutil/trace.go:171","msg":"trace[1474560013] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/federations.rabbitmq.com; range_end:; response_count:0; response_revision:1321; }","duration":"294.276379ms","start":"2026-02-25T17:06:37.040906Z","end":"2026-02-25T17:06:37.335182Z","steps":["trace[1474560013] 'agreement among raft nodes before linearized reading' (duration: 294.199107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:37.335444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.516317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-25T17:06:37.335459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.598896ms","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-02-25T17:06:37.33551Z","caller":"traceutil/trace.go:171","msg":"trace[1632164053] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1321; }","duration":"162.679698ms","start":"2026-02-25T17:06:37.172818Z","end":"2026-02-25T17:06:37.335497Z","steps":["trace[1632164053] 'agreement among raft nodes before linearized reading' (duration: 162.521554ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:37.335508Z","caller":"traceutil/trace.go:171","msg":"trace[496614569] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1321; }","duration":"147.61688ms","start":"2026-02-25T17:06:37.187876Z","end":"2026-02-25T17:06:37.335492Z","steps":["trace[496614569] 'agreement among raft nodes before linearized reading' (duration: 147.449176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:37.335554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.634097ms","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-02-25T17:06:37.335609Z","caller":"traceutil/trace.go:171","msg":"trace[1651959917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1321; }","duration":"162.718309ms","start":"2026-02-25T17:06:37.172877Z","end":"2026-02-25T17:06:37.335596Z","steps":["trace[1651959917] 'agreement among raft nodes before linearized reading' (duration: 162.581426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:37.335881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.791692ms","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-02-25T17:06:37.335943Z","caller":"traceutil/trace.go:171","msg":"trace[601003807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1321; }","duration":"187.880403ms","start":"2026-02-25T17:06:37.148048Z","end":"2026-02-25T17:06:37.335929Z","steps":["trace[601003807] 'agreement among raft nodes before linearized reading' (duration: 187.728269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:37.335887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.816034ms","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-02-25T17:06:37.336053Z","caller":"traceutil/trace.go:171","msg":"trace[1292972706] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1321; }","duration":"193.013198ms","start":"2026-02-25T17:06:37.143012Z","end":"2026-02-25T17:06:37.336025Z","steps":["trace[1292972706] 'agreement among raft nodes before linearized reading' (duration: 192.321711ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:37.68809Z","caller":"traceutil/trace.go:171","msg":"trace[1593953202] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"166.060793ms","start":"2026-02-25T17:06:37.52199Z","end":"2026-02-25T17:06:37.688051Z","steps":["trace[1593953202] 'process raft request' (duration: 102.437487ms)","trace[1593953202] 'compare' (duration: 63.36944ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:39.481336Z","caller":"traceutil/trace.go:171","msg":"trace[903651421] linearizableReadLoop","detail":"{readStateIndex:1405; appliedIndex:1404; }","duration":"120.235946ms","start":"2026-02-25T17:06:39.361069Z","end":"2026-02-25T17:06:39.481305Z","steps":["trace[903651421] 'read index received' (duration: 119.919459ms)","trace[903651421] 'applied index is now lower than readState.Index' (duration: 314.747µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:39.481384Z","caller":"traceutil/trace.go:171","msg":"trace[2016739297] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"125.824172ms","start":"2026-02-25T17:06:39.355529Z","end":"2026-02-25T17:06:39.481353Z","steps":["trace[2016739297] 'process raft request' (duration: 125.541365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:39.481663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.568854ms","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-02-25T17:06:39.481722Z","caller":"traceutil/trace.go:171","msg":"trace[2105286312] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1362; }","duration":"120.711557ms","start":"2026-02-25T17:06:39.360996Z","end":"2026-02-25T17:06:39.481708Z","steps":["trace[2105286312] 'agreement among raft nodes before linearized reading' (duration: 120.443181ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:39.648625Z","caller":"traceutil/trace.go:171","msg":"trace[79034351] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"161.504272ms","start":"2026-02-25T17:06:39.487083Z","end":"2026-02-25T17:06:39.648588Z","steps":["trace[79034351] 'process raft request' (duration: 161.391669ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:39.648646Z","caller":"traceutil/trace.go:171","msg":"trace[95102378] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"162.31426ms","start":"2026-02-25T17:06:39.486278Z","end":"2026-02-25T17:06:39.648592Z","steps":["trace[95102378] 'process raft request' (duration: 100.093715ms)","trace[95102378] 'compare' (duration: 61.862327ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:39.648901Z","caller":"traceutil/trace.go:171","msg":"trace[1292716962] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"161.742587ms","start":"2026-02-25T17:06:39.487131Z","end":"2026-02-25T17:06:39.648874Z","steps":["trace[1292716962] 'process raft request' (duration: 161.401169ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:39.6489Z","caller":"traceutil/trace.go:171","msg":"trace[1982057289] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"161.564842ms","start":"2026-02-25T17:06:39.487314Z","end":"2026-02-25T17:06:39.648878Z","steps":["trace[1982057289] 'process raft request' (duration: 161.45142ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:41.122834Z","caller":"traceutil/trace.go:171","msg":"trace[1609681206] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1370; }","duration":"160.849446ms","start":"2026-02-25T17:06:40.961955Z","end":"2026-02-25T17:06:41.122805Z","steps":["trace[1609681206] 'process raft request' (duration: 160.703083ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:43.384851Z","caller":"traceutil/trace.go:171","msg":"trace[786639720] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"102.265254ms","start":"2026-02-25T17:06:43.282548Z","end":"2026-02-25T17:06:43.384813Z","steps":["trace[786639720] 'process raft request' (duration: 102.071489ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:46.465586Z","caller":"traceutil/trace.go:171","msg":"trace[1930624284] transaction","detail":"{read_only:false; response_revision:1498; number_of_response:1; }","duration":"109.272261ms","start":"2026-02-25T17:06:46.356289Z","end":"2026-02-25T17:06:46.465561Z","steps":["trace[1930624284] 'process raft request' (duration: 109.072996ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:14:35.784056Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2139} {"level":"info","ts":"2026-02-25T17:14:35.837419Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2139,"took":"52.513686ms","hash":954232032,"current-db-size-bytes":14036992,"current-db-size":"14 MB","current-db-size-in-use-bytes":4325376,"current-db-size-in-use":"4.3 MB"} {"level":"info","ts":"2026-02-25T17:14:35.837494Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":954232032,"revision":2139,"compact-revision":-1} {"level":"info","ts":"2026-02-25T17:17:19.073929Z","caller":"traceutil/trace.go:171","msg":"trace[1040811223] linearizableReadLoop","detail":"{readStateIndex:3773; appliedIndex:3772; }","duration":"130.393886ms","start":"2026-02-25T17:17:18.943504Z","end":"2026-02-25T17:17:19.073898Z","steps":["trace[1040811223] 'read index received' (duration: 130.240383ms)","trace[1040811223] 'applied index is now lower than readState.Index' (duration: 152.113µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:17:19.074078Z","caller":"traceutil/trace.go:171","msg":"trace[229888912] transaction","detail":"{read_only:false; response_revision:3575; number_of_response:1; }","duration":"159.43734ms","start":"2026-02-25T17:17:18.914629Z","end":"2026-02-25T17:17:19.074066Z","steps":["trace[229888912] 'process raft request' (duration: 159.088462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:17:19.074357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.523789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-25T17:17:19.074441Z","caller":"traceutil/trace.go:171","msg":"trace[1693757448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3575; }","duration":"112.648192ms","start":"2026-02-25T17:17:18.96177Z","end":"2026-02-25T17:17:19.074418Z","steps":["trace[1693757448] 'agreement among raft nodes before linearized reading' (duration: 112.461598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:17:19.074518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.991651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-25T17:17:19.074581Z","caller":"traceutil/trace.go:171","msg":"trace[1531523103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3575; }","duration":"131.092773ms","start":"2026-02-25T17:17:18.943472Z","end":"2026-02-25T17:17:19.074565Z","steps":["trace[1531523103] 'agreement among raft nodes before linearized reading' (duration: 130.963789ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:17:19.260177Z","caller":"traceutil/trace.go:171","msg":"trace[1578996590] transaction","detail":"{read_only:false; response_revision:3577; number_of_response:1; }","duration":"181.720309ms","start":"2026-02-25T17:17:19.078428Z","end":"2026-02-25T17:17:19.260148Z","steps":["trace[1578996590] 'process raft request' (duration: 106.417239ms)","trace[1578996590] 'compare' (duration: 75.106196ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:17:19.260519Z","caller":"traceutil/trace.go:171","msg":"trace[315667728] transaction","detail":"{read_only:false; response_revision:3578; number_of_response:1; }","duration":"181.573987ms","start":"2026-02-25T17:17:19.078933Z","end":"2026-02-25T17:17:19.260507Z","steps":["trace[315667728] 'process raft request' (duration: 181.166457ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:17:19.26065Z","caller":"traceutil/trace.go:171","msg":"trace[377121985] linearizableReadLoop","detail":"{readStateIndex:3777; appliedIndex:3773; }","duration":"158.412848ms","start":"2026-02-25T17:17:19.102154Z","end":"2026-02-25T17:17:19.260567Z","steps":["trace[377121985] 'read index received' (duration: 8.139716ms)","trace[377121985] 'applied index is now lower than readState.Index' (duration: 150.272222ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:17:19.260952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.775076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:3875"} {"level":"info","ts":"2026-02-25T17:17:19.260995Z","caller":"traceutil/trace.go:171","msg":"trace[1469547306] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:3579; }","duration":"158.872918ms","start":"2026-02-25T17:17:19.102109Z","end":"2026-02-25T17:17:19.260982Z","steps":["trace[1469547306] 'agreement among raft nodes before linearized reading' (duration: 158.607852ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:17:19.261291Z","caller":"traceutil/trace.go:171","msg":"trace[928576887] transaction","detail":"{read_only:false; response_revision:3579; number_of_response:1; }","duration":"182.20485ms","start":"2026-02-25T17:17:19.07903Z","end":"2026-02-25T17:17:19.261235Z","steps":["trace[928576887] 'process raft request' (duration: 181.415412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:17:30.516497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.348894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:3875"} {"level":"info","ts":"2026-02-25T17:17:30.516596Z","caller":"traceutil/trace.go:171","msg":"trace[1039736486] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:3612; }","duration":"101.497357ms","start":"2026-02-25T17:17:30.415079Z","end":"2026-02-25T17:17:30.516577Z","steps":["trace[1039736486] 'range keys from in-memory index tree' (duration: 100.888654ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:17:43.672247Z","caller":"traceutil/trace.go:171","msg":"trace[230057441] transaction","detail":"{read_only:false; response_revision:3650; number_of_response:1; }","duration":"246.651059ms","start":"2026-02-25T17:17:43.425574Z","end":"2026-02-25T17:17:43.672225Z","steps":["trace[230057441] 'process raft request' (duration: 246.497325ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:17:46.177987Z","caller":"traceutil/trace.go:171","msg":"trace[1713463413] transaction","detail":"{read_only:false; response_revision:3661; number_of_response:1; }","duration":"120.586737ms","start":"2026-02-25T17:17:46.057371Z","end":"2026-02-25T17:17:46.177958Z","steps":["trace[1713463413] 'process raft request' (duration: 120.426853ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:17:48.122397Z","caller":"traceutil/trace.go:171","msg":"trace[544801457] transaction","detail":"{read_only:false; response_revision:3665; number_of_response:1; }","duration":"109.155316ms","start":"2026-02-25T17:17:48.013207Z","end":"2026-02-25T17:17:48.122362Z","steps":["trace[544801457] 'process raft request' (duration: 55.088379ms)","trace[544801457] 'compare' (duration: 53.915774ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:17:48.147516Z","caller":"traceutil/trace.go:171","msg":"trace[155086602] transaction","detail":"{read_only:false; response_revision:3666; number_of_response:1; }","duration":"133.957503ms","start":"2026-02-25T17:17:48.013538Z","end":"2026-02-25T17:17:48.147495Z","steps":["trace[155086602] 'process raft request' (duration: 133.807019ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:17:48.147687Z","caller":"traceutil/trace.go:171","msg":"trace[1663260006] transaction","detail":"{read_only:false; response_revision:3667; number_of_response:1; }","duration":"133.655166ms","start":"2026-02-25T17:17:48.013999Z","end":"2026-02-25T17:17:48.147655Z","steps":["trace[1663260006] 'process raft request' (duration: 133.462872ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:19:35.789663Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3071} {"level":"info","ts":"2026-02-25T17:19:35.809968Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3071,"took":"19.553817ms","hash":3085833645,"current-db-size-bytes":14036992,"current-db-size":"14 MB","current-db-size-in-use-bytes":4689920,"current-db-size-in-use":"4.7 MB"} {"level":"info","ts":"2026-02-25T17:19:35.810032Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3085833645,"revision":3071,"compact-revision":2139} {"level":"info","ts":"2026-02-25T17:20:06.801041Z","caller":"traceutil/trace.go:171","msg":"trace[641846834] linearizableReadLoop","detail":"{readStateIndex:4325; appliedIndex:4324; }","duration":"107.87375ms","start":"2026-02-25T17:20:06.693134Z","end":"2026-02-25T17:20:06.801008Z","steps":["trace[641846834] 'read index received' (duration: 107.550143ms)","trace[641846834] 'applied index is now lower than readState.Index' (duration: 322.407µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:20:06.801099Z","caller":"traceutil/trace.go:171","msg":"trace[1468049483] transaction","detail":"{read_only:false; response_revision:4092; number_of_response:1; }","duration":"109.243331ms","start":"2026-02-25T17:20:06.691814Z","end":"2026-02-25T17:20:06.801057Z","steps":["trace[1468049483] 'process raft request' (duration: 108.936784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:20:06.801248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.105506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-25T17:20:06.801334Z","caller":"traceutil/trace.go:171","msg":"trace[117011237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4092; }","duration":"108.240879ms","start":"2026-02-25T17:20:06.693075Z","end":"2026-02-25T17:20:06.801316Z","steps":["trace[117011237] 'agreement among raft nodes before linearized reading' (duration: 108.045674ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:20:21.162053Z","caller":"traceutil/trace.go:171","msg":"trace[774562914] transaction","detail":"{read_only:false; response_revision:4139; number_of_response:1; }","duration":"100.207496ms","start":"2026-02-25T17:20:21.061814Z","end":"2026-02-25T17:20:21.162022Z","steps":["trace[774562914] 'process raft request' (duration: 98.777353ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:22:19.618353Z","caller":"traceutil/trace.go:171","msg":"trace[1554449990] linearizableReadLoop","detail":"{readStateIndex:4759; appliedIndex:4758; }","duration":"104.917212ms","start":"2026-02-25T17:22:19.513413Z","end":"2026-02-25T17:22:19.61833Z","steps":["trace[1554449990] 'read index received' (duration: 104.730988ms)","trace[1554449990] 'applied index is now lower than readState.Index' (duration: 185.314µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:22:19.618475Z","caller":"traceutil/trace.go:171","msg":"trace[645973780] transaction","detail":"{read_only:false; response_revision:4499; number_of_response:1; }","duration":"127.221889ms","start":"2026-02-25T17:22:19.491216Z","end":"2026-02-25T17:22:19.618438Z","steps":["trace[645973780] 'process raft request' (duration: 126.987544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:22:19.618607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.111018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubelet-config\" ","response":"range_response_count:1 size:1357"} {"level":"warn","ts":"2026-02-25T17:22:19.618607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.173948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-25T17:22:19.618649Z","caller":"traceutil/trace.go:171","msg":"trace[194784944] range","detail":"{range_begin:/registry/configmaps/kube-system/kubelet-config; range_end:; response_count:1; response_revision:4499; }","duration":"102.196591ms","start":"2026-02-25T17:22:19.516442Z","end":"2026-02-25T17:22:19.618639Z","steps":["trace[194784944] 'agreement among raft nodes before linearized reading' (duration: 102.024907ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:22:19.618687Z","caller":"traceutil/trace.go:171","msg":"trace[1710966860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4499; }","duration":"105.319082ms","start":"2026-02-25T17:22:19.51335Z","end":"2026-02-25T17:22:19.618669Z","steps":["trace[1710966860] 'agreement among raft nodes before linearized reading' (duration: 105.141828ms)"],"step_count":1}