{"level":"warn","ts":"2026-07-09T23:45:41.441129Z","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:45:41.441378Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.78: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.78:2380","--initial-cluster=instance=https://199.19.213.78:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.78:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.78: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:45:41.441502Z","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:45:41.441543Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.78:2380"]} {"level":"info","ts":"2026-07-09T23:45:41.441583Z","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:45:41.442373Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.78:2379"]} {"level":"info","ts":"2026-07-09T23:45:41.443227Z","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.78:2380"],"listen-peer-urls":["https://199.19.213.78:2380"],"advertise-client-urls":["https://199.19.213.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.78: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:45:41.448244Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.694172ms"} {"level":"info","ts":"2026-07-09T23:45:41.458292Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"173f1df9994fe3b5","cluster-id":"f38512da1b70c9fc"} {"level":"info","ts":"2026-07-09T23:45:41.4584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 switched to configuration voters=()"} {"level":"info","ts":"2026-07-09T23:45:41.45846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 became follower at term 0"} {"level":"info","ts":"2026-07-09T23:45:41.458513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 173f1df9994fe3b5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-09T23:45:41.458561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 became follower at term 1"} {"level":"info","ts":"2026-07-09T23:45:41.458638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 switched to configuration voters=(1675090544261325749)"} {"level":"warn","ts":"2026-07-09T23:45:41.468785Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-09T23:45:41.470152Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-09T23:45:41.471272Z","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:45:41.473485Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"173f1df9994fe3b5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-09T23:45:41.473892Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"173f1df9994fe3b5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-09T23:45:41.474109Z","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:45:41.474306Z","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:45:41.474344Z","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:45:41.477563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 switched to configuration voters=(1675090544261325749)"} {"level":"info","ts":"2026-07-09T23:45:41.477821Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f38512da1b70c9fc","local-member-id":"173f1df9994fe3b5","added-peer-id":"173f1df9994fe3b5","added-peer-peer-urls":["https://199.19.213.78:2380"]} {"level":"info","ts":"2026-07-09T23:45:41.479426Z","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:45:41.479549Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.78:2380"} {"level":"info","ts":"2026-07-09T23:45:41.479586Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.78:2380"} {"level":"info","ts":"2026-07-09T23:45:41.479845Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"173f1df9994fe3b5","initial-advertise-peer-urls":["https://199.19.213.78:2380"],"listen-peer-urls":["https://199.19.213.78:2380"],"advertise-client-urls":["https://199.19.213.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-09T23:45:41.479905Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-09T23:45:42.159602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 is starting a new election at term 1"} {"level":"info","ts":"2026-07-09T23:45:42.159666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-09T23:45:42.159699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 received MsgPreVoteResp from 173f1df9994fe3b5 at term 1"} {"level":"info","ts":"2026-07-09T23:45:42.159717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 became candidate at term 2"} {"level":"info","ts":"2026-07-09T23:45:42.159729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 received MsgVoteResp from 173f1df9994fe3b5 at term 2"} {"level":"info","ts":"2026-07-09T23:45:42.15974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"173f1df9994fe3b5 became leader at term 2"} {"level":"info","ts":"2026-07-09T23:45:42.159752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 173f1df9994fe3b5 elected leader 173f1df9994fe3b5 at term 2"} {"level":"info","ts":"2026-07-09T23:45:42.160747Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T23:45:42.161516Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"173f1df9994fe3b5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.78:2379]}","request-path":"/0/members/173f1df9994fe3b5/attributes","cluster-id":"f38512da1b70c9fc","publish-timeout":"7s"} {"level":"info","ts":"2026-07-09T23:45:42.161534Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T23:45:42.16159Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T23:45:42.161997Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-09T23:45:42.162024Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f38512da1b70c9fc","local-member-id":"173f1df9994fe3b5","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T23:45:42.162041Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-09T23:45:42.162121Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T23:45:42.162157Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T23:45:42.164864Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.78:2379"} {"level":"info","ts":"2026-07-09T23:45:42.165589Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-09T23:46:20.28579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.661371ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:46:20.285955Z","caller":"traceutil/trace.go:171","msg":"trace[715058609] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"166.568827ms","start":"2026-07-09T23:46:20.119348Z","end":"2026-07-09T23:46:20.285917Z","steps":["trace[715058609] 'process raft request' (duration: 46.184196ms)","trace[715058609] 'compare' (duration: 119.146719ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:46:20.300563Z","caller":"traceutil/trace.go:171","msg":"trace[715088065] transaction","detail":"{read_only:false; number_of_response:1; response_revision:582; }","duration":"180.869914ms","start":"2026-07-09T23:46:20.119644Z","end":"2026-07-09T23:46:20.300514Z","steps":["trace[715088065] 'process raft request' (duration: 180.0667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:26.35001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.260271ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:46:26.350122Z","caller":"traceutil/trace.go:171","msg":"trace[989007258] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"218.515839ms","start":"2026-07-09T23:46:26.13159Z","end":"2026-07-09T23:46:26.350106Z","steps":["trace[989007258] 'read index received' (duration: 68.712296ms)","trace[989007258] 'applied index is now lower than readState.Index' (duration: 149.801982ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:46:26.350173Z","caller":"traceutil/trace.go:171","msg":"trace[1305391223] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"575.10681ms","start":"2026-07-09T23:46:25.775018Z","end":"2026-07-09T23:46:26.350124Z","steps":["trace[1305391223] 'process raft request' (duration: 425.512006ms)","trace[1305391223] 'compare' (duration: 148.347194ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:46:26.350303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.669774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-09T23:46:26.350341Z","caller":"traceutil/trace.go:171","msg":"trace[572898670] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:627; }","duration":"218.789019ms","start":"2026-07-09T23:46:26.13154Z","end":"2026-07-09T23:46:26.350329Z","steps":["trace[572898670] 'agreement among raft nodes before linearized reading' (duration: 218.610712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:26.350373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:25.774987Z","time spent":"575.280447ms","remote":"127.0.0.1:50630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:46:26.849783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.67908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:46:26.849904Z","caller":"traceutil/trace.go:171","msg":"trace[1553726246] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"348.032789ms","start":"2026-07-09T23:46:26.501854Z","end":"2026-07-09T23:46:26.849887Z","steps":["trace[1553726246] 'read index received' (duration: 202.762026ms)","trace[1553726246] 'applied index is now lower than readState.Index' (duration: 145.269273ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:46:26.850109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.252287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xbtsp\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-09T23:46:26.850082Z","caller":"traceutil/trace.go:171","msg":"trace[1942453675] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"437.582439ms","start":"2026-07-09T23:46:26.412335Z","end":"2026-07-09T23:46:26.849917Z","steps":["trace[1942453675] 'process raft request' (duration: 292.258874ms)","trace[1942453675] 'compare' (duration: 144.446761ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:46:26.850148Z","caller":"traceutil/trace.go:171","msg":"trace[850519894] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xbtsp; range_end:; response_count:1; response_revision:629; }","duration":"348.32999ms","start":"2026-07-09T23:46:26.501808Z","end":"2026-07-09T23:46:26.850137Z","steps":["trace[850519894] 'agreement among raft nodes before linearized reading' (duration: 348.164843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:26.85018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:26.501793Z","time spent":"348.379742ms","remote":"127.0.0.1:50690","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-xbtsp\" "} {"level":"warn","ts":"2026-07-09T23:46:26.850221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:26.412309Z","time spent":"437.84778ms","remote":"127.0.0.1:50712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T23:46:27.482465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.835823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:46:27.482527Z","caller":"traceutil/trace.go:171","msg":"trace[525226597] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"564.233551ms","start":"2026-07-09T23:46:26.918282Z","end":"2026-07-09T23:46:27.482516Z","steps":["trace[525226597] 'read index received' (duration: 268.266192ms)","trace[525226597] 'applied index is now lower than readState.Index' (duration: 295.966739ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:46:27.482605Z","caller":"traceutil/trace.go:171","msg":"trace[941434705] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"617.58122ms","start":"2026-07-09T23:46:26.865018Z","end":"2026-07-09T23:46:27.482599Z","steps":["trace[941434705] 'process raft request' (duration: 321.544899ms)","trace[941434705] 'compare' (duration: 295.765631ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:46:27.482689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.407948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T23:46:27.482662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:26.865004Z","time spent":"617.638243ms","remote":"127.0.0.1:50690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:46:27.48415Z","caller":"traceutil/trace.go:171","msg":"trace[1834679666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"564.4544ms","start":"2026-07-09T23:46:26.918249Z","end":"2026-07-09T23:46:27.482703Z","steps":["trace[1834679666] 'agreement among raft nodes before linearized reading' (duration: 564.421659ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:27.484589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:26.918232Z","time spent":"566.321863ms","remote":"127.0.0.1:50540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T23:46:27.697527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.957192ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T23:46:27.697673Z","caller":"traceutil/trace.go:171","msg":"trace[382749064] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"215.051296ms","start":"2026-07-09T23:46:27.482577Z","end":"2026-07-09T23:46:27.697628Z","steps":["trace[382749064] 'read index received' (duration: 107.428559ms)","trace[382749064] 'applied index is now lower than readState.Index' (duration: 107.621277ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:46:27.69772Z","caller":"traceutil/trace.go:171","msg":"trace[1674480026] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"423.396119ms","start":"2026-07-09T23:46:27.274285Z","end":"2026-07-09T23:46:27.697681Z","steps":["trace[1674480026] 'process raft request' (duration: 316.182878ms)","trace[1674480026] 'compare' (duration: 106.506202ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:46:27.697817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.05687ms","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:46:27.697854Z","caller":"traceutil/trace.go:171","msg":"trace[1920657325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"331.131292ms","start":"2026-07-09T23:46:27.366714Z","end":"2026-07-09T23:46:27.697845Z","steps":["trace[1920657325] 'agreement among raft nodes before linearized reading' (duration: 331.039859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:27.697892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:27.366692Z","time spent":"331.192165ms","remote":"127.0.0.1:50800","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-07-09T23:46:27.697899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:27.274261Z","time spent":"423.538784ms","remote":"127.0.0.1:50630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036165,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T23:46:27.698163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.533058ms","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:46:27.698268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.494029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T23:46:27.698421Z","caller":"traceutil/trace.go:171","msg":"trace[1747877710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"208.621174ms","start":"2026-07-09T23:46:27.489757Z","end":"2026-07-09T23:46:27.698378Z","steps":["trace[1747877710] 'agreement among raft nodes before linearized reading' (duration: 208.444867ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:46:27.698417Z","caller":"traceutil/trace.go:171","msg":"trace[1782279542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"331.671363ms","start":"2026-07-09T23:46:27.366577Z","end":"2026-07-09T23:46:27.698249Z","steps":["trace[1782279542] 'agreement among raft nodes before linearized reading' (duration: 331.489716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:27.698481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:27.366558Z","time spent":"331.910522ms","remote":"127.0.0.1:50800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-09T23:46:27.812291Z","caller":"traceutil/trace.go:171","msg":"trace[98134289] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"108.980909ms","start":"2026-07-09T23:46:27.703279Z","end":"2026-07-09T23:46:27.812259Z","steps":["trace[98134289] 'process raft request' (duration: 98.27162ms)","trace[98134289] 'compare' (duration: 10.514112ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:46:32.661841Z","caller":"traceutil/trace.go:171","msg":"trace[750092175] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"109.948543ms","start":"2026-07-09T23:46:32.551868Z","end":"2026-07-09T23:46:32.661817Z","steps":["trace[750092175] 'process raft request' (duration: 109.674863ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:46:32.915761Z","caller":"traceutil/trace.go:171","msg":"trace[492654988] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"241.897828ms","start":"2026-07-09T23:46:32.673846Z","end":"2026-07-09T23:46:32.915744Z","steps":["trace[492654988] 'process raft request' (duration: 151.481517ms)","trace[492654988] 'compare' (duration: 89.931353ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:46:33.134067Z","caller":"traceutil/trace.go:171","msg":"trace[1033481733] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"153.846412ms","start":"2026-07-09T23:46:32.980197Z","end":"2026-07-09T23:46:33.134044Z","steps":["trace[1033481733] 'process raft request' (duration: 141.112258ms)","trace[1033481733] 'compare' (duration: 12.60844ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:46:33.34266Z","caller":"traceutil/trace.go:171","msg":"trace[869390393] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"105.453914ms","start":"2026-07-09T23:46:33.237179Z","end":"2026-07-09T23:46:33.342633Z","steps":["trace[869390393] 'process raft request' (duration: 105.135883ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:46:33.487173Z","caller":"traceutil/trace.go:171","msg":"trace[1848914140] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"248.022823ms","start":"2026-07-09T23:46:33.239113Z","end":"2026-07-09T23:46:33.487136Z","steps":["trace[1848914140] 'process raft request' (duration: 221.191163ms)","trace[1848914140] 'compare' (duration: 26.623273ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:46:33.487351Z","caller":"traceutil/trace.go:171","msg":"trace[878639717] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"224.470912ms","start":"2026-07-09T23:46:33.262864Z","end":"2026-07-09T23:46:33.487335Z","steps":["trace[878639717] 'process raft request' (duration: 224.277895ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:46:33.487616Z","caller":"traceutil/trace.go:171","msg":"trace[474073872] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:746; }","duration":"246.445206ms","start":"2026-07-09T23:46:33.240829Z","end":"2026-07-09T23:46:33.487274Z","steps":["trace[474073872] 'read index received' (duration: 101.509661ms)","trace[474073872] 'applied index is now lower than readState.Index' (duration: 144.934335ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:46:33.487878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.233237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-09T23:46:33.487937Z","caller":"traceutil/trace.go:171","msg":"trace[1455727132] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:724; }","duration":"229.342811ms","start":"2026-07-09T23:46:33.258566Z","end":"2026-07-09T23:46:33.487909Z","steps":["trace[1455727132] 'agreement among raft nodes before linearized reading' (duration: 229.146994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:33.487927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.964626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-07-09T23:46:33.48809Z","caller":"traceutil/trace.go:171","msg":"trace[425069818] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:724; }","duration":"247.256107ms","start":"2026-07-09T23:46:33.240802Z","end":"2026-07-09T23:46:33.488058Z","steps":["trace[425069818] 'agreement among raft nodes before linearized reading' (duration: 246.699205ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:46:33.990416Z","caller":"traceutil/trace.go:171","msg":"trace[1593483364] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:749; }","duration":"439.857634ms","start":"2026-07-09T23:46:33.550517Z","end":"2026-07-09T23:46:33.990375Z","steps":["trace[1593483364] 'read index received' (duration: 6.096132ms)","trace[1593483364] 'applied index is now lower than readState.Index' (duration: 433.760402ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:46:33.990523Z","caller":"traceutil/trace.go:171","msg":"trace[8661970] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"497.899205ms","start":"2026-07-09T23:46:33.492588Z","end":"2026-07-09T23:46:33.990487Z","steps":["trace[8661970] 'process raft request' (duration: 497.542432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:33.990694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.167165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xbtsp\" ","response":"range_response_count:1 size:18333"} {"level":"warn","ts":"2026-07-09T23:46:33.990718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:33.492562Z","time spent":"498.05659ms","remote":"127.0.0.1:50800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:46:33.990768Z","caller":"traceutil/trace.go:171","msg":"trace[535127591] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xbtsp; range_end:; response_count:1; response_revision:726; }","duration":"440.282749ms","start":"2026-07-09T23:46:33.550468Z","end":"2026-07-09T23:46:33.990751Z","steps":["trace[535127591] 'agreement among raft nodes before linearized reading' (duration: 440.088382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:33.990808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:33.550448Z","time spent":"440.351932ms","remote":"127.0.0.1:50690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18356,"request content":"key:\"/registry/pods/kube-system/cilium-xbtsp\" "} {"level":"info","ts":"2026-07-09T23:46:34.124132Z","caller":"traceutil/trace.go:171","msg":"trace[1548023012] linearizableReadLoop","detail":"{readStateIndex:752; appliedIndex:751; }","duration":"133.537162ms","start":"2026-07-09T23:46:33.99057Z","end":"2026-07-09T23:46:34.124107Z","steps":["trace[1548023012] 'read index received' (duration: 133.20945ms)","trace[1548023012] 'applied index is now lower than readState.Index' (duration: 326.522µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:46:34.124455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.059017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"warn","ts":"2026-07-09T23:46:34.124457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.425113ms","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:46:34.124455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.215798ms","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:46:34.1245Z","caller":"traceutil/trace.go:171","msg":"trace[161124595] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:727; }","duration":"485.242063ms","start":"2026-07-09T23:46:33.639247Z","end":"2026-07-09T23:46:34.124489Z","steps":["trace[161124595] 'agreement among raft nodes before linearized reading' (duration: 484.962233ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:46:34.124517Z","caller":"traceutil/trace.go:171","msg":"trace[2024419062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:727; }","duration":"328.554858ms","start":"2026-07-09T23:46:33.795944Z","end":"2026-07-09T23:46:34.124499Z","steps":["trace[2024419062] 'agreement among raft nodes before linearized reading' (duration: 328.361831ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:46:34.124523Z","caller":"traceutil/trace.go:171","msg":"trace[295368193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:727; }","duration":"293.28825ms","start":"2026-07-09T23:46:33.831217Z","end":"2026-07-09T23:46:34.124505Z","steps":["trace[295368193] 'agreement among raft nodes before linearized reading' (duration: 293.075752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:34.124539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:33.639224Z","time spent":"485.306576ms","remote":"127.0.0.1:50600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188518,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-07-09T23:46:34.12455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:33.795933Z","time spent":"328.609679ms","remote":"127.0.0.1:50800","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:46:34.124843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.016674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-07-09T23:46:34.12491Z","caller":"traceutil/trace.go:171","msg":"trace[2025686101] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"559.987016ms","start":"2026-07-09T23:46:33.564902Z","end":"2026-07-09T23:46:34.124889Z","steps":["trace[2025686101] 'process raft request' (duration: 558.904717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:34.125245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:33.564875Z","time spent":"560.256745ms","remote":"127.0.0.1:50600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T23:46:34.125028Z","caller":"traceutil/trace.go:171","msg":"trace[629086202] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:727; }","duration":"286.288774ms","start":"2026-07-09T23:46:33.83871Z","end":"2026-07-09T23:46:34.124999Z","steps":["trace[629086202] 'agreement among raft nodes before linearized reading' (duration: 285.579388ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:46:34.188501Z","caller":"traceutil/trace.go:171","msg":"trace[913408034] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"182.813408ms","start":"2026-07-09T23:46:34.005668Z","end":"2026-07-09T23:46:34.188482Z","steps":["trace[913408034] 'process raft request' (duration: 182.696554ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:46:35.543557Z","caller":"traceutil/trace.go:171","msg":"trace[1114877215] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:764; }","duration":"131.703938ms","start":"2026-07-09T23:46:35.41183Z","end":"2026-07-09T23:46:35.543534Z","steps":["trace[1114877215] 'read index received' (duration: 127.53805ms)","trace[1114877215] 'applied index is now lower than readState.Index' (duration: 4.164498ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:46:35.543651Z","caller":"traceutil/trace.go:171","msg":"trace[716817208] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"164.728178ms","start":"2026-07-09T23:46:35.378897Z","end":"2026-07-09T23:46:35.543625Z","steps":["trace[716817208] 'process raft request' (duration: 160.514337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:35.543719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.854964ms","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:46:35.543761Z","caller":"traceutil/trace.go:171","msg":"trace[677128998] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:739; }","duration":"131.951138ms","start":"2026-07-09T23:46:35.4118Z","end":"2026-07-09T23:46:35.543751Z","steps":["trace[677128998] 'agreement among raft nodes before linearized reading' (duration: 131.819832ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:46:40.545589Z","caller":"traceutil/trace.go:171","msg":"trace[1970041409] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"105.691079ms","start":"2026-07-09T23:46:40.439865Z","end":"2026-07-09T23:46:40.545556Z","steps":["trace[1970041409] 'process raft request' (duration: 105.526234ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:46:42.353969Z","caller":"traceutil/trace.go:171","msg":"trace[174960166] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"117.242126ms","start":"2026-07-09T23:46:42.236692Z","end":"2026-07-09T23:46:42.353934Z","steps":["trace[174960166] 'process raft request' (duration: 79.352721ms)","trace[174960166] 'compare' (duration: 37.740911ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:46:46.133442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.412317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-zfhrv\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-07-09T23:46:46.133555Z","caller":"traceutil/trace.go:171","msg":"trace[854525866] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-zfhrv; range_end:; response_count:1; response_revision:811; }","duration":"516.553782ms","start":"2026-07-09T23:46:45.616976Z","end":"2026-07-09T23:46:46.13353Z","steps":["trace[854525866] 'range keys from in-memory index tree' (duration: 516.208181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:46:46.133602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T23:46:45.616933Z","time spent":"516.656835ms","remote":"127.0.0.1:50690","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-zfhrv\" "} {"level":"info","ts":"2026-07-09T23:47:41.004411Z","caller":"traceutil/trace.go:171","msg":"trace[2114752593] linearizableReadLoop","detail":"{readStateIndex:1167; appliedIndex:1166; }","duration":"100.744017ms","start":"2026-07-09T23:47:40.903636Z","end":"2026-07-09T23:47:41.00438Z","steps":["trace[2114752593] 'read index received' (duration: 100.583734ms)","trace[2114752593] 'applied index is now lower than readState.Index' (duration: 159.393µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:47:41.004441Z","caller":"traceutil/trace.go:171","msg":"trace[1587346594] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"113.675954ms","start":"2026-07-09T23:47:40.890732Z","end":"2026-07-09T23:47:41.004408Z","steps":["trace[1587346594] 'process raft request' (duration: 113.506531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:47:41.004588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.91756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T23:47:41.004672Z","caller":"traceutil/trace.go:171","msg":"trace[1976148641] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1125; }","duration":"101.057553ms","start":"2026-07-09T23:47:40.903597Z","end":"2026-07-09T23:47:41.004654Z","steps":["trace[1976148641] 'agreement among raft nodes before linearized reading' (duration: 100.88764ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:48:27.143473Z","caller":"traceutil/trace.go:171","msg":"trace[1687386081] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"118.468265ms","start":"2026-07-09T23:48:27.024971Z","end":"2026-07-09T23:48:27.143439Z","steps":["trace[1687386081] 'process raft request' (duration: 80.810484ms)","trace[1687386081] 'compare' (duration: 37.507808ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:48:27.314029Z","caller":"traceutil/trace.go:171","msg":"trace[1624163192] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1294; }","duration":"110.555305ms","start":"2026-07-09T23:48:27.203431Z","end":"2026-07-09T23:48:27.313987Z","steps":["trace[1624163192] 'process raft request' (duration: 39.857695ms)","trace[1624163192] 'compare' (duration: 70.457637ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T23:48:27.365118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.56313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3c1a07e2-12a3-4314-a347-e7f2f0b6e55e\" ","response":"range_response_count:1 size:3884"} {"level":"info","ts":"2026-07-09T23:48:27.365221Z","caller":"traceutil/trace.go:171","msg":"trace[557582097] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3c1a07e2-12a3-4314-a347-e7f2f0b6e55e; range_end:; response_count:1; response_revision:1295; }","duration":"113.713712ms","start":"2026-07-09T23:48:27.251481Z","end":"2026-07-09T23:48:27.365195Z","steps":["trace[557582097] 'agreement among raft nodes before linearized reading' (duration: 113.445368ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:48:31.136928Z","caller":"traceutil/trace.go:171","msg":"trace[734855968] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"161.820032ms","start":"2026-07-09T23:48:30.97508Z","end":"2026-07-09T23:48:31.1369Z","steps":["trace[734855968] 'process raft request' (duration: 161.641808ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:48:31.265247Z","caller":"traceutil/trace.go:171","msg":"trace[207651596] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"102.12369ms","start":"2026-07-09T23:48:31.163091Z","end":"2026-07-09T23:48:31.265214Z","steps":["trace[207651596] 'process raft request' (duration: 100.76154ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:48:40.003787Z","caller":"traceutil/trace.go:171","msg":"trace[144268444] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"242.274283ms","start":"2026-07-09T23:48:39.761472Z","end":"2026-07-09T23:48:40.003746Z","steps":["trace[144268444] 'process raft request' (duration: 241.952369ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:48:40.395787Z","caller":"traceutil/trace.go:171","msg":"trace[1511379799] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1411; }","duration":"118.873288ms","start":"2026-07-09T23:48:40.276893Z","end":"2026-07-09T23:48:40.395766Z","steps":["trace[1511379799] 'read index received' (duration: 118.691036ms)","trace[1511379799] 'applied index is now lower than readState.Index' (duration: 181.062µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:48:40.396032Z","caller":"traceutil/trace.go:171","msg":"trace[1502253244] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1357; }","duration":"184.912816ms","start":"2026-07-09T23:48:40.211099Z","end":"2026-07-09T23:48:40.396012Z","steps":["trace[1502253244] 'process raft request' (duration: 184.52715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:48:40.396045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.130952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2577"} {"level":"info","ts":"2026-07-09T23:48:40.396101Z","caller":"traceutil/trace.go:171","msg":"trace[1956138984] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1357; }","duration":"119.220443ms","start":"2026-07-09T23:48:40.276867Z","end":"2026-07-09T23:48:40.396088Z","steps":["trace[1956138984] 'agreement among raft nodes before linearized reading' (duration: 118.991969ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:48:40.511957Z","caller":"traceutil/trace.go:171","msg":"trace[735048146] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"108.846263ms","start":"2026-07-09T23:48:40.403084Z","end":"2026-07-09T23:48:40.51193Z","steps":["trace[735048146] 'process raft request' (duration: 106.470599ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:48:41.521008Z","caller":"traceutil/trace.go:171","msg":"trace[1914217875] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"107.789282ms","start":"2026-07-09T23:48:41.4132Z","end":"2026-07-09T23:48:41.520989Z","steps":["trace[1914217875] 'process raft request' (duration: 103.40382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:49:01.690723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.946514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking\" ","response":"range_response_count:1 size:370"} {"level":"warn","ts":"2026-07-09T23:49:01.690759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.066219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:1 size:3017"} {"level":"info","ts":"2026-07-09T23:49:01.690811Z","caller":"traceutil/trace.go:171","msg":"trace[143194855] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:1; response_revision:1431; }","duration":"177.155561ms","start":"2026-07-09T23:49:01.513643Z","end":"2026-07-09T23:49:01.690798Z","steps":["trace[143194855] 'agreement among raft nodes before linearized reading' (duration: 177.07269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:49:01.690964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.991269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/\" range_end:\"/registry/rolebindings/kube-system0\" limit:500 ","response":"range_response_count:11 size:7467"} {"level":"info","ts":"2026-07-09T23:49:01.691015Z","caller":"traceutil/trace.go:171","msg":"trace[288653869] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-apiserver-legacy-service-account-token-tracking; range_end:; response_count:1; response_revision:1431; }","duration":"158.059835ms","start":"2026-07-09T23:49:01.532719Z","end":"2026-07-09T23:49:01.690779Z","steps":["trace[288653869] 'agreement among raft nodes before linearized reading' (duration: 157.920003ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:49:01.69106Z","caller":"traceutil/trace.go:171","msg":"trace[188420445] range","detail":"{range_begin:/registry/rolebindings/kube-system/; range_end:/registry/rolebindings/kube-system0; response_count:11; response_revision:1431; }","duration":"112.132771ms","start":"2026-07-09T23:49:01.578907Z","end":"2026-07-09T23:49:01.69104Z","steps":["trace[188420445] 'agreement among raft nodes before linearized reading' (duration: 111.876938ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:49:09.169463Z","caller":"traceutil/trace.go:171","msg":"trace[560297058] linearizableReadLoop","detail":"{readStateIndex:1517; appliedIndex:1516; }","duration":"111.126855ms","start":"2026-07-09T23:49:09.058308Z","end":"2026-07-09T23:49:09.169435Z","steps":["trace[560297058] 'read index received' (duration: 110.75388ms)","trace[560297058] 'applied index is now lower than readState.Index' (duration: 371.655µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T23:49:09.169615Z","caller":"traceutil/trace.go:171","msg":"trace[1285633266] transaction","detail":"{read_only:false; response_revision:1455; number_of_response:1; }","duration":"142.453585ms","start":"2026-07-09T23:49:09.027096Z","end":"2026-07-09T23:49:09.169549Z","steps":["trace[1285633266] 'process raft request' (duration: 142.07329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T23:49:09.169705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.374428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-07-09T23:49:09.169758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.44021ms","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-07-09T23:49:09.16989Z","caller":"traceutil/trace.go:171","msg":"trace[1304270148] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:1455; }","duration":"108.632102ms","start":"2026-07-09T23:49:09.06124Z","end":"2026-07-09T23:49:09.169872Z","steps":["trace[1304270148] 'agreement among raft nodes before linearized reading' (duration: 108.427049ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T23:49:09.169813Z","caller":"traceutil/trace.go:171","msg":"trace[1336982531] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:1455; }","duration":"111.49582ms","start":"2026-07-09T23:49:09.058292Z","end":"2026-07-09T23:49:09.169788Z","steps":["trace[1336982531] 'agreement among raft nodes before linearized reading' (duration: 111.309717ms)"],"step_count":1}