{"level":"warn","ts":"2026-07-15T21:40:01.888141Z","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-15T21:40:01.888227Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.177: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.177:2380","--initial-cluster=instance=https://199.204.45.177:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.177:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.177: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-15T21:40:01.888322Z","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-15T21:40:01.888524Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-07-15T21:40:01.888566Z","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-15T21:40:01.889216Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"]} {"level":"info","ts":"2026-07-15T21:40:01.889295Z","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.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.177: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-15T21:40:01.897405Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.876169ms"} {"level":"info","ts":"2026-07-15T21:40:01.912492Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7c53a18098324dc9","cluster-id":"4717e8aa221973c8"} {"level":"info","ts":"2026-07-15T21:40:01.912575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=()"} {"level":"info","ts":"2026-07-15T21:40:01.912599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 0"} {"level":"info","ts":"2026-07-15T21:40:01.91261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7c53a18098324dc9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-15T21:40:01.912617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 1"} {"level":"info","ts":"2026-07-15T21:40:01.912648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"warn","ts":"2026-07-15T21:40:01.920599Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-15T21:40:01.922311Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-15T21:40:01.923746Z","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-15T21:40:01.928182Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7c53a18098324dc9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-15T21:40:01.928421Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7c53a18098324dc9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-15T21:40:01.92932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"info","ts":"2026-07-15T21:40:01.92958Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","added-peer-id":"7c53a18098324dc9","added-peer-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-07-15T21:40:01.928438Z","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-15T21:40:01.929737Z","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-15T21:40:01.92975Z","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-15T21:40:01.931722Z","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-15T21:40:01.931865Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-07-15T21:40:01.931907Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-07-15T21:40:01.932494Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7c53a18098324dc9","initial-advertise-peer-urls":["https://199.204.45.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-15T21:40:01.932527Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-15T21:40:02.313711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 is starting a new election at term 1"} {"level":"info","ts":"2026-07-15T21:40:02.313778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-15T21:40:02.313815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgPreVoteResp from 7c53a18098324dc9 at term 1"} {"level":"info","ts":"2026-07-15T21:40:02.313835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became candidate at term 2"} {"level":"info","ts":"2026-07-15T21:40:02.313847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgVoteResp from 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-07-15T21:40:02.31387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became leader at term 2"} {"level":"info","ts":"2026-07-15T21:40:02.313885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7c53a18098324dc9 elected leader 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-07-15T21:40:02.314598Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T21:40:02.319504Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T21:40:02.319565Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T21:40:02.319581Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T21:40:02.319623Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7c53a18098324dc9","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.177:2379]}","request-path":"/0/members/7c53a18098324dc9/attributes","cluster-id":"4717e8aa221973c8","publish-timeout":"7s"} {"level":"info","ts":"2026-07-15T21:40:02.319639Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T21:40:02.319994Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T21:40:02.321913Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.177:2379"} {"level":"info","ts":"2026-07-15T21:40:02.322157Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-15T21:40:02.329613Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-15T21:40:02.32968Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-15T21:40:05.490635Z","caller":"traceutil/trace.go:171","msg":"trace[781372785] transaction","detail":"{read_only:false; response_revision:177; number_of_response:1; }","duration":"486.092488ms","start":"2026-07-15T21:40:05.004513Z","end":"2026-07-15T21:40:05.490606Z","steps":["trace[781372785] 'process raft request' (duration: 484.255428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:05.491444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:05.004462Z","time spent":"486.350769ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":776,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-15T21:40:06.011878Z","caller":"traceutil/trace.go:171","msg":"trace[487345622] transaction","detail":"{read_only:false; response_revision:198; number_of_response:1; }","duration":"138.706579ms","start":"2026-07-15T21:40:05.873142Z","end":"2026-07-15T21:40:06.011848Z","steps":["trace[487345622] 'process raft request' (duration: 138.513541ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:06.184606Z","caller":"traceutil/trace.go:171","msg":"trace[1577654624] linearizableReadLoop","detail":"{readStateIndex:205; appliedIndex:203; }","duration":"169.163188ms","start":"2026-07-15T21:40:06.015417Z","end":"2026-07-15T21:40:06.18458Z","steps":["trace[1577654624] 'read index received' (duration: 168.977001ms)","trace[1577654624] 'applied index is now lower than readState.Index' (duration: 185.077µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:40:06.184727Z","caller":"traceutil/trace.go:171","msg":"trace[782161072] transaction","detail":"{read_only:false; response_revision:200; number_of_response:1; }","duration":"274.907149ms","start":"2026-07-15T21:40:05.909809Z","end":"2026-07-15T21:40:06.184716Z","steps":["trace[782161072] 'process raft request' (duration: 274.723072ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:06.184807Z","caller":"traceutil/trace.go:171","msg":"trace[1235153786] transaction","detail":"{read_only:false; response_revision:199; number_of_response:1; }","duration":"278.262369ms","start":"2026-07-15T21:40:05.906498Z","end":"2026-07-15T21:40:06.184761Z","steps":["trace[1235153786] 'process raft request' (duration: 277.889684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:06.184953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.521702ms","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-15T21:40:06.185068Z","caller":"traceutil/trace.go:171","msg":"trace[978347097] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:200; }","duration":"169.671287ms","start":"2026-07-15T21:40:06.015372Z","end":"2026-07-15T21:40:06.185044Z","steps":["trace[978347097] 'agreement among raft nodes before linearized reading' (duration: 169.409997ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:06.26386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.321885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T21:40:06.263937Z","caller":"traceutil/trace.go:171","msg":"trace[1303715091] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ephemeral-volume-controller; range_end:; response_count:0; response_revision:200; }","duration":"239.445801ms","start":"2026-07-15T21:40:06.024469Z","end":"2026-07-15T21:40:06.263914Z","steps":["trace[1303715091] 'agreement among raft nodes before linearized reading' (duration: 239.325005ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:06.363931Z","caller":"traceutil/trace.go:171","msg":"trace[220461501] transaction","detail":"{read_only:false; response_revision:201; number_of_response:1; }","duration":"174.440671ms","start":"2026-07-15T21:40:06.189441Z","end":"2026-07-15T21:40:06.363882Z","steps":["trace[220461501] 'process raft request' (duration: 88.596154ms)","trace[220461501] 'compare' (duration: 85.653699ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:40:06.36415Z","caller":"traceutil/trace.go:171","msg":"trace[2112557832] transaction","detail":"{read_only:false; response_revision:202; number_of_response:1; }","duration":"174.650918ms","start":"2026-07-15T21:40:06.189479Z","end":"2026-07-15T21:40:06.36413Z","steps":["trace[2112557832] 'process raft request' (duration: 174.330516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:07.908272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.357625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-15T21:40:07.908384Z","caller":"traceutil/trace.go:171","msg":"trace[342359625] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"1.539679287s","start":"2026-07-15T21:40:06.368685Z","end":"2026-07-15T21:40:07.908364Z","steps":["trace[342359625] 'process raft request' (duration: 878.016171ms)","trace[342359625] 'compare' (duration: 661.208278ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T21:40:07.90843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:06.368662Z","time spent":"1.53974879s","remote":"127.0.0.1:39030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":205,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T21:40:08.388743Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.081609966s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-15T21:40:09.674708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.285626672s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-15T21:40:09.675137Z","caller":"traceutil/trace.go:171","msg":"trace[1368964021] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"905.407324ms","start":"2026-07-15T21:40:08.769703Z","end":"2026-07-15T21:40:09.67511Z","steps":["trace[1368964021] 'process raft request' (duration: 905.199626ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:09.675167Z","caller":"traceutil/trace.go:171","msg":"trace[930048727] linearizableReadLoop","detail":"{readStateIndex:211; appliedIndex:209; }","duration":"2.656557513s","start":"2026-07-15T21:40:07.018592Z","end":"2026-07-15T21:40:09.675149Z","steps":["trace[930048727] 'read index received' (duration: 228.122268ms)","trace[930048727] 'applied index is now lower than readState.Index' (duration: 2.428433494s)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:40:09.675175Z","caller":"traceutil/trace.go:171","msg":"trace[847904148] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"2.448907909s","start":"2026-07-15T21:40:07.226238Z","end":"2026-07-15T21:40:09.675146Z","steps":["trace[847904148] 'process raft request' (duration: 2.448584288s)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:09.675215Z","caller":"traceutil/trace.go:171","msg":"trace[326405485] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"3.305043271s","start":"2026-07-15T21:40:06.370126Z","end":"2026-07-15T21:40:09.67517Z","steps":["trace[326405485] 'process raft request' (duration: 2.018869859s)","trace[326405485] 'compare' (duration: 1.285150945s)"],"step_count":2} {"level":"warn","ts":"2026-07-15T21:40:09.675241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:08.769675Z","time spent":"905.521348ms","remote":"127.0.0.1:39000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T21:40:09.675288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.656691338s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T21:40:09.675322Z","caller":"traceutil/trace.go:171","msg":"trace[1435375557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:207; }","duration":"2.656788761s","start":"2026-07-15T21:40:07.018523Z","end":"2026-07-15T21:40:09.675312Z","steps":["trace[1435375557] 'agreement among raft nodes before linearized reading' (duration: 2.656699498s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:09.67534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:07.226202Z","time spent":"2.449063215s","remote":"127.0.0.1:38862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T21:40:09.675355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:07.018494Z","time spent":"2.656851264s","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-15T21:40:09.675349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:06.370104Z","time spent":"3.305176776s","remote":"127.0.0.1:39160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T21:40:10.17548Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186479072672254,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T21:40:10.675646Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186479072672254,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T21:40:10.763276Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.09161106s","expected-duration":"1s"} {"level":"info","ts":"2026-07-15T21:40:10.7869Z","caller":"traceutil/trace.go:171","msg":"trace[1023572776] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"1.77022697s","start":"2026-07-15T21:40:09.016649Z","end":"2026-07-15T21:40:10.786876Z","steps":["trace[1023572776] 'process raft request' (duration: 1.746787272s)","trace[1023572776] 'compare' (duration: 23.291093ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T21:40:10.787032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:09.016623Z","time spent":"1.770328814s","remote":"127.0.0.1:39000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-15T21:40:10.80904Z","caller":"traceutil/trace.go:171","msg":"trace[1331805821] linearizableReadLoop","detail":"{readStateIndex:217; appliedIndex:213; }","duration":"1.133808665s","start":"2026-07-15T21:40:09.675202Z","end":"2026-07-15T21:40:10.809011Z","steps":["trace[1331805821] 'read index received' (duration: 1.088243046s)","trace[1331805821] 'applied index is now lower than readState.Index' (duration: 45.564909ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:40:10.809063Z","caller":"traceutil/trace.go:171","msg":"trace[645781531] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"1.128388504s","start":"2026-07-15T21:40:09.680659Z","end":"2026-07-15T21:40:10.809047Z","steps":["trace[645781531] 'process raft request' (duration: 1.128317482s)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:10.809106Z","caller":"traceutil/trace.go:171","msg":"trace[1947322375] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"1.129788145s","start":"2026-07-15T21:40:09.6793Z","end":"2026-07-15T21:40:10.809089Z","steps":["trace[1947322375] 'process raft request' (duration: 1.129548916s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:10.809163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:09.679276Z","time spent":"1.129852227s","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T21:40:10.809181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:09.68065Z","time spent":"1.128458016s","remote":"127.0.0.1:38908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1342,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-15T21:40:10.809354Z","caller":"traceutil/trace.go:171","msg":"trace[1447175715] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"1.129320918s","start":"2026-07-15T21:40:09.680023Z","end":"2026-07-15T21:40:10.809344Z","steps":["trace[1447175715] 'process raft request' (duration: 1.128912913s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:10.80941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:09.680009Z","time spent":"1.129375879s","remote":"127.0.0.1:38862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T21:40:10.809427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.897428212s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T21:40:10.809466Z","caller":"traceutil/trace.go:171","msg":"trace[1876306526] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:0; response_revision:211; }","duration":"2.897489005s","start":"2026-07-15T21:40:07.911961Z","end":"2026-07-15T21:40:10.80945Z","steps":["trace[1876306526] 'agreement among raft nodes before linearized reading' (duration: 2.897387951s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:10.809497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:07.911945Z","time spent":"2.897541526s","remote":"127.0.0.1:39030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" "} {"level":"warn","ts":"2026-07-15T21:40:10.809642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.790212s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T21:40:10.80968Z","caller":"traceutil/trace.go:171","msg":"trace[1945288232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:211; }","duration":"3.790277533s","start":"2026-07-15T21:40:07.019392Z","end":"2026-07-15T21:40:10.80967Z","steps":["trace[1945288232] 'agreement among raft nodes before linearized reading' (duration: 3.79021346s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:10.809711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:07.019371Z","time spent":"3.790332815s","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-15T21:40:10.809742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.440556355s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-15T21:40:10.809833Z","caller":"traceutil/trace.go:171","msg":"trace[1486575943] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:211; }","duration":"2.440694081s","start":"2026-07-15T21:40:08.369111Z","end":"2026-07-15T21:40:10.809805Z","steps":["trace[1486575943] 'agreement among raft nodes before linearized reading' (duration: 2.440515444s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:10.809883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:08.369083Z","time spent":"2.440789754s","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-15T21:40:10.810121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.87943512s","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-15T21:40:10.810329Z","caller":"traceutil/trace.go:171","msg":"trace[435304629] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:211; }","duration":"2.879772363s","start":"2026-07-15T21:40:07.930531Z","end":"2026-07-15T21:40:10.810303Z","steps":["trace[435304629] 'agreement among raft nodes before linearized reading' (duration: 2.878758556s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:10.810522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:07.930503Z","time spent":"2.879999941s","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-15T21:40:12.581338Z","caller":"traceutil/trace.go:171","msg":"trace[2126307527] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"114.501232ms","start":"2026-07-15T21:40:12.466805Z","end":"2026-07-15T21:40:12.581306Z","steps":["trace[2126307527] 'process raft request' (duration: 55.123984ms)","trace[2126307527] 'compare' (duration: 59.14996ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:40:19.032632Z","caller":"traceutil/trace.go:171","msg":"trace[1504744040] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"107.456216ms","start":"2026-07-15T21:40:18.925148Z","end":"2026-07-15T21:40:19.032604Z","steps":["trace[1504744040] 'process raft request' (duration: 107.364113ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:19.032657Z","caller":"traceutil/trace.go:171","msg":"trace[1272784019] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"109.08745ms","start":"2026-07-15T21:40:18.923533Z","end":"2026-07-15T21:40:19.03262Z","steps":["trace[1272784019] 'process raft request' (duration: 103.727123ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:19.032954Z","caller":"traceutil/trace.go:171","msg":"trace[203375581] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"107.438667ms","start":"2026-07-15T21:40:18.925405Z","end":"2026-07-15T21:40:19.032843Z","steps":["trace[203375581] 'process raft request' (duration: 107.158476ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:34.649789Z","caller":"traceutil/trace.go:171","msg":"trace[881506438] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"115.144122ms","start":"2026-07-15T21:40:34.534613Z","end":"2026-07-15T21:40:34.649757Z","steps":["trace[881506438] 'process raft request' (duration: 96.513322ms)","trace[881506438] 'compare' (duration: 18.343642ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:40:34.650255Z","caller":"traceutil/trace.go:171","msg":"trace[384960115] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"115.44288ms","start":"2026-07-15T21:40:34.534795Z","end":"2026-07-15T21:40:34.650238Z","steps":["trace[384960115] 'process raft request' (duration: 114.838593ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:34.650288Z","caller":"traceutil/trace.go:171","msg":"trace[2008672497] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"115.45022ms","start":"2026-07-15T21:40:34.534814Z","end":"2026-07-15T21:40:34.650264Z","steps":["trace[2008672497] 'process raft request' (duration: 115.142812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:41.523206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.669176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-15T21:40:41.523332Z","caller":"traceutil/trace.go:171","msg":"trace[890066217] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:492; }","duration":"216.83147ms","start":"2026-07-15T21:40:41.306457Z","end":"2026-07-15T21:40:41.523288Z","steps":["trace[890066217] 'range keys from in-memory index tree' (duration: 216.483499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:41.52349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.115883ms","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-15T21:40:41.523546Z","caller":"traceutil/trace.go:171","msg":"trace[1344462433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:492; }","duration":"182.220486ms","start":"2026-07-15T21:40:41.341312Z","end":"2026-07-15T21:40:41.523533Z","steps":["trace[1344462433] 'range keys from in-memory index tree' (duration: 182.027932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:41.523675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.271514ms","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-15T21:40:41.523692Z","caller":"traceutil/trace.go:171","msg":"trace[1218818348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:492; }","duration":"213.363846ms","start":"2026-07-15T21:40:41.310324Z","end":"2026-07-15T21:40:41.523687Z","steps":["trace[1218818348] 'range keys from in-memory index tree' (duration: 213.184522ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:42.08429Z","caller":"traceutil/trace.go:171","msg":"trace[697411086] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"556.846632ms","start":"2026-07-15T21:40:41.527413Z","end":"2026-07-15T21:40:42.08426Z","steps":["trace[697411086] 'process raft request' (duration: 556.668327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:42.084694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:41.5274Z","time spent":"556.967524ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T21:40:42.105069Z","caller":"traceutil/trace.go:171","msg":"trace[905686229] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"574.859728ms","start":"2026-07-15T21:40:41.530162Z","end":"2026-07-15T21:40:42.105022Z","steps":["trace[905686229] 'process raft request' (duration: 574.677592ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:42.105219Z","caller":"traceutil/trace.go:171","msg":"trace[1175963046] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:514; }","duration":"514.096947ms","start":"2026-07-15T21:40:41.591109Z","end":"2026-07-15T21:40:42.105205Z","steps":["trace[1175963046] 'read index received' (duration: 493.0253ms)","trace[1175963046] 'applied index is now lower than readState.Index' (duration: 21.070167ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:40:42.105324Z","caller":"traceutil/trace.go:171","msg":"trace[1665633348] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"574.930869ms","start":"2026-07-15T21:40:41.530345Z","end":"2026-07-15T21:40:42.105276Z","steps":["trace[1665633348] 'process raft request' (duration: 574.6074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:42.105374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.243431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T21:40:42.105407Z","caller":"traceutil/trace.go:171","msg":"trace[691586254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:495; }","duration":"514.325543ms","start":"2026-07-15T21:40:41.591072Z","end":"2026-07-15T21:40:42.105397Z","steps":["trace[691586254] 'agreement among raft nodes before linearized reading' (duration: 514.18056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:42.105443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:41.591054Z","time spent":"514.381914ms","remote":"127.0.0.1:38770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-15T21:40:42.1055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:41.530331Z","time spent":"575.075153ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T21:40:42.105763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:41.530137Z","time spent":"575.026261ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T21:40:47.815533Z","caller":"traceutil/trace.go:171","msg":"trace[1521069933] transaction","detail":"{read_only:false; number_of_response:0; response_revision:564; }","duration":"119.396263ms","start":"2026-07-15T21:40:47.696112Z","end":"2026-07-15T21:40:47.815508Z","steps":["trace[1521069933] 'process raft request' (duration: 119.191738ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:47.8206Z","caller":"traceutil/trace.go:171","msg":"trace[2075489854] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"105.691567ms","start":"2026-07-15T21:40:47.714846Z","end":"2026-07-15T21:40:47.820537Z","steps":["trace[2075489854] 'process raft request' (duration: 100.640189ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:48.374477Z","caller":"traceutil/trace.go:171","msg":"trace[1484492005] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:613; }","duration":"175.207241ms","start":"2026-07-15T21:40:48.199245Z","end":"2026-07-15T21:40:48.374453Z","steps":["trace[1484492005] 'read index received' (duration: 116.532054ms)","trace[1484492005] 'applied index is now lower than readState.Index' (duration: 58.674287ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:40:48.374617Z","caller":"traceutil/trace.go:171","msg":"trace[418276985] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"195.973149ms","start":"2026-07-15T21:40:48.178631Z","end":"2026-07-15T21:40:48.374604Z","steps":["trace[418276985] 'process raft request' (duration: 137.152379ms)","trace[418276985] 'compare' (duration: 58.405649ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:40:48.374784Z","caller":"traceutil/trace.go:171","msg":"trace[1089972436] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"193.513108ms","start":"2026-07-15T21:40:48.181221Z","end":"2026-07-15T21:40:48.374734Z","steps":["trace[1089972436] 'process raft request' (duration: 193.121337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:48.374913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.619511ms","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-15T21:40:48.375084Z","caller":"traceutil/trace.go:171","msg":"trace[1678183329] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:590; }","duration":"175.853097ms","start":"2026-07-15T21:40:48.199209Z","end":"2026-07-15T21:40:48.375062Z","steps":["trace[1678183329] 'agreement among raft nodes before linearized reading' (duration: 175.497778ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:48.383693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.286312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-15T21:40:48.383797Z","caller":"traceutil/trace.go:171","msg":"trace[879493348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"179.439986ms","start":"2026-07-15T21:40:48.204344Z","end":"2026-07-15T21:40:48.383784Z","steps":["trace[879493348] 'agreement among raft nodes before linearized reading' (duration: 179.287502ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:48.384043Z","caller":"traceutil/trace.go:171","msg":"trace[1100241707] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"157.833936ms","start":"2026-07-15T21:40:48.226199Z","end":"2026-07-15T21:40:48.384033Z","steps":["trace[1100241707] 'process raft request' (duration: 157.389905ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:48.384313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.36683ms","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-15T21:40:48.38439Z","caller":"traceutil/trace.go:171","msg":"trace[1041910174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"140.473021ms","start":"2026-07-15T21:40:48.243909Z","end":"2026-07-15T21:40:48.384382Z","steps":["trace[1041910174] 'agreement among raft nodes before linearized reading' (duration: 140.294537ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:48.93701Z","caller":"traceutil/trace.go:171","msg":"trace[320952084] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"423.55785ms","start":"2026-07-15T21:40:48.513417Z","end":"2026-07-15T21:40:48.936975Z","steps":["trace[320952084] 'process raft request' (duration: 390.190465ms)","trace[320952084] 'compare' (duration: 33.148259ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T21:40:48.937201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:48.513397Z","time spent":"423.716653ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T21:40:48.961181Z","caller":"traceutil/trace.go:171","msg":"trace[827638608] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"318.947174ms","start":"2026-07-15T21:40:48.642211Z","end":"2026-07-15T21:40:48.961158Z","steps":["trace[827638608] 'process raft request' (duration: 318.672088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:48.961328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:48.642186Z","time spent":"319.082707ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T21:40:51.768557Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186479072673107,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T21:40:52.26873Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186479072673107,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T21:40:52.635066Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.658533549s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-15T21:40:52.767221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.770821ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-15T21:40:52.773713Z","caller":"traceutil/trace.go:171","msg":"trace[186580210] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"1.652422886s","start":"2026-07-15T21:40:51.121275Z","end":"2026-07-15T21:40:52.773698Z","steps":["trace[186580210] 'process raft request' (duration: 1.652224112s)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:52.773709Z","caller":"traceutil/trace.go:171","msg":"trace[716714757] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:678; }","duration":"1.506125174s","start":"2026-07-15T21:40:51.26756Z","end":"2026-07-15T21:40:52.773685Z","steps":["trace[716714757] 'read index received' (duration: 1.367928399s)","trace[716714757] 'applied index is now lower than readState.Index' (duration: 138.195615ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:40:52.773732Z","caller":"traceutil/trace.go:171","msg":"trace[906997902] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"251.741086ms","start":"2026-07-15T21:40:52.521974Z","end":"2026-07-15T21:40:52.773715Z","steps":["trace[906997902] 'process raft request' (duration: 251.666834ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:52.773796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:51.121251Z","time spent":"1.652503618s","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T21:40:52.773908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.455662196s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T21:40:52.773949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.506373541s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-15T21:40:52.773972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.734917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T21:40:52.773982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.603031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-15T21:40:52.773987Z","caller":"traceutil/trace.go:171","msg":"trace[148303845] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:654; }","duration":"1.506460862s","start":"2026-07-15T21:40:51.267514Z","end":"2026-07-15T21:40:52.773974Z","steps":["trace[148303845] 'agreement among raft nodes before linearized reading' (duration: 1.506290598s)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:52.774012Z","caller":"traceutil/trace.go:171","msg":"trace[907536595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"181.797427ms","start":"2026-07-15T21:40:52.592201Z","end":"2026-07-15T21:40:52.773999Z","steps":["trace[907536595] 'agreement among raft nodes before linearized reading' (duration: 181.739946ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:52.774018Z","caller":"traceutil/trace.go:171","msg":"trace[1709504086] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:654; }","duration":"345.640252ms","start":"2026-07-15T21:40:52.428365Z","end":"2026-07-15T21:40:52.774006Z","steps":["trace[1709504086] 'agreement among raft nodes before linearized reading' (duration: 345.511228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:52.774026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:51.267494Z","time spent":"1.506524124s","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-15T21:40:52.774047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:52.428311Z","time spent":"345.728903ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-15T21:40:52.774102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.230789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-15T21:40:52.774154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.255104363s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T21:40:52.774163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055846072s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"warn","ts":"2026-07-15T21:40:52.774171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.776201ms","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-15T21:40:52.774196Z","caller":"traceutil/trace.go:171","msg":"trace[1933441320] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:654; }","duration":"1.055912714s","start":"2026-07-15T21:40:51.718273Z","end":"2026-07-15T21:40:52.774186Z","steps":["trace[1933441320] 'agreement among raft nodes before linearized reading' (duration: 1.055835702s)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:52.774201Z","caller":"traceutil/trace.go:171","msg":"trace[984834233] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:654; }","duration":"1.255173065s","start":"2026-07-15T21:40:51.519006Z","end":"2026-07-15T21:40:52.774179Z","steps":["trace[984834233] 'agreement among raft nodes before linearized reading' (duration: 1.255125534s)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:52.774182Z","caller":"traceutil/trace.go:171","msg":"trace[1406324075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:654; }","duration":"325.329601ms","start":"2026-07-15T21:40:52.448835Z","end":"2026-07-15T21:40:52.774165Z","steps":["trace[1406324075] 'agreement among raft nodes before linearized reading' (duration: 325.154287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:52.774218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:51.718254Z","time spent":"1.055960354s","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5199,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"info","ts":"2026-07-15T21:40:52.774228Z","caller":"traceutil/trace.go:171","msg":"trace[1511766990] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:654; }","duration":"848.875313ms","start":"2026-07-15T21:40:51.925336Z","end":"2026-07-15T21:40:52.774212Z","steps":["trace[1511766990] 'agreement among raft nodes before linearized reading' (duration: 848.683989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:52.774234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:51.518984Z","time spent":"1.255244476s","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-07-15T21:40:52.774243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:52.448829Z","time spent":"325.406663ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-15T21:40:52.774263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:51.925313Z","time spent":"848.941864ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-15T21:40:52.774234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.994308ms","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-15T21:40:52.774299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.77011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-07-15T21:40:52.774318Z","caller":"traceutil/trace.go:171","msg":"trace[290896746] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:654; }","duration":"855.825762ms","start":"2026-07-15T21:40:51.918486Z","end":"2026-07-15T21:40:52.774312Z","steps":["trace[290896746] 'agreement among raft nodes before linearized reading' (duration: 855.774231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:52.774319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.711239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-07-15T21:40:52.774318Z","caller":"traceutil/trace.go:171","msg":"trace[891064786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:654; }","duration":"331.098401ms","start":"2026-07-15T21:40:52.44321Z","end":"2026-07-15T21:40:52.774308Z","steps":["trace[891064786] 'agreement among raft nodes before linearized reading' (duration: 330.994448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:52.774335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:51.918466Z","time spent":"855.864643ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5608,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"info","ts":"2026-07-15T21:40:52.774344Z","caller":"traceutil/trace.go:171","msg":"trace[1841358027] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:654; }","duration":"455.735369ms","start":"2026-07-15T21:40:52.318599Z","end":"2026-07-15T21:40:52.774335Z","steps":["trace[1841358027] 'agreement among raft nodes before linearized reading' (duration: 455.672538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:52.774347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:52.443199Z","time spent":"331.140661ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-15T21:40:52.774388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:52.318535Z","time spent":"455.847161ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4105,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"info","ts":"2026-07-15T21:40:52.773933Z","caller":"traceutil/trace.go:171","msg":"trace[996217602] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:654; }","duration":"1.455723758s","start":"2026-07-15T21:40:51.318201Z","end":"2026-07-15T21:40:52.773925Z","steps":["trace[996217602] 'agreement among raft nodes before linearized reading' (duration: 1.455679187s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:52.774433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:51.318182Z","time spent":"1.45624595s","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-07-15T21:40:52.774433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.838212ms","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-15T21:40:52.775173Z","caller":"traceutil/trace.go:171","msg":"trace[1246765409] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:654; }","duration":"656.940914ms","start":"2026-07-15T21:40:52.117556Z","end":"2026-07-15T21:40:52.774497Z","steps":["trace[1246765409] 'agreement among raft nodes before linearized reading' (duration: 656.828092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:52.775214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:52.117536Z","time spent":"657.669222ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4202,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"info","ts":"2026-07-15T21:40:53.88852Z","caller":"traceutil/trace.go:171","msg":"trace[1505167925] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"131.755362ms","start":"2026-07-15T21:40:53.756736Z","end":"2026-07-15T21:40:53.888491Z","steps":["trace[1505167925] 'process raft request' (duration: 131.144108ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:53.901826Z","caller":"traceutil/trace.go:171","msg":"trace[81552524] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"102.7797ms","start":"2026-07-15T21:40:53.79903Z","end":"2026-07-15T21:40:53.90181Z","steps":["trace[81552524] 'process raft request' (duration: 102.637806ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:55.648419Z","caller":"traceutil/trace.go:171","msg":"trace[2076261379] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"105.613204ms","start":"2026-07-15T21:40:55.542769Z","end":"2026-07-15T21:40:55.648382Z","steps":["trace[2076261379] 'process raft request' (duration: 20.318879ms)","trace[2076261379] 'compare' (duration: 85.06714ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:40:57.985065Z","caller":"traceutil/trace.go:171","msg":"trace[487321755] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"938.576195ms","start":"2026-07-15T21:40:57.046465Z","end":"2026-07-15T21:40:57.985041Z","steps":["trace[487321755] 'process raft request' (duration: 938.074914ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:57.985226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:57.046426Z","time spent":"938.719219ms","remote":"127.0.0.1:38894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T21:40:57.990025Z","caller":"traceutil/trace.go:171","msg":"trace[972161737] linearizableReadLoop","detail":"{readStateIndex:731; appliedIndex:731; }","duration":"397.627195ms","start":"2026-07-15T21:40:57.592387Z","end":"2026-07-15T21:40:57.990014Z","steps":["trace[972161737] 'read index received' (duration: 397.623975ms)","trace[972161737] 'applied index is now lower than readState.Index' (duration: 2.56µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T21:40:57.990078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.696067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T21:40:57.990098Z","caller":"traceutil/trace.go:171","msg":"trace[1079513130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:703; }","duration":"397.754828ms","start":"2026-07-15T21:40:57.592337Z","end":"2026-07-15T21:40:57.990092Z","steps":["trace[1079513130] 'agreement among raft nodes before linearized reading' (duration: 397.717417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:57.990118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:57.592316Z","time spent":"397.7976ms","remote":"127.0.0.1:38770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-15T21:41:00.092768Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186479072673245,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-15T21:41:00.128848Z","caller":"traceutil/trace.go:171","msg":"trace[1008859538] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:745; }","duration":"536.370296ms","start":"2026-07-15T21:40:59.592454Z","end":"2026-07-15T21:41:00.128824Z","steps":["trace[1008859538] 'read index received' (duration: 536.085229ms)","trace[1008859538] 'applied index is now lower than readState.Index' (duration: 284.137µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T21:41:00.128993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.527359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T21:41:00.12903Z","caller":"traceutil/trace.go:171","msg":"trace[2058660102] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:718; }","duration":"536.620841ms","start":"2026-07-15T21:40:59.5924Z","end":"2026-07-15T21:41:00.129021Z","steps":["trace[2058660102] 'agreement among raft nodes before linearized reading' (duration: 536.519349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:00.129068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:59.592383Z","time spent":"536.677983ms","remote":"127.0.0.1:38776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-15T21:41:00.129288Z","caller":"traceutil/trace.go:171","msg":"trace[2107344736] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"632.908402ms","start":"2026-07-15T21:40:59.496348Z","end":"2026-07-15T21:41:00.129256Z","steps":["trace[2107344736] 'process raft request' (duration: 632.349069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:00.129459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:59.496316Z","time spent":"633.057545ms","remote":"127.0.0.1:39000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T21:41:01.131832Z","caller":"traceutil/trace.go:171","msg":"trace[1130632745] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"262.20319ms","start":"2026-07-15T21:41:00.869606Z","end":"2026-07-15T21:41:01.131809Z","steps":["trace[1130632745] 'process raft request' (duration: 262.078868ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:41:01.25934Z","caller":"traceutil/trace.go:171","msg":"trace[2042973323] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"389.638522ms","start":"2026-07-15T21:41:00.869663Z","end":"2026-07-15T21:41:01.259302Z","steps":["trace[2042973323] 'process raft request' (duration: 299.68437ms)","trace[2042973323] 'compare' (duration: 89.764008ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T21:41:01.259617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:00.869637Z","time spent":"389.832206ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T21:41:01.405861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.587189ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-15T21:41:01.406202Z","caller":"traceutil/trace.go:171","msg":"trace[1232036358] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:746; }","duration":"518.033412ms","start":"2026-07-15T21:41:00.888081Z","end":"2026-07-15T21:41:01.406114Z","steps":["trace[1232036358] 'read index received' (duration: 243.648023ms)","trace[1232036358] 'applied index is now lower than readState.Index' (duration: 274.383419ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T21:41:01.406378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.250856ms","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-15T21:41:01.406528Z","caller":"traceutil/trace.go:171","msg":"trace[346417995] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:720; }","duration":"518.462372ms","start":"2026-07-15T21:41:00.888046Z","end":"2026-07-15T21:41:01.406508Z","steps":["trace[346417995] 'agreement among raft nodes before linearized reading' (duration: 518.168435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:01.406588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:00.888033Z","time spent":"518.542784ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-15T21:41:01.406872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.219386ms","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-15T21:41:01.406937Z","caller":"traceutil/trace.go:171","msg":"trace[100501311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:720; }","duration":"518.312557ms","start":"2026-07-15T21:41:00.888609Z","end":"2026-07-15T21:41:01.406921Z","steps":["trace[100501311] 'agreement among raft nodes before linearized reading' (duration: 518.058142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:01.406976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:00.888597Z","time spent":"518.369639ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-15T21:41:01.406946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.754048ms","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-15T21:41:01.407051Z","caller":"traceutil/trace.go:171","msg":"trace[1920030895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:720; }","duration":"518.920922ms","start":"2026-07-15T21:41:00.888105Z","end":"2026-07-15T21:41:01.407026Z","steps":["trace[1920030895] 'agreement among raft nodes before linearized reading' (duration: 518.502893ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:01.407114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:00.888075Z","time spent":"519.028744ms","remote":"127.0.0.1:39096","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-15T21:41:01.803498Z","caller":"traceutil/trace.go:171","msg":"trace[894479838] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"391.388996ms","start":"2026-07-15T21:41:01.412082Z","end":"2026-07-15T21:41:01.803471Z","steps":["trace[894479838] 'process raft request' (duration: 343.522176ms)","trace[894479838] 'compare' (duration: 47.694416ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T21:41:01.80367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:01.412062Z","time spent":"391.531179ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T21:41:01.803794Z","caller":"traceutil/trace.go:171","msg":"trace[2035489451] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"391.312814ms","start":"2026-07-15T21:41:01.412379Z","end":"2026-07-15T21:41:01.803692Z","steps":["trace[2035489451] 'process raft request' (duration: 391.050288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:01.804272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:01.41237Z","time spent":"391.632541ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T21:41:03.76392Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186479072673262,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T21:41:04.264331Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186479072673262,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T21:41:04.764953Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186479072673262,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T21:41:05.266078Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186479072673262,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T21:41:05.592303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999856309s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-15T21:41:05.592369Z","caller":"traceutil/trace.go:171","msg":"trace[931563684] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999979791s","start":"2026-07-15T21:41:03.592375Z","end":"2026-07-15T21:41:05.592355Z","steps":["trace[931563684] 'agreement among raft nodes before linearized reading' (duration: 1.99989611s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:05.59241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:03.59235Z","time spent":"2.000051102s","remote":"127.0.0.1:38770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/15 21:41:05 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-15T21:41:05.766581Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186479072673262,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T21:41:06.267315Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186479072673262,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T21:41:06.522551Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.381053155s","expected-duration":"1s"} {"level":"info","ts":"2026-07-15T21:41:06.522948Z","caller":"traceutil/trace.go:171","msg":"trace[142601906] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"3.381541166s","start":"2026-07-15T21:41:03.141386Z","end":"2026-07-15T21:41:06.522927Z","steps":["trace[142601906] 'process raft request' (duration: 3.381394543s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:06.523085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:03.141368Z","time spent":"3.381648808s","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T21:41:06.529977Z","caller":"traceutil/trace.go:171","msg":"trace[234864571] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"3.249424524s","start":"2026-07-15T21:41:03.280524Z","end":"2026-07-15T21:41:06.529949Z","steps":["trace[234864571] 'process raft request' (duration: 3.24923371s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:06.530185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:03.2805Z","time spent":"3.249571257s","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T21:41:06.530199Z","caller":"traceutil/trace.go:171","msg":"trace[1382929182] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:752; }","duration":"3.267006351s","start":"2026-07-15T21:41:03.263169Z","end":"2026-07-15T21:41:06.530176Z","steps":["trace[1382929182] 'read index received' (duration: 3.259656193s)","trace[1382929182] 'applied index is now lower than readState.Index' (duration: 7.348068ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:41:06.530277Z","caller":"traceutil/trace.go:171","msg":"trace[1632190203] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"1.645315053s","start":"2026-07-15T21:41:04.884915Z","end":"2026-07-15T21:41:06.530231Z","steps":["trace[1632190203] 'process raft request' (duration: 1.644976654s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:06.530418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.454343653s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-15T21:41:06.530449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.267283108s","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-15T21:41:06.530463Z","caller":"traceutil/trace.go:171","msg":"trace[1848633123] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:726; }","duration":"2.454428074s","start":"2026-07-15T21:41:04.076022Z","end":"2026-07-15T21:41:06.53045Z","steps":["trace[1848633123] 'agreement among raft nodes before linearized reading' (duration: 2.454354943s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:06.530499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:04.076006Z","time spent":"2.454484096s","remote":"127.0.0.1:39072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":1,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-07-15T21:41:06.5305Z","caller":"traceutil/trace.go:171","msg":"trace[579668003] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:726; }","duration":"3.267372849s","start":"2026-07-15T21:41:03.263112Z","end":"2026-07-15T21:41:06.530485Z","steps":["trace[579668003] 'agreement among raft nodes before linearized reading' (duration: 3.267176075s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:06.5305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.003468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-15T21:41:06.5305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:04.88489Z","time spent":"1.645501956s","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T21:41:06.530545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:03.263089Z","time spent":"3.267443841s","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-15T21:41:06.530558Z","caller":"traceutil/trace.go:171","msg":"trace[1728549641] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:726; }","duration":"426.059599ms","start":"2026-07-15T21:41:06.104482Z","end":"2026-07-15T21:41:06.530542Z","steps":["trace[1728549641] 'agreement among raft nodes before linearized reading' (duration: 425.966387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:06.530535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.059778178s","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-15T21:41:06.5306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:06.104444Z","time spent":"426.142861ms","remote":"127.0.0.1:38920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-07-15T21:41:06.530416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.606047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T21:41:06.530645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.556700407s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T21:41:06.530671Z","caller":"traceutil/trace.go:171","msg":"trace[905157573] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:726; }","duration":"1.556726488s","start":"2026-07-15T21:41:04.973938Z","end":"2026-07-15T21:41:06.530664Z","steps":["trace[905157573] 'agreement among raft nodes before linearized reading' (duration: 1.556679317s)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:41:06.530666Z","caller":"traceutil/trace.go:171","msg":"trace[1723681652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:726; }","duration":"937.887664ms","start":"2026-07-15T21:41:05.592765Z","end":"2026-07-15T21:41:06.530653Z","steps":["trace[1723681652] 'agreement among raft nodes before linearized reading' (duration: 937.578227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:06.53069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:04.973886Z","time spent":"1.556798199s","remote":"127.0.0.1:39000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-07-15T21:41:06.530698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.728741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-15T21:41:06.530711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:05.592749Z","time spent":"937.951995ms","remote":"127.0.0.1:38776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-15T21:41:06.530739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.723372292s","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-15T21:41:06.530743Z","caller":"traceutil/trace.go:171","msg":"trace[1943739343] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:726; }","duration":"566.831403ms","start":"2026-07-15T21:41:05.9639Z","end":"2026-07-15T21:41:06.530731Z","steps":["trace[1943739343] 'agreement among raft nodes before linearized reading' (duration: 566.753462ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:41:06.530772Z","caller":"traceutil/trace.go:171","msg":"trace[777970512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:726; }","duration":"2.723444853s","start":"2026-07-15T21:41:03.807318Z","end":"2026-07-15T21:41:06.530763Z","steps":["trace[777970512] 'agreement among raft nodes before linearized reading' (duration: 2.723345281s)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:41:06.530628Z","caller":"traceutil/trace.go:171","msg":"trace[264848415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:726; }","duration":"3.059922921s","start":"2026-07-15T21:41:03.47068Z","end":"2026-07-15T21:41:06.530603Z","steps":["trace[264848415] 'agreement among raft nodes before linearized reading' (duration: 3.059659656s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:06.530748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.722550564s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-15T21:41:06.530806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:03.807298Z","time spent":"2.723499515s","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-15T21:41:06.530811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:03.470663Z","time spent":"3.060140957s","remote":"127.0.0.1:39096","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-15T21:41:06.530853Z","caller":"traceutil/trace.go:171","msg":"trace[75172861] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:726; }","duration":"2.722713287s","start":"2026-07-15T21:41:03.808116Z","end":"2026-07-15T21:41:06.530829Z","steps":["trace[75172861] 'agreement among raft nodes before linearized reading' (duration: 2.72234794s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:06.530907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:03.808089Z","time spent":"2.72280758s","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-15T21:41:06.530928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.956515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.177\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-15T21:41:06.530785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:05.963872Z","time spent":"566.901205ms","remote":"127.0.0.1:39200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-07-15T21:41:06.530982Z","caller":"traceutil/trace.go:171","msg":"trace[1699743469] range","detail":"{range_begin:/registry/masterleases/199.204.45.177; range_end:; response_count:1; response_revision:726; }","duration":"920.056397ms","start":"2026-07-15T21:41:05.610907Z","end":"2026-07-15T21:41:06.530963Z","steps":["trace[1699743469] 'agreement among raft nodes before linearized reading' (duration: 919.916894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:06.531027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:05.610884Z","time spent":"920.130209ms","remote":"127.0.0.1:38810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.177\" "} {"level":"warn","ts":"2026-07-15T21:41:21.462229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.843448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T21:41:21.462318Z","caller":"traceutil/trace.go:171","msg":"trace[1096931297] linearizableReadLoop","detail":"{readStateIndex:862; appliedIndex:861; }","duration":"707.636467ms","start":"2026-07-15T21:41:20.75467Z","end":"2026-07-15T21:41:21.462306Z","steps":["trace[1096931297] 'read index received' (duration: 109.683566ms)","trace[1096931297] 'applied index is now lower than readState.Index' (duration: 597.951851ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:41:21.462387Z","caller":"traceutil/trace.go:171","msg":"trace[369923056] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"737.24413ms","start":"2026-07-15T21:41:20.725084Z","end":"2026-07-15T21:41:21.462328Z","steps":["trace[369923056] 'process raft request' (duration: 139.240919ms)","trace[369923056] 'compare' (duration: 597.766857ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T21:41:21.46249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.82737ms","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-15T21:41:21.462518Z","caller":"traceutil/trace.go:171","msg":"trace[819600276] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:828; }","duration":"707.888651ms","start":"2026-07-15T21:41:20.754622Z","end":"2026-07-15T21:41:21.462511Z","steps":["trace[819600276] 'agreement among raft nodes before linearized reading' (duration: 707.787059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:21.462538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:20.7546Z","time spent":"707.934012ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-15T21:41:21.46252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:20.725058Z","time spent":"737.377842ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T21:41:21.462663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.058247ms","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-15T21:41:21.462873Z","caller":"traceutil/trace.go:171","msg":"trace[1969588517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:828; }","duration":"704.297911ms","start":"2026-07-15T21:41:20.75855Z","end":"2026-07-15T21:41:21.462848Z","steps":["trace[1969588517] 'agreement among raft nodes before linearized reading' (duration: 703.947424ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:21.462942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:20.758537Z","time spent":"704.388753ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-15T21:41:25.166958Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186479072673583,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T21:41:25.667282Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186479072673583,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T21:41:26.168189Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186479072673583,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T21:41:26.668307Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186479072673583,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T21:41:26.803636Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.116683983s","expected-duration":"1s"} {"level":"info","ts":"2026-07-15T21:41:26.804108Z","caller":"traceutil/trace.go:171","msg":"trace[858535170] linearizableReadLoop","detail":"{readStateIndex:879; appliedIndex:878; }","duration":"2.137527387s","start":"2026-07-15T21:41:24.666531Z","end":"2026-07-15T21:41:26.804058Z","steps":["trace[858535170] 'read index received' (duration: 2.137243582s)","trace[858535170] 'applied index is now lower than readState.Index' (duration: 282.785µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:41:26.804463Z","caller":"traceutil/trace.go:171","msg":"trace[1719229907] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"2.196154493s","start":"2026-07-15T21:41:24.608289Z","end":"2026-07-15T21:41:26.804443Z","steps":["trace[1719229907] 'process raft request' (duration: 2.195571563s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:26.804523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.137962625s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-15T21:41:26.804592Z","caller":"traceutil/trace.go:171","msg":"trace[1028298942] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:844; }","duration":"2.138102018s","start":"2026-07-15T21:41:24.666471Z","end":"2026-07-15T21:41:26.804574Z","steps":["trace[1028298942] 'agreement among raft nodes before linearized reading' (duration: 2.137854333s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:26.804643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:24.666445Z","time spent":"2.138186479s","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-15T21:41:26.80461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.098627502s","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-15T21:41:26.804674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.037156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T21:41:26.804712Z","caller":"traceutil/trace.go:171","msg":"trace[1134137949] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:844; }","duration":"2.098807276s","start":"2026-07-15T21:41:24.705886Z","end":"2026-07-15T21:41:26.804693Z","steps":["trace[1134137949] 'agreement among raft nodes before linearized reading' (duration: 2.098524141s)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:41:26.804738Z","caller":"traceutil/trace.go:171","msg":"trace[272315332] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:844; }","duration":"142.112687ms","start":"2026-07-15T21:41:26.662611Z","end":"2026-07-15T21:41:26.804724Z","steps":["trace[272315332] 'agreement among raft nodes before linearized reading' (duration: 141.909544ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:26.804761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:24.705865Z","time spent":"2.098884577s","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-15T21:41:26.804759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.322826249s","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-15T21:41:26.804876Z","caller":"traceutil/trace.go:171","msg":"trace[72529750] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:844; }","duration":"1.322989472s","start":"2026-07-15T21:41:25.481863Z","end":"2026-07-15T21:41:26.804852Z","steps":["trace[72529750] 'agreement among raft nodes before linearized reading' (duration: 1.322605955s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:26.804941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:25.481853Z","time spent":"1.323077563s","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-15T21:41:26.804887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.297623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T21:41:26.805158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.330201418s","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-15T21:41:26.805161Z","caller":"traceutil/trace.go:171","msg":"trace[1111286860] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:844; }","duration":"212.712991ms","start":"2026-07-15T21:41:26.592414Z","end":"2026-07-15T21:41:26.805127Z","steps":["trace[1111286860] 'agreement among raft nodes before linearized reading' (duration: 212.259243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:26.804964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.319765492s","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-15T21:41:26.805226Z","caller":"traceutil/trace.go:171","msg":"trace[2068121932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:844; }","duration":"1.33033984s","start":"2026-07-15T21:41:25.474874Z","end":"2026-07-15T21:41:26.805214Z","steps":["trace[2068121932] 'agreement among raft nodes before linearized reading' (duration: 1.329998194s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:26.805332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:25.474853Z","time spent":"1.330455602s","remote":"127.0.0.1:39096","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-15T21:41:26.805269Z","caller":"traceutil/trace.go:171","msg":"trace[2140441367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:844; }","duration":"1.320103878s","start":"2026-07-15T21:41:25.485152Z","end":"2026-07-15T21:41:26.805256Z","steps":["trace[2140441367] 'agreement among raft nodes before linearized reading' (duration: 1.319749762s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:26.805542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:25.485141Z","time spent":"1.320377113s","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-15T21:41:26.804756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:24.608271Z","time spent":"2.196273775s","remote":"127.0.0.1:39000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T21:41:26.80589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.193891993s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.177\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-15T21:41:26.805961Z","caller":"traceutil/trace.go:171","msg":"trace[1044870167] range","detail":"{range_begin:/registry/masterleases/199.204.45.177; range_end:; response_count:1; response_revision:844; }","duration":"1.193978114s","start":"2026-07-15T21:41:25.611969Z","end":"2026-07-15T21:41:26.805947Z","steps":["trace[1044870167] 'agreement among raft nodes before linearized reading' (duration: 1.193334162s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:26.805992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:25.61196Z","time spent":"1.194024025s","remote":"127.0.0.1:38810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.177\" "} {"level":"info","ts":"2026-07-15T21:41:41.060519Z","caller":"traceutil/trace.go:171","msg":"trace[700640179] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1045; }","duration":"136.908392ms","start":"2026-07-15T21:41:40.92359Z","end":"2026-07-15T21:41:41.060498Z","steps":["trace[700640179] 'read index received' (duration: 136.816631ms)","trace[700640179] 'applied index is now lower than readState.Index' (duration: 90.781µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:41:41.060595Z","caller":"traceutil/trace.go:171","msg":"trace[1438339520] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"143.910241ms","start":"2026-07-15T21:41:40.916644Z","end":"2026-07-15T21:41:41.060554Z","steps":["trace[1438339520] 'process raft request' (duration: 143.709468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:41.06083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.469462ms","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-15T21:41:41.060892Z","caller":"traceutil/trace.go:171","msg":"trace[2024510905] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1008; }","duration":"130.570603ms","start":"2026-07-15T21:41:40.93031Z","end":"2026-07-15T21:41:41.060881Z","steps":["trace[2024510905] 'agreement among raft nodes before linearized reading' (duration: 130.396191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:41.060925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.863424ms","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-15T21:41:41.061102Z","caller":"traceutil/trace.go:171","msg":"trace[532153943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1008; }","duration":"136.051357ms","start":"2026-07-15T21:41:40.924985Z","end":"2026-07-15T21:41:41.061037Z","steps":["trace[532153943] 'agreement among raft nodes before linearized reading' (duration: 135.728632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:41.061016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.1588ms","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-15T21:41:41.061205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.611674ms","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-15T21:41:41.061249Z","caller":"traceutil/trace.go:171","msg":"trace[1500195058] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1008; }","duration":"136.524195ms","start":"2026-07-15T21:41:40.924702Z","end":"2026-07-15T21:41:41.061226Z","steps":["trace[1500195058] 'agreement among raft nodes before linearized reading' (duration: 135.973346ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:41:41.061288Z","caller":"traceutil/trace.go:171","msg":"trace[1647715714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1008; }","duration":"137.709225ms","start":"2026-07-15T21:41:40.923548Z","end":"2026-07-15T21:41:41.061257Z","steps":["trace[1647715714] 'agreement among raft nodes before linearized reading' (duration: 137.078174ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:43:40.151303Z","caller":"traceutil/trace.go:171","msg":"trace[1957547959] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"105.739594ms","start":"2026-07-15T21:43:40.045543Z","end":"2026-07-15T21:43:40.151283Z","steps":["trace[1957547959] 'process raft request' (duration: 105.503971ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:43:40.370954Z","caller":"traceutil/trace.go:171","msg":"trace[1264083981] linearizableReadLoop","detail":"{readStateIndex:1523; appliedIndex:1522; }","duration":"212.872364ms","start":"2026-07-15T21:43:40.158055Z","end":"2026-07-15T21:43:40.370927Z","steps":["trace[1264083981] 'read index received' (duration: 162.047356ms)","trace[1264083981] 'applied index is now lower than readState.Index' (duration: 50.824168ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:43:40.371031Z","caller":"traceutil/trace.go:171","msg":"trace[1955584709] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"216.089771ms","start":"2026-07-15T21:43:40.154857Z","end":"2026-07-15T21:43:40.370946Z","steps":["trace[1955584709] 'process raft request' (duration: 165.260053ms)","trace[1955584709] 'compare' (duration: 50.695667ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T21:43:40.371138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.066576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14480"} {"level":"warn","ts":"2026-07-15T21:43:40.371196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.412486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-15T21:43:40.371194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.087195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2918"} {"level":"info","ts":"2026-07-15T21:43:40.371207Z","caller":"traceutil/trace.go:171","msg":"trace[268623457] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:1458; }","duration":"213.159697ms","start":"2026-07-15T21:43:40.158032Z","end":"2026-07-15T21:43:40.371191Z","steps":["trace[268623457] 'agreement among raft nodes before linearized reading' (duration: 212.979115ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:43:40.371234Z","caller":"traceutil/trace.go:171","msg":"trace[572793641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1458; }","duration":"199.472236ms","start":"2026-07-15T21:43:40.17175Z","end":"2026-07-15T21:43:40.371222Z","steps":["trace[572793641] 'agreement among raft nodes before linearized reading' (duration: 199.328215ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:43:40.371246Z","caller":"traceutil/trace.go:171","msg":"trace[1178546298] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:1458; }","duration":"150.153165ms","start":"2026-07-15T21:43:40.22108Z","end":"2026-07-15T21:43:40.371233Z","steps":["trace[1178546298] 'agreement among raft nodes before linearized reading' (duration: 150.042644ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:43:40.371203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.131933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/local-path-storage/\" range_end:\"/registry/persistentvolumeclaims/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T21:43:40.371305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.450336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-15T21:43:40.371342Z","caller":"traceutil/trace.go:171","msg":"trace[1605019147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1458; }","duration":"199.529736ms","start":"2026-07-15T21:43:40.171802Z","end":"2026-07-15T21:43:40.371332Z","steps":["trace[1605019147] 'agreement among raft nodes before linearized reading' (duration: 199.382645ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:43:40.371379Z","caller":"traceutil/trace.go:171","msg":"trace[1352752622] range","detail":"{range_begin:/registry/persistentvolumeclaims/local-path-storage/; range_end:/registry/persistentvolumeclaims/local-path-storage0; response_count:0; response_revision:1458; }","duration":"149.341446ms","start":"2026-07-15T21:43:40.221987Z","end":"2026-07-15T21:43:40.371328Z","steps":["trace[1352752622] 'agreement among raft nodes before linearized reading' (duration: 149.105484ms)"],"step_count":1}