{"level":"warn","ts":"2026-07-09T23:52:00.040474Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T23:52:00.040595Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23: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-07-09T23:52:00.040716Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T23:52:00.040735Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-07-09T23:52:00.040766Z","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-07-09T23:52:00.041537Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-07-09T23:52:00.041634Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23: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-07-09T23:52:00.045647Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.747589ms"} {"level":"info","ts":"2026-07-09T23:52:00.051314Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-07-09T23:52:00.051404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-07-09T23:52:00.051433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-07-09T23:52:00.051447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-09T23:52:00.051468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-07-09T23:52:00.051526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-07-09T23:52:00.057411Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-09T23:52:00.059038Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-09T23:52:00.060129Z","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-07-09T23:52:00.062464Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-09T23:52:00.062745Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-09T23:52:00.062949Z","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-07-09T23:52:00.063023Z","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-07-09T23:52:00.063033Z","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-07-09T23:52:00.06384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-07-09T23:52:00.064955Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-07-09T23:52:00.067901Z","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-07-09T23:52:00.06807Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-07-09T23:52:00.068111Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-07-09T23:52:00.069356Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-09T23:52:00.069297Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-09T23:52:00.951969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-07-09T23:52:00.952032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-09T23:52:00.952055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-07-09T23:52:00.952075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-07-09T23:52:00.952087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-07-09T23:52:00.952125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-07-09T23:52:00.952144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-07-09T23:52:00.952996Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T23:52:00.953942Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T23:52:00.953843Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-07-09T23:52:00.953998Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T23:52:00.954143Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-09T23:52:00.954242Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-09T23:52:00.954569Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T23:52:00.954657Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T23:52:00.954688Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T23:52:00.956917Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-09T23:52:00.961591Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"warn","ts":"2026-07-09T23:52:06.599594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.913121ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:52:06.599953Z","caller":"traceutil/trace.go:171","msg":"trace[711554899] linearizableReadLoop","detail":"{readStateIndex:276; appliedIndex:275; }","duration":"750.00856ms","start":"2026-07-09T23:52:05.849931Z","end":"2026-07-09T23:52:06.599939Z","steps":["trace[711554899] 'read index received' (duration: 148.091303ms)","trace[711554899] 'applied index is now lower than readState.Index' (duration: 601.916307ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:52:06.599993Z","caller":"traceutil/trace.go:171","msg":"trace[2054120291] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"774.743725ms","start":"2026-07-09T23:52:05.825205Z","end":"2026-07-09T23:52:06.599949Z","steps":["trace[2054120291] 'process raft request' (duration: 172.863029ms)","trace[2054120291] 'compare' (duration: 600.722555ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:52:06.600051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.125883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:52:06.60033Z","caller":"traceutil/trace.go:171","msg":"trace[1116744058] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:0; response_revision:267; }","duration":"750.199095ms","start":"2026-07-09T23:52:05.84987Z","end":"2026-07-09T23:52:06.600069Z","steps":["trace[1116744058] 'agreement among raft nodes before linearized reading' (duration: 750.129594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:06.600404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:05.825183Z","time spent":"774.868089ms","remote":"127.0.0.1:60892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":435,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T23:52:06.600466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:05.849849Z","time spent":"750.603346ms","remote":"127.0.0.1:60760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" "} {"level":"info","ts":"2026-07-09T23:52:06.887332Z","caller":"traceutil/trace.go:171","msg":"trace[794629497] transaction","detail":"{read_only:false; number_of_response:0; response_revision:267; }","duration":"401.669782ms","start":"2026-07-09T23:52:06.485601Z","end":"2026-07-09T23:52:06.887271Z","steps":["trace[794629497] 'process raft request' (duration: 401.581049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:06.88743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:06.48558Z","time spent":"401.804835ms","remote":"127.0.0.1:60746","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T23:52:06.894116Z","caller":"traceutil/trace.go:171","msg":"trace[922942862] linearizableReadLoop","detail":"{readStateIndex:280; appliedIndex:277; }","duration":"294.107122ms","start":"2026-07-09T23:52:06.599983Z","end":"2026-07-09T23:52:06.89409Z","steps":["trace[922942862] 'read index received' (duration: 287.700173ms)","trace[922942862] 'applied index is now lower than readState.Index' (duration: 6.405589ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:52:06.894322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.738134ms","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-07-09T23:52:06.894366Z","caller":"traceutil/trace.go:171","msg":"trace[820059085] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:267; }","duration":"823.863878ms","start":"2026-07-09T23:52:06.070486Z","end":"2026-07-09T23:52:06.89435Z","steps":["trace[820059085] 'agreement among raft nodes before linearized reading' (duration: 823.820777ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:06.894373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.460539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-09T23:52:06.894406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:06.070467Z","time spent":"823.931639ms","remote":"127.0.0.1:60812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-09T23:52:06.894414Z","caller":"traceutil/trace.go:171","msg":"trace[1553625464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:267; }","duration":"640.54578ms","start":"2026-07-09T23:52:06.253859Z","end":"2026-07-09T23:52:06.894404Z","steps":["trace[1553625464] 'agreement among raft nodes before linearized reading' (duration: 640.446037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:06.894489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:06.253801Z","time spent":"640.641513ms","remote":"127.0.0.1:60812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-09T23:52:06.894178Z","caller":"traceutil/trace.go:171","msg":"trace[434195547] transaction","detail":"{read_only:false; number_of_response:0; response_revision:267; }","duration":"407.833455ms","start":"2026-07-09T23:52:06.486315Z","end":"2026-07-09T23:52:06.894148Z","steps":["trace[434195547] 'process raft request' (duration: 407.718682ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:06.894805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:06.486298Z","time spent":"408.393339ms","remote":"127.0.0.1:60746","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T23:52:06.895049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.613109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-09T23:52:06.8951Z","caller":"traceutil/trace.go:171","msg":"trace[405783479] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:267; }","duration":"236.715481ms","start":"2026-07-09T23:52:06.65837Z","end":"2026-07-09T23:52:06.895085Z","steps":["trace[405783479] 'agreement among raft nodes before linearized reading' (duration: 236.648599ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:06.894229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.398462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T23:52:06.895203Z","caller":"traceutil/trace.go:171","msg":"trace[112847950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:267; }","duration":"639.384979ms","start":"2026-07-09T23:52:06.25581Z","end":"2026-07-09T23:52:06.895195Z","steps":["trace[112847950] 'agreement among raft nodes before linearized reading' (duration: 638.357981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:06.895231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:06.255748Z","time spent":"639.475161ms","remote":"127.0.0.1:60812","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-07-09T23:52:06.894239Z","caller":"traceutil/trace.go:171","msg":"trace[842005929] transaction","detail":"{read_only:false; number_of_response:0; response_revision:267; }","duration":"407.846966ms","start":"2026-07-09T23:52:06.486384Z","end":"2026-07-09T23:52:06.894231Z","steps":["trace[842005929] 'process raft request' (duration: 407.571248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:06.895337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:06.486362Z","time spent":"408.937394ms","remote":"127.0.0.1:60746","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T23:52:06.894278Z","caller":"traceutil/trace.go:171","msg":"trace[1305568112] transaction","detail":"{read_only:false; number_of_response:0; response_revision:267; }","duration":"407.078346ms","start":"2026-07-09T23:52:06.487193Z","end":"2026-07-09T23:52:06.894272Z","steps":["trace[1305568112] 'process raft request' (duration: 406.868349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:06.895493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:06.487178Z","time spent":"408.258006ms","remote":"127.0.0.1:60746","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T23:52:06.894278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.037411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:52:06.895627Z","caller":"traceutil/trace.go:171","msg":"trace[692993989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:267; }","duration":"606.385385ms","start":"2026-07-09T23:52:06.289212Z","end":"2026-07-09T23:52:06.895598Z","steps":["trace[692993989] 'agreement among raft nodes before linearized reading' (duration: 605.0496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:06.894214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.428347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5892"} {"level":"warn","ts":"2026-07-09T23:52:06.895704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:06.289162Z","time spent":"606.517369ms","remote":"127.0.0.1:60518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T23:52:06.895726Z","caller":"traceutil/trace.go:171","msg":"trace[1934524393] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:267; }","duration":"410.918527ms","start":"2026-07-09T23:52:06.484758Z","end":"2026-07-09T23:52:06.895676Z","steps":["trace[1934524393] 'agreement among raft nodes before linearized reading' (duration: 409.356705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:06.896561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.277194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-09T23:52:06.896628Z","caller":"traceutil/trace.go:171","msg":"trace[713418712] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:267; }","duration":"291.048881ms","start":"2026-07-09T23:52:06.605553Z","end":"2026-07-09T23:52:06.896602Z","steps":["trace[713418712] 'agreement among raft nodes before linearized reading' (duration: 289.221322ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:06.895877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:06.484698Z","time spent":"411.165573ms","remote":"127.0.0.1:60746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5915,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-07-09T23:52:37.903561Z","caller":"traceutil/trace.go:171","msg":"trace[1540409213] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"120.656637ms","start":"2026-07-09T23:52:37.782877Z","end":"2026-07-09T23:52:37.903534Z","steps":["trace[1540409213] 'process raft request' (duration: 120.477641ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:37.909724Z","caller":"traceutil/trace.go:171","msg":"trace[1035470171] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"114.564204ms","start":"2026-07-09T23:52:37.795103Z","end":"2026-07-09T23:52:37.909667Z","steps":["trace[1035470171] 'process raft request' (duration: 113.962338ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:37.910084Z","caller":"traceutil/trace.go:171","msg":"trace[360506959] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"115.943282ms","start":"2026-07-09T23:52:37.794131Z","end":"2026-07-09T23:52:37.910074Z","steps":["trace[360506959] 'process raft request' (duration: 114.820392ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:37.910187Z","caller":"traceutil/trace.go:171","msg":"trace[973761811] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"122.259479ms","start":"2026-07-09T23:52:37.787919Z","end":"2026-07-09T23:52:37.910179Z","steps":["trace[973761811] 'process raft request' (duration: 120.812461ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:37.910272Z","caller":"traceutil/trace.go:171","msg":"trace[599637429] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"121.777146ms","start":"2026-07-09T23:52:37.788486Z","end":"2026-07-09T23:52:37.910263Z","steps":["trace[599637429] 'process raft request' (duration: 120.40591ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:37.910372Z","caller":"traceutil/trace.go:171","msg":"trace[1350045093] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"116.139017ms","start":"2026-07-09T23:52:37.794219Z","end":"2026-07-09T23:52:37.910358Z","steps":["trace[1350045093] 'process raft request' (duration: 114.782411ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:37.909767Z","caller":"traceutil/trace.go:171","msg":"trace[314599976] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"113.560758ms","start":"2026-07-09T23:52:37.796178Z","end":"2026-07-09T23:52:37.909739Z","steps":["trace[314599976] 'process raft request' (duration: 112.934242ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:37.909839Z","caller":"traceutil/trace.go:171","msg":"trace[1093173587] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"110.937369ms","start":"2026-07-09T23:52:37.798892Z","end":"2026-07-09T23:52:37.909829Z","steps":["trace[1093173587] 'process raft request' (duration: 110.265092ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:52:37.909867Z","caller":"traceutil/trace.go:171","msg":"trace[1452822415] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"104.18892ms","start":"2026-07-09T23:52:37.805671Z","end":"2026-07-09T23:52:37.90986Z","steps":["trace[1452822415] 'process raft request' (duration: 103.618335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:45.081411Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955949339162703,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T23:52:45.168858Z","caller":"traceutil/trace.go:171","msg":"trace[694998441] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"587.613977ms","start":"2026-07-09T23:52:44.581216Z","end":"2026-07-09T23:52:45.16883Z","steps":["trace[694998441] 'read index received' (duration: 587.516384ms)","trace[694998441] 'applied index is now lower than readState.Index' (duration: 96.603µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:52:45.168988Z","caller":"traceutil/trace.go:171","msg":"trace[430405070] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"837.593931ms","start":"2026-07-09T23:52:44.331385Z","end":"2026-07-09T23:52:45.168979Z","steps":["trace[430405070] 'process raft request' (duration: 837.308313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:45.169083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:44.331367Z","time spent":"837.656852ms","remote":"127.0.0.1:60876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T23:52:45.169705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.477429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mch97\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-09T23:52:45.169781Z","caller":"traceutil/trace.go:171","msg":"trace[360483267] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mch97; range_end:; response_count:1; response_revision:593; }","duration":"588.580812ms","start":"2026-07-09T23:52:44.581169Z","end":"2026-07-09T23:52:45.16975Z","steps":["trace[360483267] 'agreement among raft nodes before linearized reading' (duration: 588.134821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:52:45.169817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:44.581151Z","time spent":"588.657444ms","remote":"127.0.0.1:60746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-mch97\" "} {"level":"info","ts":"2026-07-09T23:52:57.371467Z","caller":"traceutil/trace.go:171","msg":"trace[1286989920] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"119.717916ms","start":"2026-07-09T23:52:57.251721Z","end":"2026-07-09T23:52:57.371439Z","steps":["trace[1286989920] 'process raft request' (duration: 118.307108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:00.031519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.296279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-85hsd\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-07-09T23:53:00.031653Z","caller":"traceutil/trace.go:171","msg":"trace[1554149457] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-85hsd; range_end:; response_count:1; response_revision:764; }","duration":"351.447103ms","start":"2026-07-09T23:52:59.680149Z","end":"2026-07-09T23:53:00.031596Z","steps":["trace[1554149457] 'range keys from in-memory index tree' (duration: 351.109883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:00.031713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:52:59.680123Z","time spent":"351.571246ms","remote":"127.0.0.1:60746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-85hsd\" "} {"level":"info","ts":"2026-07-09T23:53:35.214826Z","caller":"traceutil/trace.go:171","msg":"trace[362118394] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"518.13365ms","start":"2026-07-09T23:53:34.696658Z","end":"2026-07-09T23:53:35.214792Z","steps":["trace[362118394] 'process raft request' (duration: 517.75989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:35.214992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.557365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T23:53:35.214994Z","caller":"traceutil/trace.go:171","msg":"trace[678268486] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1014; }","duration":"298.317339ms","start":"2026-07-09T23:53:34.916394Z","end":"2026-07-09T23:53:35.214711Z","steps":["trace[678268486] 'read index received' (duration: 298.173454ms)","trace[678268486] 'applied index is now lower than readState.Index' (duration: 141.085µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:53:35.215079Z","caller":"traceutil/trace.go:171","msg":"trace[1495765037] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:977; }","duration":"298.703299ms","start":"2026-07-09T23:53:34.916358Z","end":"2026-07-09T23:53:35.215061Z","steps":["trace[1495765037] 'agreement among raft nodes before linearized reading' (duration: 298.523424ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:35.215165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:53:34.696638Z","time spent":"518.271913ms","remote":"127.0.0.1:60812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:53:35.235019Z","caller":"traceutil/trace.go:171","msg":"trace[1332000056] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"247.129864ms","start":"2026-07-09T23:53:34.98786Z","end":"2026-07-09T23:53:35.23499Z","steps":["trace[1332000056] 'process raft request' (duration: 247.030641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:35.425178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.03068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:53:35.425275Z","caller":"traceutil/trace.go:171","msg":"trace[73105799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:978; }","duration":"135.185534ms","start":"2026-07-09T23:53:35.290066Z","end":"2026-07-09T23:53:35.425251Z","steps":["trace[73105799] 'range keys from in-memory index tree' (duration: 134.958199ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:53:55.502079Z","caller":"traceutil/trace.go:171","msg":"trace[519930028] linearizableReadLoop","detail":"{readStateIndex:1085; appliedIndex:1084; }","duration":"192.393677ms","start":"2026-07-09T23:53:55.309664Z","end":"2026-07-09T23:53:55.502057Z","steps":["trace[519930028] 'read index received' (duration: 192.190451ms)","trace[519930028] 'applied index is now lower than readState.Index' (duration: 202.086µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:53:55.502173Z","caller":"traceutil/trace.go:171","msg":"trace[1711217147] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"204.737084ms","start":"2026-07-09T23:53:55.297412Z","end":"2026-07-09T23:53:55.502149Z","steps":["trace[1711217147] 'process raft request' (duration: 204.501397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:53:55.502247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.558361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T23:53:55.502272Z","caller":"traceutil/trace.go:171","msg":"trace[190590564] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1043; }","duration":"192.620573ms","start":"2026-07-09T23:53:55.309645Z","end":"2026-07-09T23:53:55.502266Z","steps":["trace[190590564] 'agreement among raft nodes before linearized reading' (duration: 192.49986ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:54:13.766025Z","caller":"traceutil/trace.go:171","msg":"trace[49761682] linearizableReadLoop","detail":"{readStateIndex:1199; appliedIndex:1198; }","duration":"176.262137ms","start":"2026-07-09T23:54:13.589746Z","end":"2026-07-09T23:54:13.766009Z","steps":["trace[49761682] 'read index received' (duration: 176.142264ms)","trace[49761682] 'applied index is now lower than readState.Index' (duration: 117.463µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:54:13.766115Z","caller":"traceutil/trace.go:171","msg":"trace[1665090132] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"213.348649ms","start":"2026-07-09T23:54:13.55276Z","end":"2026-07-09T23:54:13.766108Z","steps":["trace[1665090132] 'process raft request' (duration: 213.146883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:54:13.766254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.505354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T23:54:13.766271Z","caller":"traceutil/trace.go:171","msg":"trace[103389444] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1152; }","duration":"176.564876ms","start":"2026-07-09T23:54:13.589701Z","end":"2026-07-09T23:54:13.766266Z","steps":["trace[103389444] 'agreement among raft nodes before linearized reading' (duration: 176.479354ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:54:30.347465Z","caller":"traceutil/trace.go:171","msg":"trace[374572491] linearizableReadLoop","detail":"{readStateIndex:1277; appliedIndex:1276; }","duration":"489.136257ms","start":"2026-07-09T23:54:29.858313Z","end":"2026-07-09T23:54:30.34745Z","steps":["trace[374572491] 'read index received' (duration: 488.984813ms)","trace[374572491] 'applied index is now lower than readState.Index' (duration: 151.034µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:54:30.347646Z","caller":"traceutil/trace.go:171","msg":"trace[350544763] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"534.222198ms","start":"2026-07-09T23:54:29.813417Z","end":"2026-07-09T23:54:30.347639Z","steps":["trace[350544763] 'process raft request' (duration: 533.92604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:54:30.347712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:54:29.813391Z","time spent":"534.270088ms","remote":"127.0.0.1:60812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:54:30.347949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.465323ms","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-07-09T23:54:30.347973Z","caller":"traceutil/trace.go:171","msg":"trace[642238892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1226; }","duration":"428.523124ms","start":"2026-07-09T23:54:29.919444Z","end":"2026-07-09T23:54:30.347967Z","steps":["trace[642238892] 'agreement among raft nodes before linearized reading' (duration: 428.446182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:54:30.347988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:54:29.919427Z","time spent":"428.557335ms","remote":"127.0.0.1:60812","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-07-09T23:54:30.348121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.385696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-09T23:54:30.348079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.198877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-09T23:54:30.348182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.874796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T23:54:30.348198Z","caller":"traceutil/trace.go:171","msg":"trace[1087618153] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1226; }","duration":"489.916737ms","start":"2026-07-09T23:54:29.858277Z","end":"2026-07-09T23:54:30.348193Z","steps":["trace[1087618153] 'agreement among raft nodes before linearized reading' (duration: 489.876666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:54:30.348212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:54:29.858259Z","time spent":"489.950088ms","remote":"127.0.0.1:60812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T23:54:30.348165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.432708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-09T23:54:30.348185Z","caller":"traceutil/trace.go:171","msg":"trace[865786460] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1226; }","duration":"467.357572ms","start":"2026-07-09T23:54:29.880803Z","end":"2026-07-09T23:54:30.34816Z","steps":["trace[865786460] 'agreement among raft nodes before linearized reading' (duration: 467.029132ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:54:30.348138Z","caller":"traceutil/trace.go:171","msg":"trace[1089683311] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1226; }","duration":"460.431378ms","start":"2026-07-09T23:54:29.887702Z","end":"2026-07-09T23:54:30.348134Z","steps":["trace[1089683311] 'agreement among raft nodes before linearized reading' (duration: 460.387477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:54:30.348264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:54:29.887688Z","time spent":"460.570731ms","remote":"127.0.0.1:60812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-09T23:54:30.34828Z","caller":"traceutil/trace.go:171","msg":"trace[483267609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1226; }","duration":"426.597233ms","start":"2026-07-09T23:54:29.921654Z","end":"2026-07-09T23:54:30.348252Z","steps":["trace[483267609] 'agreement among raft nodes before linearized reading' (duration: 426.220243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:54:30.348315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:54:29.880786Z","time spent":"467.518795ms","remote":"127.0.0.1:60812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T23:54:30.348308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.128961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T23:54:30.348352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:54:29.921643Z","time spent":"426.690485ms","remote":"127.0.0.1:60812","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-07-09T23:54:30.348389Z","caller":"traceutil/trace.go:171","msg":"trace[213238857] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1226; }","duration":"289.279015ms","start":"2026-07-09T23:54:30.05909Z","end":"2026-07-09T23:54:30.34837Z","steps":["trace[213238857] 'agreement among raft nodes before linearized reading' (duration: 289.10487ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:54:31.418284Z","caller":"traceutil/trace.go:171","msg":"trace[1910399488] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1283; }","duration":"267.414386ms","start":"2026-07-09T23:54:31.150846Z","end":"2026-07-09T23:54:31.418261Z","steps":["trace[1910399488] 'read index received' (duration: 267.227121ms)","trace[1910399488] 'applied index is now lower than readState.Index' (duration: 186.395µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:54:31.418401Z","caller":"traceutil/trace.go:171","msg":"trace[1653768511] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"358.888734ms","start":"2026-07-09T23:54:31.059502Z","end":"2026-07-09T23:54:31.418391Z","steps":["trace[1653768511] 'process raft request' (duration: 358.635277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:54:31.418695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:54:31.059478Z","time spent":"358.951966ms","remote":"127.0.0.1:60614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T23:54:31.418757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.227903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T23:54:31.418836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.959441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:54:31.418904Z","caller":"traceutil/trace.go:171","msg":"trace[1133072995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1233; }","duration":"127.503489ms","start":"2026-07-09T23:54:31.291374Z","end":"2026-07-09T23:54:31.418877Z","steps":["trace[1133072995] 'agreement among raft nodes before linearized reading' (duration: 127.189001ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:54:31.418956Z","caller":"traceutil/trace.go:171","msg":"trace[158841880] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1233; }","duration":"268.077675ms","start":"2026-07-09T23:54:31.150829Z","end":"2026-07-09T23:54:31.418907Z","steps":["trace[158841880] 'agreement among raft nodes before linearized reading' (duration: 267.88183ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:54:31.573Z","caller":"traceutil/trace.go:171","msg":"trace[1082053788] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"148.689709ms","start":"2026-07-09T23:54:31.42429Z","end":"2026-07-09T23:54:31.57298Z","steps":["trace[1082053788] 'process raft request' (duration: 94.169289ms)","trace[1082053788] 'compare' (duration: 54.425528ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:54:31.595365Z","caller":"traceutil/trace.go:171","msg":"trace[280399990] linearizableReadLoop","detail":"{readStateIndex:1286; appliedIndex:1284; }","duration":"109.128443ms","start":"2026-07-09T23:54:31.486213Z","end":"2026-07-09T23:54:31.595341Z","steps":["trace[280399990] 'read index received' (duration: 32.323744ms)","trace[280399990] 'applied index is now lower than readState.Index' (duration: 76.803429ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:54:31.595392Z","caller":"traceutil/trace.go:171","msg":"trace[518392137] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"154.925364ms","start":"2026-07-09T23:54:31.440437Z","end":"2026-07-09T23:54:31.595362Z","steps":["trace[518392137] 'process raft request' (duration: 154.801111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:54:31.595554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.304188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:54:31.595644Z","caller":"traceutil/trace.go:171","msg":"trace[1446390386] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1235; }","duration":"109.442112ms","start":"2026-07-09T23:54:31.486181Z","end":"2026-07-09T23:54:31.595623Z","steps":["trace[1446390386] 'agreement among raft nodes before linearized reading' (duration: 109.247387ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:54:31.82219Z","caller":"traceutil/trace.go:171","msg":"trace[211063009] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"215.515684ms","start":"2026-07-09T23:54:31.606639Z","end":"2026-07-09T23:54:31.822155Z","steps":["trace[211063009] 'process raft request' (duration: 199.228164ms)","trace[211063009] 'compare' (duration: 15.985933ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:54:32.468912Z","caller":"traceutil/trace.go:171","msg":"trace[679405489] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"116.724665ms","start":"2026-07-09T23:54:32.352157Z","end":"2026-07-09T23:54:32.468882Z","steps":["trace[679405489] 'process raft request' (duration: 116.421447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:54:44.301982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.902688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:54:44.302067Z","caller":"traceutil/trace.go:171","msg":"trace[201014993] linearizableReadLoop","detail":"{readStateIndex:1375; appliedIndex:1374; }","duration":"254.031512ms","start":"2026-07-09T23:54:44.048023Z","end":"2026-07-09T23:54:44.302054Z","steps":["trace[201014993] 'read index received' (duration: 100.077205ms)","trace[201014993] 'applied index is now lower than readState.Index' (duration: 153.953527ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:54:44.302124Z","caller":"traceutil/trace.go:171","msg":"trace[3256854] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"332.725013ms","start":"2026-07-09T23:54:43.969391Z","end":"2026-07-09T23:54:44.302116Z","steps":["trace[3256854] 'process raft request' (duration: 178.629361ms)","trace[3256854] 'compare' (duration: 153.803434ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:54:44.302168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:54:43.969372Z","time spent":"332.774274ms","remote":"127.0.0.1:60568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:54:44.303119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.09169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-09T23:54:44.303188Z","caller":"traceutil/trace.go:171","msg":"trace[391984049] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1321; }","duration":"255.163743ms","start":"2026-07-09T23:54:44.047993Z","end":"2026-07-09T23:54:44.303157Z","steps":["trace[391984049] 'agreement among raft nodes before linearized reading' (duration: 254.250069ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:54:44.673474Z","caller":"traceutil/trace.go:171","msg":"trace[1475851418] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"163.0914ms","start":"2026-07-09T23:54:44.510361Z","end":"2026-07-09T23:54:44.673452Z","steps":["trace[1475851418] 'process raft request' (duration: 163.043339ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:54:44.673735Z","caller":"traceutil/trace.go:171","msg":"trace[976957063] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"363.108945ms","start":"2026-07-09T23:54:44.310597Z","end":"2026-07-09T23:54:44.673706Z","steps":["trace[976957063] 'process raft request' (duration: 293.563108ms)","trace[976957063] 'compare' (duration: 69.101846ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:54:44.673878Z","caller":"traceutil/trace.go:171","msg":"trace[2018763870] linearizableReadLoop","detail":"{readStateIndex:1376; appliedIndex:1375; }","duration":"362.257613ms","start":"2026-07-09T23:54:44.311464Z","end":"2026-07-09T23:54:44.673721Z","steps":["trace[2018763870] 'read index received' (duration: 292.813228ms)","trace[2018763870] 'applied index is now lower than readState.Index' (duration: 69.442645ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:54:44.673994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.50115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-09T23:54:44.674065Z","caller":"traceutil/trace.go:171","msg":"trace[432438822] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1323; }","duration":"362.598832ms","start":"2026-07-09T23:54:44.311448Z","end":"2026-07-09T23:54:44.674047Z","steps":["trace[432438822] 'agreement among raft nodes before linearized reading' (duration: 362.451488ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:54:44.674114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:54:44.311442Z","time spent":"362.658574ms","remote":"127.0.0.1:60820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-07-09T23:54:44.674206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.234253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T23:54:44.674305Z","caller":"traceutil/trace.go:171","msg":"trace[479389386] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1323; }","duration":"121.337106ms","start":"2026-07-09T23:54:44.552917Z","end":"2026-07-09T23:54:44.674254Z","steps":["trace[479389386] 'agreement among raft nodes before linearized reading' (duration: 121.178432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:54:44.674711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.546966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-09T23:54:44.674777Z","caller":"traceutil/trace.go:171","msg":"trace[1422026366] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1323; }","duration":"106.648679ms","start":"2026-07-09T23:54:44.568111Z","end":"2026-07-09T23:54:44.67476Z","steps":["trace[1422026366] 'agreement among raft nodes before linearized reading' (duration: 106.498245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:54:44.67471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.352744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-09T23:54:44.67485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:54:44.310578Z","time spent":"364.195684ms","remote":"127.0.0.1:60812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:54:44.674903Z","caller":"traceutil/trace.go:171","msg":"trace[1178434601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1323; }","duration":"108.571039ms","start":"2026-07-09T23:54:44.566319Z","end":"2026-07-09T23:54:44.67489Z","steps":["trace[1178434601] 'agreement among raft nodes before linearized reading' (duration: 108.282572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:57:13.986987Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125955949339165917,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T23:57:14.199093Z","caller":"traceutil/trace.go:171","msg":"trace[439541586] linearizableReadLoop","detail":"{readStateIndex:2089; appliedIndex:2088; }","duration":"712.198263ms","start":"2026-07-09T23:57:13.48688Z","end":"2026-07-09T23:57:14.199078Z","steps":["trace[439541586] 'read index received' (duration: 712.10572ms)","trace[439541586] 'applied index is now lower than readState.Index' (duration: 92.073µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:57:14.199276Z","caller":"traceutil/trace.go:171","msg":"trace[565100256] transaction","detail":"{read_only:false; response_revision:2006; number_of_response:1; }","duration":"785.947858ms","start":"2026-07-09T23:57:13.413303Z","end":"2026-07-09T23:57:14.19925Z","steps":["trace[565100256] 'process raft request' (duration: 785.695582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:57:14.200222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:57:13.413293Z","time spent":"786.859442ms","remote":"127.0.0.1:60812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:57:14.199367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.47348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-09T23:57:14.201642Z","caller":"traceutil/trace.go:171","msg":"trace[667542008] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2006; }","duration":"714.779451ms","start":"2026-07-09T23:57:13.486846Z","end":"2026-07-09T23:57:14.201625Z","steps":["trace[667542008] 'agreement among raft nodes before linearized reading' (duration: 712.434629ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:57:14.19954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.813943ms","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-07-09T23:57:14.199585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.160536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-09T23:57:14.199628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.417016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-09T23:57:14.199669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.774647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-09T23:57:14.199778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.223791ms","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-07-09T23:57:14.199843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.908287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-09T23:57:14.199945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.923274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-09T23:57:14.200064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.058281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-09T23:57:14.201911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:57:13.486832Z","time spent":"715.063119ms","remote":"127.0.0.1:60812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-09T23:57:14.202026Z","caller":"traceutil/trace.go:171","msg":"trace[1059200234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2006; }","duration":"706.295457ms","start":"2026-07-09T23:57:13.495704Z","end":"2026-07-09T23:57:14.201999Z","steps":["trace[1059200234] 'agreement among raft nodes before linearized reading' (duration: 703.784352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:57:14.203293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:57:13.495697Z","time spent":"707.554591ms","remote":"127.0.0.1:60812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-09T23:57:14.202115Z","caller":"traceutil/trace.go:171","msg":"trace[388674821] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:2006; }","duration":"374.684122ms","start":"2026-07-09T23:57:13.827421Z","end":"2026-07-09T23:57:14.202106Z","steps":["trace[388674821] 'agreement among raft nodes before linearized reading' (duration: 372.133256ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:57:14.202214Z","caller":"traceutil/trace.go:171","msg":"trace[872334417] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2006; }","duration":"407.038646ms","start":"2026-07-09T23:57:13.795163Z","end":"2026-07-09T23:57:14.202202Z","steps":["trace[872334417] 'agreement among raft nodes before linearized reading' (duration: 404.429577ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:57:14.202392Z","caller":"traceutil/trace.go:171","msg":"trace[600222444] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2006; }","duration":"459.51254ms","start":"2026-07-09T23:57:13.742856Z","end":"2026-07-09T23:57:14.202368Z","steps":["trace[600222444] 'agreement among raft nodes before linearized reading' (duration: 456.781418ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:57:14.202482Z","caller":"traceutil/trace.go:171","msg":"trace[497140900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2006; }","duration":"701.936103ms","start":"2026-07-09T23:57:13.500537Z","end":"2026-07-09T23:57:14.202473Z","steps":["trace[497140900] 'agreement among raft nodes before linearized reading' (duration: 699.210421ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:57:14.2026Z","caller":"traceutil/trace.go:171","msg":"trace[514065342] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2006; }","duration":"700.673669ms","start":"2026-07-09T23:57:13.501918Z","end":"2026-07-09T23:57:14.202592Z","steps":["trace[514065342] 'agreement among raft nodes before linearized reading' (duration: 697.880806ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:57:14.202697Z","caller":"traceutil/trace.go:171","msg":"trace[1342940630] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2006; }","duration":"706.685498ms","start":"2026-07-09T23:57:13.496002Z","end":"2026-07-09T23:57:14.202688Z","steps":["trace[1342940630] 'agreement among raft nodes before linearized reading' (duration: 703.866673ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:57:14.202798Z","caller":"traceutil/trace.go:171","msg":"trace[1687167305] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:2006; }","duration":"628.811194ms","start":"2026-07-09T23:57:13.573975Z","end":"2026-07-09T23:57:14.202786Z","steps":["trace[1687167305] 'agreement among raft nodes before linearized reading' (duration: 625.700913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:57:14.203906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:57:13.82741Z","time spent":"376.47646ms","remote":"127.0.0.1:60568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.23\" "} {"level":"warn","ts":"2026-07-09T23:57:14.204283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:57:13.795144Z","time spent":"409.11605ms","remote":"127.0.0.1:60908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":55,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-09T23:57:14.204433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:57:13.742841Z","time spent":"461.575744ms","remote":"127.0.0.1:60812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-09T23:57:14.204585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:57:13.50053Z","time spent":"704.040299ms","remote":"127.0.0.1:60812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T23:57:14.204734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:57:13.501913Z","time spent":"702.805375ms","remote":"127.0.0.1:60812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T23:57:14.204901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:57:13.495997Z","time spent":"708.883505ms","remote":"127.0.0.1:60812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T23:57:14.205068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:57:13.573964Z","time spent":"631.086715ms","remote":"127.0.0.1:60820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-07-09T23:57:14.568995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:57:14.205975Z","time spent":"363.015243ms","remote":"127.0.0.1:60568","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-09T23:57:14.572483Z","caller":"traceutil/trace.go:171","msg":"trace[609603652] transaction","detail":"{read_only:false; response_revision:2007; number_of_response:1; }","duration":"364.253696ms","start":"2026-07-09T23:57:14.2082Z","end":"2026-07-09T23:57:14.572454Z","steps":["trace[609603652] 'process raft request' (duration: 364.077212ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:57:14.572564Z","caller":"traceutil/trace.go:171","msg":"trace[1035630851] transaction","detail":"{read_only:false; response_revision:2009; number_of_response:1; }","duration":"362.427968ms","start":"2026-07-09T23:57:14.210091Z","end":"2026-07-09T23:57:14.572519Z","steps":["trace[1035630851] 'process raft request' (duration: 362.313935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:57:14.572622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:57:14.208177Z","time spent":"364.373711ms","remote":"127.0.0.1:60812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:57:14.572616Z","caller":"traceutil/trace.go:171","msg":"trace[2086185902] transaction","detail":"{read_only:false; response_revision:2008; number_of_response:1; }","duration":"363.863697ms","start":"2026-07-09T23:57:14.208714Z","end":"2026-07-09T23:57:14.572578Z","steps":["trace[2086185902] 'process raft request' (duration: 363.664431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:57:14.572733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.000173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T23:57:14.572735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:57:14.210084Z","time spent":"362.560662ms","remote":"127.0.0.1:60812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:57:14.572822Z","caller":"traceutil/trace.go:171","msg":"trace[1133079861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2012; }","duration":"283.088037ms","start":"2026-07-09T23:57:14.289696Z","end":"2026-07-09T23:57:14.572784Z","steps":["trace[1133079861] 'agreement among raft nodes before linearized reading' (duration: 282.836059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:57:14.572887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:57:14.208704Z","time spent":"363.99885ms","remote":"127.0.0.1:60812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:57:14.572504Z","caller":"traceutil/trace.go:171","msg":"trace[777570839] linearizableReadLoop","detail":"{readStateIndex:2096; appliedIndex:2089; }","duration":"282.757346ms","start":"2026-07-09T23:57:14.289737Z","end":"2026-07-09T23:57:14.572494Z","steps":["trace[777570839] 'read index received' (duration: 278.657499ms)","trace[777570839] 'applied index is now lower than readState.Index' (duration: 4.098567ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:57:14.573006Z","caller":"traceutil/trace.go:171","msg":"trace[971052666] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"361.241727ms","start":"2026-07-09T23:57:14.211757Z","end":"2026-07-09T23:57:14.572998Z","steps":["trace[971052666] 'process raft request' (duration: 360.709623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:57:14.573064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:57:14.211744Z","time spent":"361.296189ms","remote":"127.0.0.1:60812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:57:14.572994Z","caller":"traceutil/trace.go:171","msg":"trace[617633513] transaction","detail":"{read_only:false; response_revision:2010; number_of_response:1; }","duration":"362.903821ms","start":"2026-07-09T23:57:14.21008Z","end":"2026-07-09T23:57:14.572984Z","steps":["trace[617633513] 'process raft request' (duration: 362.341666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:57:14.573191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:57:14.210063Z","time spent":"363.107086ms","remote":"127.0.0.1:60812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:57:14.573291Z","caller":"traceutil/trace.go:171","msg":"trace[682844869] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"362.696186ms","start":"2026-07-09T23:57:14.210324Z","end":"2026-07-09T23:57:14.57302Z","steps":["trace[682844869] 'process raft request' (duration: 362.12196ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:57:14.573531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:57:14.210318Z","time spent":"363.085126ms","remote":"127.0.0.1:60812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:58:46.647096Z","caller":"traceutil/trace.go:171","msg":"trace[1597883933] transaction","detail":"{read_only:false; response_revision:2451; number_of_response:1; }","duration":"160.408253ms","start":"2026-07-09T23:58:46.486619Z","end":"2026-07-09T23:58:46.647027Z","steps":["trace[1597883933] 'process raft request' (duration: 160.115195ms)"],"step_count":1}