{"level":"warn","ts":"2026-06-10T10:02:01.584501Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-10T10:02:01.584582Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.249: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.249:2380","--initial-cluster=instance=https://199.19.213.249:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.249:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.249: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-06-10T10:02:01.58465Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-10T10:02:01.584664Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.249:2380"]} {"level":"info","ts":"2026-06-10T10:02:01.584681Z","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-06-10T10:02:01.585317Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.249:2379"]} {"level":"info","ts":"2026-06-10T10:02:01.585411Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.249:2380"],"listen-peer-urls":["https://199.19.213.249:2380"],"advertise-client-urls":["https://199.19.213.249:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.249:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.249: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-06-10T10:02:01.633823Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"45.640002ms"} {"level":"info","ts":"2026-06-10T10:02:01.664295Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5835264b86215850","cluster-id":"4e10f3807bdbbd9b"} {"level":"info","ts":"2026-06-10T10:02:01.664598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 switched to configuration voters=()"} {"level":"info","ts":"2026-06-10T10:02:01.664648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became follower at term 0"} {"level":"info","ts":"2026-06-10T10:02:01.664669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5835264b86215850 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-10T10:02:01.664726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became follower at term 1"} {"level":"info","ts":"2026-06-10T10:02:01.664795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 switched to configuration voters=(6356028554918058064)"} {"level":"warn","ts":"2026-06-10T10:02:01.770612Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-10T10:02:01.778058Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-10T10:02:01.828808Z","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-06-10T10:02:01.891674Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5835264b86215850","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-10T10:02:01.894816Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5835264b86215850","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-10T10:02:01.895009Z","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-06-10T10:02:01.895046Z","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-06-10T10:02:01.895081Z","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-06-10T10:02:01.896318Z","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-06-10T10:02:01.896623Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.249:2380"} {"level":"info","ts":"2026-06-10T10:02:01.896657Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.249:2380"} {"level":"info","ts":"2026-06-10T10:02:01.896927Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5835264b86215850","initial-advertise-peer-urls":["https://199.19.213.249:2380"],"listen-peer-urls":["https://199.19.213.249:2380"],"advertise-client-urls":["https://199.19.213.249:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.249:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-10T10:02:01.896957Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-10T10:02:01.953885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 switched to configuration voters=(6356028554918058064)"} {"level":"info","ts":"2026-06-10T10:02:01.954013Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4e10f3807bdbbd9b","local-member-id":"5835264b86215850","added-peer-id":"5835264b86215850","added-peer-peer-urls":["https://199.19.213.249:2380"]} {"level":"info","ts":"2026-06-10T10:02:02.765805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 is starting a new election at term 1"} {"level":"info","ts":"2026-06-10T10:02:02.765978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-10T10:02:02.766009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 received MsgPreVoteResp from 5835264b86215850 at term 1"} {"level":"info","ts":"2026-06-10T10:02:02.766033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became candidate at term 2"} {"level":"info","ts":"2026-06-10T10:02:02.766044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 received MsgVoteResp from 5835264b86215850 at term 2"} {"level":"info","ts":"2026-06-10T10:02:02.766061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became leader at term 2"} {"level":"info","ts":"2026-06-10T10:02:02.766076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5835264b86215850 elected leader 5835264b86215850 at term 2"} {"level":"info","ts":"2026-06-10T10:02:02.851552Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-10T10:02:02.851653Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T10:02:02.851834Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-10T10:02:02.852272Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5835264b86215850","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.249:2379]}","request-path":"/0/members/5835264b86215850/attributes","cluster-id":"4e10f3807bdbbd9b","publish-timeout":"7s"} {"level":"info","ts":"2026-06-10T10:02:02.85352Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-10T10:02:02.853604Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-10T10:02:02.855183Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.249:2379"} {"level":"info","ts":"2026-06-10T10:02:02.855755Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-10T10:02:02.910398Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4e10f3807bdbbd9b","local-member-id":"5835264b86215850","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T10:02:02.910655Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T10:02:02.910748Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T10:02:04.460873Z","caller":"traceutil/trace.go:171","msg":"trace[1024526333] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"191.851125ms","start":"2026-06-10T10:02:04.268985Z","end":"2026-06-10T10:02:04.460836Z","steps":["trace[1024526333] 'read index received' (duration: 191.458922ms)","trace[1024526333] 'applied index is now lower than readState.Index' (duration: 390.443µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:02:04.460912Z","caller":"traceutil/trace.go:171","msg":"trace[2144889863] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"192.48643ms","start":"2026-06-10T10:02:04.268363Z","end":"2026-06-10T10:02:04.460849Z","steps":["trace[2144889863] 'process raft request' (duration: 192.116807ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:04.461131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.104307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-10T10:02:04.461488Z","caller":"traceutil/trace.go:171","msg":"trace[2068817358] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:2; }","duration":"192.48512ms","start":"2026-06-10T10:02:04.268982Z","end":"2026-06-10T10:02:04.461467Z","steps":["trace[2068817358] 'agreement among raft nodes before linearized reading' (duration: 191.943446ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:04.834085Z","caller":"traceutil/trace.go:171","msg":"trace[366632170] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"475.48892ms","start":"2026-06-10T10:02:04.358537Z","end":"2026-06-10T10:02:04.834026Z","steps":["trace[366632170] 'process raft request' (duration: 475.086456ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:04.834132Z","caller":"traceutil/trace.go:171","msg":"trace[1480138803] linearizableReadLoop","detail":"{readStateIndex:12; appliedIndex:5; }","duration":"372.977604ms","start":"2026-06-10T10:02:04.461119Z","end":"2026-06-10T10:02:04.834097Z","steps":["trace[1480138803] 'read index received' (duration: 372.46348ms)","trace[1480138803] 'applied index is now lower than readState.Index' (duration: 512.754µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:02:04.834157Z","caller":"traceutil/trace.go:171","msg":"trace[1937193123] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"475.270786ms","start":"2026-06-10T10:02:04.35885Z","end":"2026-06-10T10:02:04.834121Z","steps":["trace[1937193123] 'process raft request' (duration: 474.986554ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:04.834315Z","caller":"traceutil/trace.go:171","msg":"trace[623676941] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"473.469122ms","start":"2026-06-10T10:02:04.360833Z","end":"2026-06-10T10:02:04.834302Z","steps":["trace[623676941] 'process raft request' (duration: 473.070139ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:04.834367Z","caller":"traceutil/trace.go:171","msg":"trace[1429828336] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"473.407272ms","start":"2026-06-10T10:02:04.360951Z","end":"2026-06-10T10:02:04.834358Z","steps":["trace[1429828336] 'process raft request' (duration: 472.995768ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:04.834516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.477788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-10T10:02:04.83457Z","caller":"traceutil/trace.go:171","msg":"trace[2144335953] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"468.586258ms","start":"2026-06-10T10:02:04.36597Z","end":"2026-06-10T10:02:04.834556Z","steps":["trace[2144335953] 'agreement among raft nodes before linearized reading' (duration: 468.422997ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:04.834576Z","caller":"traceutil/trace.go:171","msg":"trace[395205522] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"473.510622ms","start":"2026-06-10T10:02:04.361002Z","end":"2026-06-10T10:02:04.834512Z","steps":["trace[395205522] 'process raft request' (duration: 472.977787ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:04.834615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.36596Z","time spent":"468.647319ms","remote":"127.0.0.1:40906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-10T10:02:04.834665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.074241ms","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-06-10T10:02:04.834726Z","caller":"traceutil/trace.go:171","msg":"trace[649770403] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:9; }","duration":"496.213952ms","start":"2026-06-10T10:02:04.338494Z","end":"2026-06-10T10:02:04.834708Z","steps":["trace[649770403] 'agreement among raft nodes before linearized reading' (duration: 496.064551ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:04.834775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.338472Z","time spent":"496.292373ms","remote":"127.0.0.1:40878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-06-10T10:02:04.834527Z","caller":"traceutil/trace.go:171","msg":"trace[1192680909] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"473.400431ms","start":"2026-06-10T10:02:04.361093Z","end":"2026-06-10T10:02:04.834494Z","steps":["trace[1192680909] 'process raft request' (duration: 472.963257ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:04.834829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.358518Z","time spent":"475.685141ms","remote":"127.0.0.1:41232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T10:02:04.83487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.036531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-10T10:02:04.834938Z","caller":"traceutil/trace.go:171","msg":"trace[469064306] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"444.123901ms","start":"2026-06-10T10:02:04.390795Z","end":"2026-06-10T10:02:04.834919Z","steps":["trace[469064306] 'agreement among raft nodes before linearized reading' (duration: 444.01114ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:04.834731Z","caller":"traceutil/trace.go:171","msg":"trace[1606349091] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"473.436392ms","start":"2026-06-10T10:02:04.361049Z","end":"2026-06-10T10:02:04.834486Z","steps":["trace[1606349091] 'process raft request' (duration: 472.965258ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:04.834983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.572946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-10T10:02:04.834985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.390784Z","time spent":"444.192553ms","remote":"127.0.0.1:40906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-06-10T10:02:04.835026Z","caller":"traceutil/trace.go:171","msg":"trace[896721224] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"513.651877ms","start":"2026-06-10T10:02:04.32136Z","end":"2026-06-10T10:02:04.835012Z","steps":["trace[896721224] 'agreement among raft nodes before linearized reading' (duration: 513.591416ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:04.835065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.321339Z","time spent":"513.719527ms","remote":"127.0.0.1:40906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-10T10:02:04.835192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.458303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.249\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-10T10:02:04.835227Z","caller":"traceutil/trace.go:171","msg":"trace[212666906] range","detail":"{range_begin:/registry/masterleases/199.19.213.249; range_end:; response_count:0; response_revision:9; }","duration":"498.525453ms","start":"2026-06-10T10:02:04.336691Z","end":"2026-06-10T10:02:04.835216Z","steps":["trace[212666906] 'agreement among raft nodes before linearized reading' (duration: 498.479982ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:04.83518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.361087Z","time spent":"473.857254ms","remote":"127.0.0.1:41326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T10:02:04.835261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.33668Z","time spent":"498.575173ms","remote":"127.0.0.1:40842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":26,"request content":"key:\"/registry/masterleases/199.19.213.249\" "} {"level":"warn","ts":"2026-06-10T10:02:04.835308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.36082Z","time spent":"473.528432ms","remote":"127.0.0.1:41326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T10:02:04.835249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.555794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-10T10:02:04.835473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.360995Z","time spent":"473.702113ms","remote":"127.0.0.1:41326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T10:02:04.83557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.360943Z","time spent":"473.595333ms","remote":"127.0.0.1:41326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T10:02:04.835484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.361043Z","time spent":"474.167219ms","remote":"127.0.0.1:41326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T10:02:04.835852Z","caller":"traceutil/trace.go:171","msg":"trace[226898966] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:9; }","duration":"557.762836ms","start":"2026-06-10T10:02:04.277675Z","end":"2026-06-10T10:02:04.835438Z","steps":["trace[226898966] 'agreement among raft nodes before linearized reading' (duration: 557.538804ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:04.835911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.277669Z","time spent":"558.224319ms","remote":"127.0.0.1:41174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-06-10T10:02:04.835256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.358839Z","time spent":"475.416388ms","remote":"127.0.0.1:40974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T10:02:05.293647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.516444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-06-10T10:02:05.293799Z","caller":"traceutil/trace.go:171","msg":"trace[1894214956] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"831.147453ms","start":"2026-06-10T10:02:04.462623Z","end":"2026-06-10T10:02:05.29377Z","steps":["trace[1894214956] 'process raft request' (duration: 451.050584ms)","trace[1894214956] 'compare' (duration: 379.381283ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:02:05.293882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.4626Z","time spent":"831.244343ms","remote":"127.0.0.1:40864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T10:02:05.346998Z","caller":"traceutil/trace.go:171","msg":"trace[60557266] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"507.567452ms","start":"2026-06-10T10:02:04.83929Z","end":"2026-06-10T10:02:05.346857Z","steps":["trace[60557266] 'process raft request' (duration: 507.234169ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:05.347294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.839268Z","time spent":"507.902845ms","remote":"127.0.0.1:40906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T10:02:05.34703Z","caller":"traceutil/trace.go:171","msg":"trace[247132223] transaction","detail":"{read_only:false; number_of_response:0; response_revision:11; }","duration":"506.090048ms","start":"2026-06-10T10:02:04.840882Z","end":"2026-06-10T10:02:05.346972Z","steps":["trace[247132223] 'process raft request' (duration: 505.754505ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:05.347444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.840855Z","time spent":"506.511712ms","remote":"127.0.0.1:40906","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":26,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T10:02:05.348088Z","caller":"traceutil/trace.go:171","msg":"trace[1669107220] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"506.819305ms","start":"2026-06-10T10:02:04.841253Z","end":"2026-06-10T10:02:05.348073Z","steps":["trace[1669107220] 'process raft request' (duration: 506.563273ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:05.348132Z","caller":"traceutil/trace.go:171","msg":"trace[538333212] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"506.759215ms","start":"2026-06-10T10:02:04.841351Z","end":"2026-06-10T10:02:05.348111Z","steps":["trace[538333212] 'process raft request' (duration: 506.512643ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:05.348172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.840889Z","time spent":"507.240999ms","remote":"127.0.0.1:41326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T10:02:05.348141Z","caller":"traceutil/trace.go:171","msg":"trace[1902805656] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"506.603153ms","start":"2026-06-10T10:02:04.841483Z","end":"2026-06-10T10:02:05.348086Z","steps":["trace[1902805656] 'process raft request' (duration: 506.429082ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:05.34823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.841337Z","time spent":"506.846195ms","remote":"127.0.0.1:41326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T10:02:05.348327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.841465Z","time spent":"506.764955ms","remote":"127.0.0.1:41326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T10:02:05.348405Z","caller":"traceutil/trace.go:171","msg":"trace[571997688] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"507.246189ms","start":"2026-06-10T10:02:04.841144Z","end":"2026-06-10T10:02:05.34839Z","steps":["trace[571997688] 'process raft request' (duration: 506.611234ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:05.348486Z","caller":"traceutil/trace.go:171","msg":"trace[1806412391] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"507.408191ms","start":"2026-06-10T10:02:04.841062Z","end":"2026-06-10T10:02:05.34847Z","steps":["trace[1806412391] 'process raft request' (duration: 505.695835ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:05.348487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.841134Z","time spent":"507.31545ms","remote":"127.0.0.1:41326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T10:02:05.348548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.84105Z","time spent":"507.470621ms","remote":"127.0.0.1:41326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T10:02:05.349198Z","caller":"traceutil/trace.go:171","msg":"trace[603379341] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"506.926427ms","start":"2026-06-10T10:02:04.842254Z","end":"2026-06-10T10:02:05.349181Z","steps":["trace[603379341] 'process raft request' (duration: 505.720097ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:05.349286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.842244Z","time spent":"507.001177ms","remote":"127.0.0.1:41232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T10:02:05.349666Z","caller":"traceutil/trace.go:171","msg":"trace[1166211522] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:12; }","duration":"509.665601ms","start":"2026-06-10T10:02:04.839981Z","end":"2026-06-10T10:02:05.349647Z","steps":["trace[1166211522] 'read index received' (duration: 73.721282ms)","trace[1166211522] 'applied index is now lower than readState.Index' (duration: 435.941979ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:02:05.349777Z","caller":"traceutil/trace.go:171","msg":"trace[1667784079] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"503.808499ms","start":"2026-06-10T10:02:04.845952Z","end":"2026-06-10T10:02:05.34976Z","steps":["trace[1667784079] 'process raft request' (duration: 502.084624ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:05.349887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.845943Z","time spent":"503.872749ms","remote":"127.0.0.1:40974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T10:02:05.350036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.052654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-10T10:02:05.350086Z","caller":"traceutil/trace.go:171","msg":"trace[2084275282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18; }","duration":"510.131434ms","start":"2026-06-10T10:02:04.839937Z","end":"2026-06-10T10:02:05.350068Z","steps":["trace[2084275282] 'agreement among raft nodes before linearized reading' (duration: 510.054864ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:05.350132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.839918Z","time spent":"510.204665ms","remote":"127.0.0.1:40806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-10T10:02:05.478363Z","caller":"traceutil/trace.go:171","msg":"trace[2022899982] linearizableReadLoop","detail":"{readStateIndex:23; appliedIndex:22; }","duration":"128.598319ms","start":"2026-06-10T10:02:05.349719Z","end":"2026-06-10T10:02:05.478317Z","steps":["trace[2022899982] 'read index received' (duration: 105.90892ms)","trace[2022899982] 'applied index is now lower than readState.Index' (duration: 22.686269ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:02:05.478475Z","caller":"traceutil/trace.go:171","msg":"trace[2013057720] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"536.908863ms","start":"2026-06-10T10:02:04.941527Z","end":"2026-06-10T10:02:05.478436Z","steps":["trace[2013057720] 'process raft request' (duration: 514.043682ms)","trace[2013057720] 'compare' (duration: 22.440297ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:02:05.478495Z","caller":"traceutil/trace.go:171","msg":"trace[1396705579] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"123.763856ms","start":"2026-06-10T10:02:05.354701Z","end":"2026-06-10T10:02:05.478465Z","steps":["trace[1396705579] 'process raft request' (duration: 123.545214ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:05.478564Z","caller":"traceutil/trace.go:171","msg":"trace[268781314] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"123.740415ms","start":"2026-06-10T10:02:05.354789Z","end":"2026-06-10T10:02:05.478529Z","steps":["trace[268781314] 'process raft request' (duration: 123.501893ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:05.478626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.941491Z","time spent":"537.047793ms","remote":"127.0.0.1:41048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T10:02:05.478665Z","caller":"traceutil/trace.go:171","msg":"trace[1443070609] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"123.711894ms","start":"2026-06-10T10:02:05.35494Z","end":"2026-06-10T10:02:05.478652Z","steps":["trace[1443070609] 'process raft request' (duration: 123.399852ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:05.479142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.704302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-10T10:02:05.479209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.211637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-06-10T10:02:05.479216Z","caller":"traceutil/trace.go:171","msg":"trace[1251624932] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:22; }","duration":"239.836693ms","start":"2026-06-10T10:02:05.239357Z","end":"2026-06-10T10:02:05.479194Z","steps":["trace[1251624932] 'agreement among raft nodes before linearized reading' (duration: 239.141777ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:05.479237Z","caller":"traceutil/trace.go:171","msg":"trace[1063922097] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:22; }","duration":"639.266057ms","start":"2026-06-10T10:02:04.839961Z","end":"2026-06-10T10:02:05.479227Z","steps":["trace[1063922097] 'agreement among raft nodes before linearized reading' (duration: 638.599252ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:05.479226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.339475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-06-10T10:02:05.479266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.839861Z","time spent":"639.400808ms","remote":"127.0.0.1:40974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3513,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-06-10T10:02:05.479358Z","caller":"traceutil/trace.go:171","msg":"trace[783851978] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:22; }","duration":"581.526497ms","start":"2026-06-10T10:02:04.897813Z","end":"2026-06-10T10:02:05.47934Z","steps":["trace[783851978] 'agreement among raft nodes before linearized reading' (duration: 580.732479ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:05.479154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.800663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-10T10:02:05.47947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.89778Z","time spent":"581.671779ms","remote":"127.0.0.1:40906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-06-10T10:02:05.479591Z","caller":"traceutil/trace.go:171","msg":"trace[304019393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22; }","duration":"199.201518ms","start":"2026-06-10T10:02:05.28028Z","end":"2026-06-10T10:02:05.479482Z","steps":["trace[304019393] 'agreement among raft nodes before linearized reading' (duration: 198.107618ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:05.479619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.847262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-10T10:02:05.479655Z","caller":"traceutil/trace.go:171","msg":"trace[2047774954] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:22; }","duration":"436.928043ms","start":"2026-06-10T10:02:05.042711Z","end":"2026-06-10T10:02:05.479639Z","steps":["trace[2047774954] 'agreement among raft nodes before linearized reading' (duration: 435.808012ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:05.479681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:05.04268Z","time spent":"436.993983ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-06-10T10:02:05.479888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.372426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-06-10T10:02:05.479916Z","caller":"traceutil/trace.go:171","msg":"trace[682353166] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:22; }","duration":"573.467866ms","start":"2026-06-10T10:02:04.90644Z","end":"2026-06-10T10:02:05.479908Z","steps":["trace[682353166] 'agreement among raft nodes before linearized reading' (duration: 572.091733ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:05.479936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:04.90642Z","time spent":"573.510087ms","remote":"127.0.0.1:40906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-06-10T10:02:05.48015Z","caller":"traceutil/trace.go:171","msg":"trace[747693217] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"125.223439ms","start":"2026-06-10T10:02:05.354915Z","end":"2026-06-10T10:02:05.480139Z","steps":["trace[747693217] 'process raft request' (duration: 123.472663ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:05.480152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.226458ms","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-06-10T10:02:05.480233Z","caller":"traceutil/trace.go:171","msg":"trace[451596802] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:22; }","duration":"240.311278ms","start":"2026-06-10T10:02:05.239901Z","end":"2026-06-10T10:02:05.480212Z","steps":["trace[451596802] 'agreement among raft nodes before linearized reading' (duration: 238.455452ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:05.48363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.694667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-10T10:02:05.483688Z","caller":"traceutil/trace.go:171","msg":"trace[782091429] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:25; }","duration":"130.819788ms","start":"2026-06-10T10:02:05.352853Z","end":"2026-06-10T10:02:05.483673Z","steps":["trace[782091429] 'agreement among raft nodes before linearized reading' (duration: 130.662006ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:05.483967Z","caller":"traceutil/trace.go:171","msg":"trace[2085489196] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"128.918781ms","start":"2026-06-10T10:02:05.355036Z","end":"2026-06-10T10:02:05.483955Z","steps":["trace[2085489196] 'process raft request' (duration: 128.348116ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:05.484169Z","caller":"traceutil/trace.go:171","msg":"trace[79888917] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"128.76849ms","start":"2026-06-10T10:02:05.355389Z","end":"2026-06-10T10:02:05.484158Z","steps":["trace[79888917] 'process raft request' (duration: 128.081674ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:05.48471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.70241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-06-10T10:02:05.484848Z","caller":"traceutil/trace.go:171","msg":"trace[1587881252] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:26; }","duration":"135.864051ms","start":"2026-06-10T10:02:05.348969Z","end":"2026-06-10T10:02:05.484833Z","steps":["trace[1587881252] 'agreement among raft nodes before linearized reading' (duration: 135.582279ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:06.888835Z","caller":"traceutil/trace.go:171","msg":"trace[701158832] transaction","detail":"{read_only:false; response_revision:203; number_of_response:1; }","duration":"121.220518ms","start":"2026-06-10T10:02:06.767581Z","end":"2026-06-10T10:02:06.888802Z","steps":["trace[701158832] 'process raft request' (duration: 69.602037ms)","trace[701158832] 'compare' (duration: 51.328778ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:02:06.888995Z","caller":"traceutil/trace.go:171","msg":"trace[1690986404] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"106.377478ms","start":"2026-06-10T10:02:06.782591Z","end":"2026-06-10T10:02:06.888968Z","steps":["trace[1690986404] 'process raft request' (duration: 106.204406ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:08.525153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.811922ms","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-06-10T10:02:08.525257Z","caller":"traceutil/trace.go:171","msg":"trace[1523324079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:227; }","duration":"140.984824ms","start":"2026-06-10T10:02:08.38425Z","end":"2026-06-10T10:02:08.525235Z","steps":["trace[1523324079] 'range keys from in-memory index tree' (duration: 140.602521ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:08.525371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.181206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-10T10:02:08.525288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.263859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3709"} {"level":"info","ts":"2026-06-10T10:02:08.525469Z","caller":"traceutil/trace.go:171","msg":"trace[834227390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:227; }","duration":"141.334418ms","start":"2026-06-10T10:02:08.384114Z","end":"2026-06-10T10:02:08.525448Z","steps":["trace[834227390] 'range keys from in-memory index tree' (duration: 141.043805ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:08.525523Z","caller":"traceutil/trace.go:171","msg":"trace[1933869334] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:227; }","duration":"528.594331ms","start":"2026-06-10T10:02:07.996908Z","end":"2026-06-10T10:02:08.525502Z","steps":["trace[1933869334] 'range keys from in-memory index tree' (duration: 528.182747ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:08.525622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:07.99689Z","time spent":"528.716072ms","remote":"127.0.0.1:40974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3731,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-06-10T10:02:08.525372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.648179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-06-10T10:02:08.525753Z","caller":"traceutil/trace.go:171","msg":"trace[942862443] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:227; }","duration":"882.070382ms","start":"2026-06-10T10:02:07.64366Z","end":"2026-06-10T10:02:08.525731Z","steps":["trace[942862443] 'range keys from in-memory index tree' (duration: 881.510457ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:08.525837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:07.643646Z","time spent":"882.177333ms","remote":"127.0.0.1:40906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-10T10:02:09.042349Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6363760756442546725,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-10T10:02:09.159785Z","caller":"traceutil/trace.go:171","msg":"trace[1120255215] linearizableReadLoop","detail":"{readStateIndex:237; appliedIndex:234; }","duration":"618.05747ms","start":"2026-06-10T10:02:08.541698Z","end":"2026-06-10T10:02:09.159756Z","steps":["trace[1120255215] 'read index received' (duration: 612.766375ms)","trace[1120255215] 'applied index is now lower than readState.Index' (duration: 5.289315ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:02:09.159868Z","caller":"traceutil/trace.go:171","msg":"trace[1193544135] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"626.631985ms","start":"2026-06-10T10:02:08.533198Z","end":"2026-06-10T10:02:09.15983Z","steps":["trace[1193544135] 'process raft request' (duration: 626.295772ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:09.160037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:08.533176Z","time spent":"626.777016ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T10:02:09.160101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.393264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T10:02:09.160144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.304177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T10:02:09.160179Z","caller":"traceutil/trace.go:171","msg":"trace[40867591] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:0; response_revision:231; }","duration":"618.518044ms","start":"2026-06-10T10:02:08.541639Z","end":"2026-06-10T10:02:09.160157Z","steps":["trace[40867591] 'agreement among raft nodes before linearized reading' (duration: 618.400523ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:09.160207Z","caller":"traceutil/trace.go:171","msg":"trace[1200189226] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:231; }","duration":"478.423869ms","start":"2026-06-10T10:02:08.681766Z","end":"2026-06-10T10:02:09.16019Z","steps":["trace[1200189226] 'agreement among raft nodes before linearized reading' (duration: 478.311698ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:09.160221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:08.54162Z","time spent":"618.593135ms","remote":"127.0.0.1:41002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-06-10T10:02:09.160253Z","caller":"traceutil/trace.go:171","msg":"trace[1501266492] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"628.168369ms","start":"2026-06-10T10:02:08.532072Z","end":"2026-06-10T10:02:09.160241Z","steps":["trace[1501266492] 'process raft request' (duration: 622.437219ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:09.160259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:08.681745Z","time spent":"478.50106ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-10T10:02:09.160317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:08.532061Z","time spent":"628.221589ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T10:02:09.160483Z","caller":"traceutil/trace.go:171","msg":"trace[384315197] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"627.16414ms","start":"2026-06-10T10:02:08.533296Z","end":"2026-06-10T10:02:09.160461Z","steps":["trace[384315197] 'process raft request' (duration: 626.391313ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:09.160517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.071607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3468"} {"level":"info","ts":"2026-06-10T10:02:09.160555Z","caller":"traceutil/trace.go:171","msg":"trace[1628777688] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:231; }","duration":"449.169139ms","start":"2026-06-10T10:02:08.711374Z","end":"2026-06-10T10:02:09.160543Z","steps":["trace[1628777688] 'agreement among raft nodes before linearized reading' (duration: 449.062738ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:09.160583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:08.711331Z","time spent":"449.245669ms","remote":"127.0.0.1:40984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3490,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-06-10T10:02:09.160612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:08.533279Z","time spent":"627.260511ms","remote":"127.0.0.1:40974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4003,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T10:02:09.874092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.607739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T10:02:09.874248Z","caller":"traceutil/trace.go:171","msg":"trace[1999613315] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"707.315701ms","start":"2026-06-10T10:02:09.166906Z","end":"2026-06-10T10:02:09.874222Z","steps":["trace[1999613315] 'process raft request' (duration: 217.44066ms)","trace[1999613315] 'compare' (duration: 489.426207ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:02:09.874331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:09.16689Z","time spent":"707.409582ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T10:02:09.87663Z","caller":"traceutil/trace.go:171","msg":"trace[1819471831] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"709.646761ms","start":"2026-06-10T10:02:09.166948Z","end":"2026-06-10T10:02:09.876595Z","steps":["trace[1819471831] 'process raft request' (duration: 709.432769ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:09.876792Z","caller":"traceutil/trace.go:171","msg":"trace[1877104156] linearizableReadLoop","detail":"{readStateIndex:239; appliedIndex:237; }","duration":"709.683162ms","start":"2026-06-10T10:02:09.167073Z","end":"2026-06-10T10:02:09.876756Z","steps":["trace[1877104156] 'read index received' (duration: 217.292679ms)","trace[1877104156] 'applied index is now lower than readState.Index' (duration: 492.388713ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:02:09.876825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:09.166927Z","time spent":"709.816272ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T10:02:09.87693Z","caller":"traceutil/trace.go:171","msg":"trace[1083634449] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"707.235551ms","start":"2026-06-10T10:02:09.16967Z","end":"2026-06-10T10:02:09.876906Z","steps":["trace[1083634449] 'process raft request' (duration: 706.848358ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:09.877Z","caller":"traceutil/trace.go:171","msg":"trace[602001409] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"706.714556ms","start":"2026-06-10T10:02:09.170222Z","end":"2026-06-10T10:02:09.876937Z","steps":["trace[602001409] 'process raft request' (duration: 706.366413ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:09.87709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.230396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-06-10T10:02:09.877164Z","caller":"traceutil/trace.go:171","msg":"trace[1061588996] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:236; }","duration":"710.355287ms","start":"2026-06-10T10:02:09.166788Z","end":"2026-06-10T10:02:09.877143Z","steps":["trace[1061588996] 'agreement among raft nodes before linearized reading' (duration: 710.187805ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:09.877205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:09.166772Z","time spent":"710.425258ms","remote":"127.0.0.1:40906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-10T10:02:09.877213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:09.169661Z","time spent":"707.396652ms","remote":"127.0.0.1:40888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":677,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T10:02:09.87732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:09.170214Z","time spent":"706.887247ms","remote":"127.0.0.1:40984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4649,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T10:02:09.876968Z","caller":"traceutil/trace.go:171","msg":"trace[179581800] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"497.033442ms","start":"2026-06-10T10:02:09.379924Z","end":"2026-06-10T10:02:09.876958Z","steps":["trace[179581800] 'process raft request' (duration: 496.76666ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:09.877564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:09.379892Z","time spent":"497.638948ms","remote":"127.0.0.1:40872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T10:02:12.319878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.685676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-06-10T10:02:12.319971Z","caller":"traceutil/trace.go:171","msg":"trace[872398043] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:345; }","duration":"138.827547ms","start":"2026-06-10T10:02:12.181126Z","end":"2026-06-10T10:02:12.319953Z","steps":["trace[872398043] 'range keys from in-memory index tree' (duration: 138.587645ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:12.832638Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6363760756442546955,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T10:02:13.333622Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6363760756442546955,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T10:02:13.833728Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6363760756442546955,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T10:02:14.270105Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.94403331s","expected-duration":"1s"} {"level":"info","ts":"2026-06-10T10:02:14.270549Z","caller":"traceutil/trace.go:171","msg":"trace[1357220653] linearizableReadLoop","detail":"{readStateIndex:356; appliedIndex:355; }","duration":"1.938732745s","start":"2026-06-10T10:02:12.331792Z","end":"2026-06-10T10:02:14.270525Z","steps":["trace[1357220653] 'read index received' (duration: 1.938599964s)","trace[1357220653] 'applied index is now lower than readState.Index' (duration: 131.651µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:02:14.27069Z","caller":"traceutil/trace.go:171","msg":"trace[150951529] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"1.944696255s","start":"2026-06-10T10:02:12.325959Z","end":"2026-06-10T10:02:14.270655Z","steps":["trace[150951529] 'process raft request' (duration: 1.944435993s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:14.270818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.890119896s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-06-10T10:02:14.270839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:12.32594Z","time spent":"1.944832966s","remote":"127.0.0.1:40960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T10:02:14.270869Z","caller":"traceutil/trace.go:171","msg":"trace[1628872415] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:346; }","duration":"1.890212268s","start":"2026-06-10T10:02:12.380642Z","end":"2026-06-10T10:02:14.270854Z","steps":["trace[1628872415] 'agreement among raft nodes before linearized reading' (duration: 1.890045586s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:14.270916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:12.380628Z","time spent":"1.890279598s","remote":"127.0.0.1:41002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":256,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-06-10T10:02:14.270932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.939216548s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-06-10T10:02:14.271041Z","caller":"traceutil/trace.go:171","msg":"trace[609548057] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:346; }","duration":"1.93936168s","start":"2026-06-10T10:02:12.33166Z","end":"2026-06-10T10:02:14.271022Z","steps":["trace[609548057] 'agreement among raft nodes before linearized reading' (duration: 1.939034177s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:14.271147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:12.331635Z","time spent":"1.939481301s","remote":"127.0.0.1:41002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-06-10T10:02:14.271197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.639684999s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-06-10T10:02:14.271238Z","caller":"traceutil/trace.go:171","msg":"trace[888508072] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:346; }","duration":"1.63974047s","start":"2026-06-10T10:02:12.631487Z","end":"2026-06-10T10:02:14.271228Z","steps":["trace[888508072] 'agreement among raft nodes before linearized reading' (duration: 1.639684079s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:14.271272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:12.631476Z","time spent":"1.63978974s","remote":"127.0.0.1:41002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-06-10T10:02:14.271342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.096422778s","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-06-10T10:02:14.271407Z","caller":"traceutil/trace.go:171","msg":"trace[553473688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:346; }","duration":"1.096532219s","start":"2026-06-10T10:02:13.174858Z","end":"2026-06-10T10:02:14.27139Z","steps":["trace[553473688] 'agreement among raft nodes before linearized reading' (duration: 1.096375338s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:14.271425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.690482156s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-06-10T10:02:14.271446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:13.174828Z","time spent":"1.096610289s","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-10T10:02:14.27146Z","caller":"traceutil/trace.go:171","msg":"trace[2104276565] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:346; }","duration":"1.690536357s","start":"2026-06-10T10:02:12.580914Z","end":"2026-06-10T10:02:14.27145Z","steps":["trace[2104276565] 'agreement among raft nodes before linearized reading' (duration: 1.690471057s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:14.271515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:12.580901Z","time spent":"1.690583188s","remote":"127.0.0.1:41002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-06-10T10:02:14.271151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.617288481s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T10:02:14.271636Z","caller":"traceutil/trace.go:171","msg":"trace[367887692] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:346; }","duration":"1.617783955s","start":"2026-06-10T10:02:12.653842Z","end":"2026-06-10T10:02:14.271626Z","steps":["trace[367887692] 'agreement among raft nodes before linearized reading' (duration: 1.617280141s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:14.271667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:12.653811Z","time spent":"1.617849335s","remote":"127.0.0.1:40804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-10T10:02:14.271681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.207512ms","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-06-10T10:02:14.271721Z","caller":"traceutil/trace.go:171","msg":"trace[855294101] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:346; }","duration":"371.309883ms","start":"2026-06-10T10:02:13.9004Z","end":"2026-06-10T10:02:14.27171Z","steps":["trace[855294101] 'agreement among raft nodes before linearized reading' (duration: 371.231603ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:14.271713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.841048993s","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-06-10T10:02:14.270927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.589141355s","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-06-10T10:02:14.271796Z","caller":"traceutil/trace.go:171","msg":"trace[322474797] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:346; }","duration":"1.590055222s","start":"2026-06-10T10:02:12.681729Z","end":"2026-06-10T10:02:14.271784Z","steps":["trace[322474797] 'agreement among raft nodes before linearized reading' (duration: 1.588997794s)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:14.271806Z","caller":"traceutil/trace.go:171","msg":"trace[703459586] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:346; }","duration":"1.841173574s","start":"2026-06-10T10:02:12.430607Z","end":"2026-06-10T10:02:14.271781Z","steps":["trace[703459586] 'agreement among raft nodes before linearized reading' (duration: 1.840984053s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:14.27182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.740529628s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-06-10T10:02:14.271855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:12.430592Z","time spent":"1.841254215s","remote":"127.0.0.1:41002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"info","ts":"2026-06-10T10:02:14.271892Z","caller":"traceutil/trace.go:171","msg":"trace[1330772226] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:346; }","duration":"1.740623289s","start":"2026-06-10T10:02:12.53125Z","end":"2026-06-10T10:02:14.271873Z","steps":["trace[1330772226] 'agreement among raft nodes before linearized reading' (duration: 1.740479968s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:14.271935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:12.531238Z","time spent":"1.740687229s","remote":"127.0.0.1:41002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-06-10T10:02:14.271977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098159734s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-10T10:02:14.271755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:13.900382Z","time spent":"371.366674ms","remote":"127.0.0.1:41064","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-06-10T10:02:14.271801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.306329ms","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-06-10T10:02:14.272014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.791534897s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-06-10T10:02:14.272017Z","caller":"traceutil/trace.go:171","msg":"trace[1288810646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:346; }","duration":"1.098249954s","start":"2026-06-10T10:02:13.173758Z","end":"2026-06-10T10:02:14.272008Z","steps":["trace[1288810646] 'agreement among raft nodes before linearized reading' (duration: 1.098190553s)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:14.272035Z","caller":"traceutil/trace.go:171","msg":"trace[602290187] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:346; }","duration":"380.571191ms","start":"2026-06-10T10:02:13.891452Z","end":"2026-06-10T10:02:14.272023Z","steps":["trace[602290187] 'agreement among raft nodes before linearized reading' (duration: 380.321539ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:14.272053Z","caller":"traceutil/trace.go:171","msg":"trace[597663493] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:346; }","duration":"1.791594687s","start":"2026-06-10T10:02:12.480447Z","end":"2026-06-10T10:02:14.272042Z","steps":["trace[597663493] 'agreement among raft nodes before linearized reading' (duration: 1.791533157s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:14.272055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:13.173729Z","time spent":"1.098320314s","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-10T10:02:14.272066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:13.891433Z","time spent":"380.624082ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-10T10:02:14.272082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:12.480405Z","time spent":"1.791669797s","remote":"127.0.0.1:41002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-06-10T10:02:14.271841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:12.681683Z","time spent":"1.590147922s","remote":"127.0.0.1:41002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-06-10T10:02:14.272129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.540627677s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-06-10T10:02:14.272235Z","caller":"traceutil/trace.go:171","msg":"trace[390232127] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:346; }","duration":"1.540773897s","start":"2026-06-10T10:02:12.731435Z","end":"2026-06-10T10:02:14.272209Z","steps":["trace[390232127] 'agreement among raft nodes before linearized reading' (duration: 1.540583596s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:14.272289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:12.731412Z","time spent":"1.540867059s","remote":"127.0.0.1:41002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":240,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"info","ts":"2026-06-10T10:02:14.68013Z","caller":"traceutil/trace.go:171","msg":"trace[82537218] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"373.453337ms","start":"2026-06-10T10:02:14.306647Z","end":"2026-06-10T10:02:14.6801Z","steps":["trace[82537218] 'process raft request' (duration: 353.38946ms)","trace[82537218] 'compare' (duration: 19.756834ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:02:14.680301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:14.306627Z","time spent":"373.576787ms","remote":"127.0.0.1:40890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T10:02:14.681429Z","caller":"traceutil/trace.go:171","msg":"trace[1688060505] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"374.591026ms","start":"2026-06-10T10:02:14.30681Z","end":"2026-06-10T10:02:14.681401Z","steps":["trace[1688060505] 'process raft request' (duration: 374.443765ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:14.68161Z","caller":"traceutil/trace.go:171","msg":"trace[1884816112] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"374.501686ms","start":"2026-06-10T10:02:14.307093Z","end":"2026-06-10T10:02:14.681594Z","steps":["trace[1884816112] 'process raft request' (duration: 374.293304ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:14.681605Z","caller":"traceutil/trace.go:171","msg":"trace[1272045156] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"374.437204ms","start":"2026-06-10T10:02:14.307148Z","end":"2026-06-10T10:02:14.681585Z","steps":["trace[1272045156] 'process raft request' (duration: 374.267503ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:14.681638Z","caller":"traceutil/trace.go:171","msg":"trace[1771654924] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"374.639097ms","start":"2026-06-10T10:02:14.306979Z","end":"2026-06-10T10:02:14.681618Z","steps":["trace[1771654924] 'process raft request' (duration: 374.366655ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:14.681634Z","caller":"traceutil/trace.go:171","msg":"trace[1491386906] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"372.347077ms","start":"2026-06-10T10:02:14.309267Z","end":"2026-06-10T10:02:14.681614Z","steps":["trace[1491386906] 'process raft request' (duration: 372.203696ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:14.681711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:14.307136Z","time spent":"374.516355ms","remote":"127.0.0.1:40890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2163,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T10:02:14.681718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:14.306971Z","time spent":"374.703178ms","remote":"127.0.0.1:41002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":172,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T10:02:14.681729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:14.307079Z","time spent":"374.563516ms","remote":"127.0.0.1:40984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3378,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T10:02:14.681837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:14.309259Z","time spent":"372.48602ms","remote":"127.0.0.1:41048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2267,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T10:02:14.68183Z","caller":"traceutil/trace.go:171","msg":"trace[1489671882] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"372.420088ms","start":"2026-06-10T10:02:14.309392Z","end":"2026-06-10T10:02:14.681812Z","steps":["trace[1489671882] 'process raft request' (duration: 372.109795ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:14.681924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:14.309382Z","time spent":"372.4994ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T10:02:14.682268Z","caller":"traceutil/trace.go:171","msg":"trace[2039610958] linearizableReadLoop","detail":"{readStateIndex:375; appliedIndex:366; }","duration":"323.22886ms","start":"2026-06-10T10:02:14.35902Z","end":"2026-06-10T10:02:14.682248Z","steps":["trace[2039610958] 'read index received' (duration: 301.004815ms)","trace[2039610958] 'applied index is now lower than readState.Index' (duration: 22.223215ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:02:14.682532Z","caller":"traceutil/trace.go:171","msg":"trace[1084077374] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"373.109474ms","start":"2026-06-10T10:02:14.309406Z","end":"2026-06-10T10:02:14.682516Z","steps":["trace[1084077374] 'process raft request' (duration: 372.304807ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:14.682799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.536674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T10:02:14.68288Z","caller":"traceutil/trace.go:171","msg":"trace[804026471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:365; }","duration":"137.618345ms","start":"2026-06-10T10:02:14.545245Z","end":"2026-06-10T10:02:14.682863Z","steps":["trace[804026471] 'agreement among raft nodes before linearized reading' (duration: 137.502614ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:14.683028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.002216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-06-10T10:02:14.683081Z","caller":"traceutil/trace.go:171","msg":"trace[333585493] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:365; }","duration":"324.081936ms","start":"2026-06-10T10:02:14.358987Z","end":"2026-06-10T10:02:14.683069Z","steps":["trace[333585493] 'agreement among raft nodes before linearized reading' (duration: 323.991586ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:14.683113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:14.358974Z","time spent":"324.131288ms","remote":"127.0.0.1:40984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7325,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-06-10T10:02:14.683325Z","caller":"traceutil/trace.go:171","msg":"trace[329490403] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"372.903213ms","start":"2026-06-10T10:02:14.309451Z","end":"2026-06-10T10:02:14.682354Z","steps":["trace[329490403] 'process raft request' (duration: 372.723561ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:14.683381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:14.306797Z","time spent":"374.712307ms","remote":"127.0.0.1:41272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T10:02:14.683412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:14.309442Z","time spent":"373.928061ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T10:02:14.683826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:14.309399Z","time spent":"373.207925ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T10:02:24.530164Z","caller":"traceutil/trace.go:171","msg":"trace[661010972] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"123.871654ms","start":"2026-06-10T10:02:24.406248Z","end":"2026-06-10T10:02:24.53012Z","steps":["trace[661010972] 'process raft request' (duration: 56.003695ms)","trace[661010972] 'compare' (duration: 67.521107ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:02:24.530148Z","caller":"traceutil/trace.go:171","msg":"trace[1123117998] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"123.771133ms","start":"2026-06-10T10:02:24.40634Z","end":"2026-06-10T10:02:24.530111Z","steps":["trace[1123117998] 'process raft request' (duration: 123.668503ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:36.684616Z","caller":"traceutil/trace.go:171","msg":"trace[447536484] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:500; }","duration":"104.907949ms","start":"2026-06-10T10:02:36.57969Z","end":"2026-06-10T10:02:36.684598Z","steps":["trace[447536484] 'read index received' (duration: 104.032934ms)","trace[447536484] 'applied index is now lower than readState.Index' (duration: 874.185µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:02:36.68475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.328174ms","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-06-10T10:02:36.684783Z","caller":"traceutil/trace.go:171","msg":"trace[107800704] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:485; }","duration":"100.400897ms","start":"2026-06-10T10:02:36.584373Z","end":"2026-06-10T10:02:36.684773Z","steps":["trace[107800704] 'agreement among raft nodes before linearized reading' (duration: 100.330024ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:36.68475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.054784ms","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-06-10T10:02:36.684862Z","caller":"traceutil/trace.go:171","msg":"trace[269097728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:485; }","duration":"105.211371ms","start":"2026-06-10T10:02:36.57964Z","end":"2026-06-10T10:02:36.684851Z","steps":["trace[269097728] 'agreement among raft nodes before linearized reading' (duration: 105.051444ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:42.639821Z","caller":"traceutil/trace.go:171","msg":"trace[596332971] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"491.75738ms","start":"2026-06-10T10:02:42.148039Z","end":"2026-06-10T10:02:42.639796Z","steps":["trace[596332971] 'process raft request' (duration: 491.539262ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:42.639968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:42.148018Z","time spent":"491.871265ms","remote":"127.0.0.1:40862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T10:02:42.661788Z","caller":"traceutil/trace.go:171","msg":"trace[282595528] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"513.302569ms","start":"2026-06-10T10:02:42.148437Z","end":"2026-06-10T10:02:42.661739Z","steps":["trace[282595528] 'process raft request' (duration: 513.173185ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:42.661959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:42.148431Z","time spent":"513.447836ms","remote":"127.0.0.1:40862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T10:02:42.66207Z","caller":"traceutil/trace.go:171","msg":"trace[1214042439] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"513.653354ms","start":"2026-06-10T10:02:42.148368Z","end":"2026-06-10T10:02:42.662021Z","steps":["trace[1214042439] 'process raft request' (duration: 513.119763ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:42.662288Z","caller":"traceutil/trace.go:171","msg":"trace[301176612] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"496.550761ms","start":"2026-06-10T10:02:42.165557Z","end":"2026-06-10T10:02:42.662108Z","steps":["trace[301176612] 'process raft request' (duration: 496.206157ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:42.662298Z","caller":"traceutil/trace.go:171","msg":"trace[1820992191] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"441.73589ms","start":"2026-06-10T10:02:42.220538Z","end":"2026-06-10T10:02:42.662274Z","steps":["trace[1820992191] 'process raft request' (duration: 441.475761ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:42.662342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:42.148359Z","time spent":"513.811949ms","remote":"127.0.0.1:40862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T10:02:42.662332Z","caller":"traceutil/trace.go:171","msg":"trace[159795126] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:550; }","duration":"209.081687ms","start":"2026-06-10T10:02:42.453198Z","end":"2026-06-10T10:02:42.66228Z","steps":["trace[159795126] 'read index received' (duration: 186.991247ms)","trace[159795126] 'applied index is now lower than readState.Index' (duration: 22.08934ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:02:42.662411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:42.2205Z","time spent":"441.874046ms","remote":"127.0.0.1:40862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T10:02:42.662422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:42.165454Z","time spent":"496.918505ms","remote":"127.0.0.1:40862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T10:02:42.662432Z","caller":"traceutil/trace.go:171","msg":"trace[737704998] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"349.441973ms","start":"2026-06-10T10:02:42.312979Z","end":"2026-06-10T10:02:42.662421Z","steps":["trace[737704998] 'process raft request' (duration: 349.134091ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:42.662481Z","caller":"traceutil/trace.go:171","msg":"trace[676844157] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"482.15764ms","start":"2026-06-10T10:02:42.180027Z","end":"2026-06-10T10:02:42.662185Z","steps":["trace[676844157] 'process raft request' (duration: 481.809836ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:42.662509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:42.312944Z","time spent":"349.544976ms","remote":"127.0.0.1:40862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T10:02:42.662533Z","caller":"traceutil/trace.go:171","msg":"trace[318076466] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"496.623964ms","start":"2026-06-10T10:02:42.165566Z","end":"2026-06-10T10:02:42.66219Z","steps":["trace[318076466] 'process raft request' (duration: 496.228918ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:42.662572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:42.180008Z","time spent":"482.520034ms","remote":"127.0.0.1:40862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T10:02:42.662599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.388219ms","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-06-10T10:02:42.662612Z","caller":"traceutil/trace.go:171","msg":"trace[676707040] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"478.288804ms","start":"2026-06-10T10:02:42.183924Z","end":"2026-06-10T10:02:42.662212Z","steps":["trace[676707040] 'process raft request' (duration: 477.964422ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:42.662583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:42.165548Z","time spent":"497.012068ms","remote":"127.0.0.1:40862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T10:02:42.662636Z","caller":"traceutil/trace.go:171","msg":"trace[1111974589] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:544; }","duration":"209.477412ms","start":"2026-06-10T10:02:42.453147Z","end":"2026-06-10T10:02:42.662624Z","steps":["trace[1111974589] 'agreement among raft nodes before linearized reading' (duration: 209.302206ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:42.662651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:42.183907Z","time spent":"478.727261ms","remote":"127.0.0.1:40862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T10:02:42.66272Z","caller":"traceutil/trace.go:171","msg":"trace[2055949285] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"496.842092ms","start":"2026-06-10T10:02:42.165391Z","end":"2026-06-10T10:02:42.662233Z","steps":["trace[2055949285] 'process raft request' (duration: 496.29176ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:42.662761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:42.165372Z","time spent":"497.366422ms","remote":"127.0.0.1:40862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T10:02:42.662817Z","caller":"traceutil/trace.go:171","msg":"trace[1091815823] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"502.342759ms","start":"2026-06-10T10:02:42.159895Z","end":"2026-06-10T10:02:42.662238Z","steps":["trace[1091815823] 'process raft request' (duration: 501.752846ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:42.662865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:42.159878Z","time spent":"502.964471ms","remote":"127.0.0.1:40862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T10:02:42.66294Z","caller":"traceutil/trace.go:171","msg":"trace[461641872] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"496.722977ms","start":"2026-06-10T10:02:42.165526Z","end":"2026-06-10T10:02:42.662249Z","steps":["trace[461641872] 'process raft request' (duration: 496.198317ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:42.663074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:42.165516Z","time spent":"497.492346ms","remote":"127.0.0.1:40862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T10:02:42.675959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.877674ms","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-06-10T10:02:42.676024Z","caller":"traceutil/trace.go:171","msg":"trace[1461531975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:546; }","duration":"161.984788ms","start":"2026-06-10T10:02:42.514024Z","end":"2026-06-10T10:02:42.676009Z","steps":["trace[1461531975] 'agreement among raft nodes before linearized reading' (duration: 161.827061ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:43.455368Z","caller":"traceutil/trace.go:171","msg":"trace[774832266] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"299.700979ms","start":"2026-06-10T10:02:43.155632Z","end":"2026-06-10T10:02:43.455333Z","steps":["trace[774832266] 'process raft request' (duration: 259.780165ms)","trace[774832266] 'compare' (duration: 39.773549ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:02:43.758763Z","caller":"traceutil/trace.go:171","msg":"trace[243862477] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"587.484975ms","start":"2026-06-10T10:02:43.171242Z","end":"2026-06-10T10:02:43.758727Z","steps":["trace[243862477] 'process raft request' (duration: 506.198754ms)","trace[243862477] 'compare' (duration: 81.033572ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:02:43.758914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:43.171223Z","time spent":"587.60762ms","remote":"127.0.0.1:40862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65412,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T10:02:43.758935Z","caller":"traceutil/trace.go:171","msg":"trace[799502104] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:615; }","duration":"559.808236ms","start":"2026-06-10T10:02:43.199065Z","end":"2026-06-10T10:02:43.758874Z","steps":["trace[799502104] 'read index received' (duration: 216.407454ms)","trace[799502104] 'applied index is now lower than readState.Index' (duration: 343.399692ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:02:43.759003Z","caller":"traceutil/trace.go:171","msg":"trace[636958867] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"451.372326ms","start":"2026-06-10T10:02:43.307597Z","end":"2026-06-10T10:02:43.758969Z","steps":["trace[636958867] 'process raft request' (duration: 451.114826ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:43.759105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:43.307571Z","time spent":"451.49098ms","remote":"127.0.0.1:40862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T10:02:43.759114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.025034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T10:02:43.759151Z","caller":"traceutil/trace.go:171","msg":"trace[400382054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"560.098317ms","start":"2026-06-10T10:02:43.19904Z","end":"2026-06-10T10:02:43.759139Z","steps":["trace[400382054] 'agreement among raft nodes before linearized reading' (duration: 559.969462ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:43.759193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:43.199028Z","time spent":"560.15661ms","remote":"127.0.0.1:40804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-10T10:02:43.761467Z","caller":"traceutil/trace.go:171","msg":"trace[1221022246] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"259.675851ms","start":"2026-06-10T10:02:43.501781Z","end":"2026-06-10T10:02:43.761456Z","steps":["trace[1221022246] 'process raft request' (duration: 259.248676ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:44.096367Z","caller":"traceutil/trace.go:171","msg":"trace[1870103739] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"149.121266ms","start":"2026-06-10T10:02:43.947209Z","end":"2026-06-10T10:02:44.09633Z","steps":["trace[1870103739] 'process raft request' (duration: 84.927795ms)","trace[1870103739] 'compare' (duration: 61.083045ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:02:44.151814Z","caller":"traceutil/trace.go:171","msg":"trace[2101279095] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"147.001293ms","start":"2026-06-10T10:02:44.004792Z","end":"2026-06-10T10:02:44.151794Z","steps":["trace[2101279095] 'process raft request' (duration: 146.89757ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:44.75887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.510985ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T10:02:44.758982Z","caller":"traceutil/trace.go:171","msg":"trace[49943749] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"571.181687ms","start":"2026-06-10T10:02:44.187782Z","end":"2026-06-10T10:02:44.758963Z","steps":["trace[49943749] 'read index received' (duration: 84.347071ms)","trace[49943749] 'applied index is now lower than readState.Index' (duration: 486.833026ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:02:44.75939Z","caller":"traceutil/trace.go:171","msg":"trace[166878133] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"607.621596ms","start":"2026-06-10T10:02:44.151704Z","end":"2026-06-10T10:02:44.759325Z","steps":["trace[166878133] 'process raft request' (duration: 120.51984ms)","trace[166878133] 'compare' (duration: 486.405481ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:02:44.759628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:44.151684Z","time spent":"607.784993ms","remote":"127.0.0.1:40862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T10:02:44.759654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.858742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-06-10T10:02:44.759711Z","caller":"traceutil/trace.go:171","msg":"trace[1691203840] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:607; }","duration":"571.939635ms","start":"2026-06-10T10:02:44.187753Z","end":"2026-06-10T10:02:44.759693Z","steps":["trace[1691203840] 'agreement among raft nodes before linearized reading' (duration: 571.669745ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:44.759748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:44.18774Z","time spent":"571.999676ms","remote":"127.0.0.1:40862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":29592,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-06-10T10:02:45.260021Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6363760756442547397,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T10:02:45.492247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.432312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T10:02:45.492469Z","caller":"traceutil/trace.go:171","msg":"trace[1171347014] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"1.187393693s","start":"2026-06-10T10:02:44.305042Z","end":"2026-06-10T10:02:45.492436Z","steps":["trace[1171347014] 'process raft request' (duration: 990.700142ms)","trace[1171347014] 'compare' (duration: 195.617172ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:02:45.492554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:44.305022Z","time spent":"1.187491597s","remote":"127.0.0.1:40862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T10:02:45.493114Z","caller":"traceutil/trace.go:171","msg":"trace[1964789654] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:628; }","duration":"734.092029ms","start":"2026-06-10T10:02:44.75901Z","end":"2026-06-10T10:02:45.493102Z","steps":["trace[1964789654] 'read index received' (duration: 536.741034ms)","trace[1964789654] 'applied index is now lower than readState.Index' (duration: 197.350025ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:02:45.493208Z","caller":"traceutil/trace.go:171","msg":"trace[1602032969] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"669.277907ms","start":"2026-06-10T10:02:44.823922Z","end":"2026-06-10T10:02:45.4932Z","steps":["trace[1602032969] 'process raft request' (duration: 668.977196ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:45.493387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.258324ms","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-06-10T10:02:45.493435Z","caller":"traceutil/trace.go:171","msg":"trace[376893248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:609; }","duration":"799.322957ms","start":"2026-06-10T10:02:44.694097Z","end":"2026-06-10T10:02:45.49342Z","steps":["trace[376893248] 'agreement among raft nodes before linearized reading' (duration: 799.209382ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:45.493467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:44.694083Z","time spent":"799.376879ms","remote":"127.0.0.1:41064","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-06-10T10:02:45.49352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.129147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-10T10:02:45.493568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.501478ms","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-06-10T10:02:45.493608Z","caller":"traceutil/trace.go:171","msg":"trace[568844879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:609; }","duration":"732.253311ms","start":"2026-06-10T10:02:44.761332Z","end":"2026-06-10T10:02:45.493586Z","steps":["trace[568844879] 'agreement among raft nodes before linearized reading' (duration: 731.986652ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:45.493651Z","caller":"traceutil/trace.go:171","msg":"trace[828967641] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:609; }","duration":"810.623102ms","start":"2026-06-10T10:02:44.683007Z","end":"2026-06-10T10:02:45.49363Z","steps":["trace[828967641] 'agreement among raft nodes before linearized reading' (duration: 810.452816ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:45.493666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:44.761316Z","time spent":"732.337464ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-10T10:02:45.493692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:44.682942Z","time spent":"810.741896ms","remote":"127.0.0.1:41064","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-06-10T10:02:45.493806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.865494ms","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-06-10T10:02:45.493842Z","caller":"traceutil/trace.go:171","msg":"trace[939623408] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:609; }","duration":"713.925616ms","start":"2026-06-10T10:02:44.779906Z","end":"2026-06-10T10:02:45.493831Z","steps":["trace[939623408] 'agreement among raft nodes before linearized reading' (duration: 713.806612ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:45.493861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.853194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T10:02:45.493873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:44.77989Z","time spent":"713.975759ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-10T10:02:45.493917Z","caller":"traceutil/trace.go:171","msg":"trace[2035187323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"293.938178ms","start":"2026-06-10T10:02:45.199966Z","end":"2026-06-10T10:02:45.493904Z","steps":["trace[2035187323] 'agreement among raft nodes before linearized reading' (duration: 293.862145ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:45.493817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.972637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10870"} {"level":"info","ts":"2026-06-10T10:02:45.493979Z","caller":"traceutil/trace.go:171","msg":"trace[1773387956] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:609; }","duration":"702.184485ms","start":"2026-06-10T10:02:44.791782Z","end":"2026-06-10T10:02:45.493967Z","steps":["trace[1773387956] 'agreement among raft nodes before linearized reading' (duration: 701.804672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:45.494013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:44.791768Z","time spent":"702.236318ms","remote":"127.0.0.1:40862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":10892,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-06-10T10:02:45.493265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:44.823894Z","time spent":"669.334779ms","remote":"127.0.0.1:40862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T10:02:45.496214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.900098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T10:02:45.496346Z","caller":"traceutil/trace.go:171","msg":"trace[769518154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"951.063853ms","start":"2026-06-10T10:02:44.545267Z","end":"2026-06-10T10:02:45.496331Z","steps":["trace[769518154] 'agreement among raft nodes before linearized reading' (duration: 948.952077ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:45.496428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:44.545249Z","time spent":"951.170917ms","remote":"127.0.0.1:40804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-10T10:02:49.957569Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6363760756442547476,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-10T10:02:50.073551Z","caller":"traceutil/trace.go:171","msg":"trace[152700830] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"616.378346ms","start":"2026-06-10T10:02:49.457148Z","end":"2026-06-10T10:02:50.073526Z","steps":["trace[152700830] 'read index received' (duration: 616.217471ms)","trace[152700830] 'applied index is now lower than readState.Index' (duration: 159.745µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:02:50.073689Z","caller":"traceutil/trace.go:171","msg":"trace[398106987] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"692.052271ms","start":"2026-06-10T10:02:49.381628Z","end":"2026-06-10T10:02:50.07368Z","steps":["trace[398106987] 'process raft request' (duration: 691.389539ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:50.073795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:49.381587Z","time spent":"692.129394ms","remote":"127.0.0.1:40888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T10:02:50.074303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.561789ms","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-06-10T10:02:50.074349Z","caller":"traceutil/trace.go:171","msg":"trace[266618584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"547.630752ms","start":"2026-06-10T10:02:49.526708Z","end":"2026-06-10T10:02:50.074338Z","steps":["trace[266618584] 'agreement among raft nodes before linearized reading' (duration: 547.488697ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:50.074319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.280406ms","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-06-10T10:02:50.074384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:49.526698Z","time spent":"547.678803ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-10T10:02:50.074422Z","caller":"traceutil/trace.go:171","msg":"trace[1698375897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:635; }","duration":"544.42908ms","start":"2026-06-10T10:02:49.52997Z","end":"2026-06-10T10:02:50.074399Z","steps":["trace[1698375897] 'agreement among raft nodes before linearized reading' (duration: 544.068638ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:50.074467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:49.529957Z","time spent":"544.501104ms","remote":"127.0.0.1:41064","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-06-10T10:02:50.074474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.620602ms","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-06-10T10:02:50.074542Z","caller":"traceutil/trace.go:171","msg":"trace[675505631] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:635; }","duration":"549.729876ms","start":"2026-06-10T10:02:49.524783Z","end":"2026-06-10T10:02:50.074513Z","steps":["trace[675505631] 'agreement among raft nodes before linearized reading' (duration: 549.609151ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:50.074592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:49.524755Z","time spent":"549.827929ms","remote":"127.0.0.1:41064","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-06-10T10:02:50.074636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.496005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-h2clf\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-10T10:02:50.07467Z","caller":"traceutil/trace.go:171","msg":"trace[535725475] range","detail":"{range_begin:/registry/pods/kube-system/cilium-h2clf; range_end:; response_count:1; response_revision:635; }","duration":"617.561738ms","start":"2026-06-10T10:02:49.457098Z","end":"2026-06-10T10:02:50.07466Z","steps":["trace[535725475] 'agreement among raft nodes before linearized reading' (duration: 617.467705ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:50.074702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:49.457073Z","time spent":"617.62293ms","remote":"127.0.0.1:40984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-h2clf\" "} {"level":"warn","ts":"2026-06-10T10:02:50.074283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.550097ms","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-06-10T10:02:50.074749Z","caller":"traceutil/trace.go:171","msg":"trace[15450207] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:635; }","duration":"552.106647ms","start":"2026-06-10T10:02:49.52263Z","end":"2026-06-10T10:02:50.074737Z","steps":["trace[15450207] 'agreement among raft nodes before linearized reading' (duration: 551.386182ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:50.074778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:49.522609Z","time spent":"552.16149ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-10T10:02:50.292627Z","caller":"traceutil/trace.go:171","msg":"trace[1760861279] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"212.50645ms","start":"2026-06-10T10:02:50.080058Z","end":"2026-06-10T10:02:50.292565Z","steps":["trace[1760861279] 'process raft request' (duration: 153.021331ms)","trace[1760861279] 'compare' (duration: 59.296662ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:02:50.292985Z","caller":"traceutil/trace.go:171","msg":"trace[441977237] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"212.20229ms","start":"2026-06-10T10:02:50.080758Z","end":"2026-06-10T10:02:50.292961Z","steps":["trace[441977237] 'process raft request' (duration: 211.766834ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:50.293028Z","caller":"traceutil/trace.go:171","msg":"trace[182750529] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"212.18389ms","start":"2026-06-10T10:02:50.080827Z","end":"2026-06-10T10:02:50.293011Z","steps":["trace[182750529] 'process raft request' (duration: 212.051654ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:50.293218Z","caller":"traceutil/trace.go:171","msg":"trace[235383868] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"197.517985ms","start":"2026-06-10T10:02:50.095689Z","end":"2026-06-10T10:02:50.293207Z","steps":["trace[235383868] 'process raft request' (duration: 197.319648ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:50.293196Z","caller":"traceutil/trace.go:171","msg":"trace[384446271] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"211.735515ms","start":"2026-06-10T10:02:50.081397Z","end":"2026-06-10T10:02:50.293133Z","steps":["trace[384446271] 'process raft request' (duration: 211.564609ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:50.293325Z","caller":"traceutil/trace.go:171","msg":"trace[1521330047] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"152.705832ms","start":"2026-06-10T10:02:50.140586Z","end":"2026-06-10T10:02:50.293292Z","steps":["trace[1521330047] 'process raft request' (duration: 152.545826ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:51.699613Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6363760756442547486,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T10:02:52.200702Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6363760756442547486,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T10:02:52.246876Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.522832951s","expected-duration":"1s"} {"level":"info","ts":"2026-06-10T10:02:52.247974Z","caller":"traceutil/trace.go:171","msg":"trace[86556082] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.531791988s","start":"2026-06-10T10:02:50.716158Z","end":"2026-06-10T10:02:52.24795Z","steps":["trace[86556082] 'process raft request' (duration: 1.531179488s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:52.248119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:50.716132Z","time spent":"1.531904242s","remote":"127.0.0.1:40888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T10:02:52.272401Z","caller":"traceutil/trace.go:171","msg":"trace[911316581] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"950.537333ms","start":"2026-06-10T10:02:51.321818Z","end":"2026-06-10T10:02:52.272355Z","steps":["trace[911316581] 'process raft request' (duration: 950.255423ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:52.272996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:51.321788Z","time spent":"950.75983ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T10:02:52.309159Z","caller":"traceutil/trace.go:171","msg":"trace[230392931] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:663; }","duration":"1.110002715s","start":"2026-06-10T10:02:51.199131Z","end":"2026-06-10T10:02:52.309134Z","steps":["trace[230392931] 'read index received' (duration: 1.047956922s)","trace[230392931] 'applied index is now lower than readState.Index' (duration: 62.044743ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:02:52.309301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110169121s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T10:02:52.309327Z","caller":"traceutil/trace.go:171","msg":"trace[1468220422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"1.110262804s","start":"2026-06-10T10:02:51.199056Z","end":"2026-06-10T10:02:52.309319Z","steps":["trace[1468220422] 'agreement among raft nodes before linearized reading' (duration: 1.110192622s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:02:52.309378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:02:51.199025Z","time spent":"1.110347767s","remote":"127.0.0.1:40804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-10T10:02:56.570955Z","caller":"traceutil/trace.go:171","msg":"trace[1878600745] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"221.455504ms","start":"2026-06-10T10:02:56.349457Z","end":"2026-06-10T10:02:56.570913Z","steps":["trace[1878600745] 'process raft request' (duration: 195.094131ms)","trace[1878600745] 'compare' (duration: 26.168986ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T10:02:56.57119Z","caller":"traceutil/trace.go:171","msg":"trace[1527904979] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"220.503823ms","start":"2026-06-10T10:02:56.350653Z","end":"2026-06-10T10:02:56.571157Z","steps":["trace[1527904979] 'process raft request' (duration: 220.169981ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:02:57.036265Z","caller":"traceutil/trace.go:171","msg":"trace[277576979] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"238.128602ms","start":"2026-06-10T10:02:56.798104Z","end":"2026-06-10T10:02:57.036233Z","steps":["trace[277576979] 'process raft request' (duration: 237.902125ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:03:04.615975Z","caller":"traceutil/trace.go:171","msg":"trace[1017445917] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"217.693605ms","start":"2026-06-10T10:03:04.398255Z","end":"2026-06-10T10:03:04.615949Z","steps":["trace[1017445917] 'process raft request' (duration: 217.478109ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:03:04.61596Z","caller":"traceutil/trace.go:171","msg":"trace[1565170120] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:811; }","duration":"179.884667ms","start":"2026-06-10T10:03:04.436031Z","end":"2026-06-10T10:03:04.615916Z","steps":["trace[1565170120] 'read index received' (duration: 179.627749ms)","trace[1565170120] 'applied index is now lower than readState.Index' (duration: 255.678µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:03:04.616346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.29296ms","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-06-10T10:03:04.61646Z","caller":"traceutil/trace.go:171","msg":"trace[795257287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:786; }","duration":"180.427933ms","start":"2026-06-10T10:03:04.43599Z","end":"2026-06-10T10:03:04.616417Z","steps":["trace[795257287] 'agreement among raft nodes before linearized reading' (duration: 180.049762ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:03:08.220417Z","caller":"traceutil/trace.go:171","msg":"trace[22153969] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"808.525686ms","start":"2026-06-10T10:03:07.411866Z","end":"2026-06-10T10:03:08.220392Z","steps":["trace[22153969] 'process raft request' (duration: 808.357161ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:03:08.220743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:03:07.411818Z","time spent":"808.668449ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T10:03:08.645953Z","caller":"traceutil/trace.go:171","msg":"trace[1468332717] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:831; }","duration":"591.479953ms","start":"2026-06-10T10:03:08.054444Z","end":"2026-06-10T10:03:08.645924Z","steps":["trace[1468332717] 'read index received' (duration: 165.728986ms)","trace[1468332717] 'applied index is now lower than readState.Index' (duration: 425.750237ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T10:03:08.646184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.335826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-10T10:03:08.646232Z","caller":"traceutil/trace.go:171","msg":"trace[95442952] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:806; }","duration":"310.433938ms","start":"2026-06-10T10:03:08.335786Z","end":"2026-06-10T10:03:08.64622Z","steps":["trace[95442952] 'agreement among raft nodes before linearized reading' (duration: 310.359416ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:03:08.646264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:03:08.335774Z","time spent":"310.482219ms","remote":"127.0.0.1:40890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":13,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-06-10T10:03:08.646371Z","caller":"traceutil/trace.go:171","msg":"trace[1992595953] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"1.053757602s","start":"2026-06-10T10:03:07.592372Z","end":"2026-06-10T10:03:08.64613Z","steps":["trace[1992595953] 'process raft request' (duration: 1.053441603s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:03:08.646353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.210104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T10:03:08.646118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.659448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-10T10:03:08.646611Z","caller":"traceutil/trace.go:171","msg":"trace[1495857793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:806; }","duration":"446.460581ms","start":"2026-06-10T10:03:08.200086Z","end":"2026-06-10T10:03:08.646546Z","steps":["trace[1495857793] 'agreement among raft nodes before linearized reading' (duration: 446.002488ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:03:08.646669Z","caller":"traceutil/trace.go:171","msg":"trace[434154389] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:806; }","duration":"592.217193ms","start":"2026-06-10T10:03:08.054402Z","end":"2026-06-10T10:03:08.646619Z","steps":["trace[434154389] 'agreement among raft nodes before linearized reading' (duration: 591.637347ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T10:03:08.646817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:03:07.592349Z","time spent":"1.054178284s","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T10:03:08.646845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:03:08.054382Z","time spent":"592.44839ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-10T10:03:08.647582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T10:03:08.200067Z","time spent":"446.636126ms","remote":"127.0.0.1:40804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-10T10:03:22.915448Z","caller":"traceutil/trace.go:171","msg":"trace[24669629] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"144.214308ms","start":"2026-06-10T10:03:22.771209Z","end":"2026-06-10T10:03:22.915423Z","steps":["trace[24669629] 'process raft request' (duration: 144.087225ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:03:22.917728Z","caller":"traceutil/trace.go:171","msg":"trace[838310013] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"145.824978ms","start":"2026-06-10T10:03:22.771865Z","end":"2026-06-10T10:03:22.91769Z","steps":["trace[838310013] 'process raft request' (duration: 145.551202ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:03:22.91777Z","caller":"traceutil/trace.go:171","msg":"trace[1433344434] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"145.740226ms","start":"2026-06-10T10:03:22.772014Z","end":"2026-06-10T10:03:22.917754Z","steps":["trace[1433344434] 'process raft request' (duration: 145.49622ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T10:04:17.273422Z","caller":"traceutil/trace.go:171","msg":"trace[98170885] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"160.075101ms","start":"2026-06-10T10:04:17.113302Z","end":"2026-06-10T10:04:17.273377Z","steps":["trace[98170885] 'process raft request' (duration: 90.816819ms)","trace[98170885] 'compare' (duration: 68.980277ms)"],"step_count":2}