{"level":"warn","ts":"2026-08-24T15:39:13.389684Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T15:39:13.389779Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223: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.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223: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-08-24T15:39:13.389868Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T15:39:13.389883Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-08-24T15:39:13.389901Z","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-08-24T15:39:13.390635Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-08-24T15:39:13.39074Z","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.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223: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-08-24T15:39:13.395781Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.790345ms"} {"level":"info","ts":"2026-08-24T15:39:13.410843Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-08-24T15:39:13.410949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-08-24T15:39:13.410981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-08-24T15:39:13.411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-24T15:39:13.41101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-08-24T15:39:13.411082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-08-24T15:39:13.421019Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-24T15:39:13.422612Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-24T15:39:13.42569Z","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-08-24T15:39:13.430255Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-24T15:39:13.43074Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-24T15:39:13.430727Z","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-08-24T15:39:13.43085Z","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-08-24T15:39:13.430863Z","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-08-24T15:39:13.433889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-08-24T15:39:13.434049Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-08-24T15:39:13.43555Z","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-08-24T15:39:13.435653Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-08-24T15:39:13.435709Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-08-24T15:39:13.435902Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-24T15:39:13.435966Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-24T15:39:14.311881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-08-24T15:39:14.311924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-24T15:39:14.311942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-08-24T15:39:14.311956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-08-24T15:39:14.311963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-08-24T15:39:14.311974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-08-24T15:39:14.311983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-08-24T15:39:14.313026Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-08-24T15:39:14.313148Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T15:39:14.313196Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T15:39:14.313409Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:39:14.314322Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:39:14.314386Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:39:14.314404Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:39:14.31542Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-24T15:39:14.315511Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-24T15:39:14.315935Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-24T15:39:14.316547Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"info","ts":"2026-08-24T15:39:19.797258Z","caller":"traceutil/trace.go:171","msg":"trace[1644691986] linearizableReadLoop","detail":"{readStateIndex:309; appliedIndex:308; }","duration":"150.806031ms","start":"2026-08-24T15:39:19.646424Z","end":"2026-08-24T15:39:19.79723Z","steps":["trace[1644691986] 'read index received' (duration: 147.122568ms)","trace[1644691986] 'applied index is now lower than readState.Index' (duration: 3.669113ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:39:19.797285Z","caller":"traceutil/trace.go:171","msg":"trace[126647244] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"197.672618ms","start":"2026-08-24T15:39:19.599579Z","end":"2026-08-24T15:39:19.797251Z","steps":["trace[126647244] 'process raft request' (duration: 193.961614ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:19.797499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.013978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-08-24T15:39:19.797733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.894835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-24T15:39:19.797829Z","caller":"traceutil/trace.go:171","msg":"trace[809248754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:299; }","duration":"113.058311ms","start":"2026-08-24T15:39:19.684755Z","end":"2026-08-24T15:39:19.797814Z","steps":["trace[809248754] 'agreement among raft nodes before linearized reading' (duration: 112.885604ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:19.797586Z","caller":"traceutil/trace.go:171","msg":"trace[1596022756] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:299; }","duration":"151.152352ms","start":"2026-08-24T15:39:19.646413Z","end":"2026-08-24T15:39:19.797565Z","steps":["trace[1596022756] 'agreement among raft nodes before linearized reading' (duration: 150.926515ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:20.009869Z","caller":"traceutil/trace.go:171","msg":"trace[454351070] linearizableReadLoop","detail":"{readStateIndex:312; appliedIndex:311; }","duration":"146.278803ms","start":"2026-08-24T15:39:19.863552Z","end":"2026-08-24T15:39:20.009831Z","steps":["trace[454351070] 'read index received' (duration: 68.972036ms)","trace[454351070] 'applied index is now lower than readState.Index' (duration: 77.305617ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:39:20.01009Z","caller":"traceutil/trace.go:171","msg":"trace[1799676464] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"152.213411ms","start":"2026-08-24T15:39:19.857858Z","end":"2026-08-24T15:39:20.010071Z","steps":["trace[1799676464] 'process raft request' (duration: 74.584814ms)","trace[1799676464] 'compare' (duration: 77.224964ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:39:20.010165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.595074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"info","ts":"2026-08-24T15:39:20.010305Z","caller":"traceutil/trace.go:171","msg":"trace[1912958265] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:302; }","duration":"146.752709ms","start":"2026-08-24T15:39:19.863524Z","end":"2026-08-24T15:39:20.010276Z","steps":["trace[1912958265] 'agreement among raft nodes before linearized reading' (duration: 146.514931ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:20.035521Z","caller":"traceutil/trace.go:171","msg":"trace[1264721964] transaction","detail":"{read_only:false; number_of_response:0; response_revision:302; }","duration":"171.897441ms","start":"2026-08-24T15:39:19.863605Z","end":"2026-08-24T15:39:20.035502Z","steps":["trace[1264721964] 'process raft request' (duration: 171.772167ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:20.035857Z","caller":"traceutil/trace.go:171","msg":"trace[530747075] transaction","detail":"{read_only:false; number_of_response:0; response_revision:302; }","duration":"172.092149ms","start":"2026-08-24T15:39:19.863758Z","end":"2026-08-24T15:39:20.03585Z","steps":["trace[530747075] 'process raft request' (duration: 171.679844ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:20.035965Z","caller":"traceutil/trace.go:171","msg":"trace[1762919117] transaction","detail":"{read_only:false; number_of_response:0; response_revision:302; }","duration":"171.452548ms","start":"2026-08-24T15:39:19.864506Z","end":"2026-08-24T15:39:20.035958Z","steps":["trace[1762919117] 'process raft request' (duration: 170.94633ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:20.036097Z","caller":"traceutil/trace.go:171","msg":"trace[1187541031] transaction","detail":"{read_only:false; number_of_response:0; response_revision:302; }","duration":"170.957971ms","start":"2026-08-24T15:39:19.865131Z","end":"2026-08-24T15:39:20.036089Z","steps":["trace[1187541031] 'process raft request' (duration: 170.334549ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:20.036266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.123258ms","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-08-24T15:39:20.036294Z","caller":"traceutil/trace.go:171","msg":"trace[1265997275] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:302; }","duration":"160.195192ms","start":"2026-08-24T15:39:19.876092Z","end":"2026-08-24T15:39:20.036287Z","steps":["trace[1265997275] 'agreement among raft nodes before linearized reading' (duration: 160.099608ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:20.30239Z","caller":"traceutil/trace.go:171","msg":"trace[1765304714] linearizableReadLoop","detail":"{readStateIndex:317; appliedIndex:316; }","duration":"257.477158ms","start":"2026-08-24T15:39:20.044884Z","end":"2026-08-24T15:39:20.302361Z","steps":["trace[1765304714] 'read index received' (duration: 249.56886ms)","trace[1765304714] 'applied index is now lower than readState.Index' (duration: 7.906458ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:39:20.302615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.687585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-24T15:39:20.30261Z","caller":"traceutil/trace.go:171","msg":"trace[819870301] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"259.32055ms","start":"2026-08-24T15:39:20.043259Z","end":"2026-08-24T15:39:20.302579Z","steps":["trace[819870301] 'process raft request' (duration: 251.180224ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:20.30267Z","caller":"traceutil/trace.go:171","msg":"trace[2126725787] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:303; }","duration":"257.788609ms","start":"2026-08-24T15:39:20.044866Z","end":"2026-08-24T15:39:20.302654Z","steps":["trace[2126725787] 'agreement among raft nodes before linearized reading' (duration: 257.606823ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:20.389385Z","caller":"traceutil/trace.go:171","msg":"trace[1606774289] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"342.071306ms","start":"2026-08-24T15:39:20.04728Z","end":"2026-08-24T15:39:20.389351Z","steps":["trace[1606774289] 'process raft request' (duration: 341.867479ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:20.390449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:20.047255Z","time spent":"342.336605ms","remote":"127.0.0.1:32982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:39:20.669751Z","caller":"traceutil/trace.go:171","msg":"trace[67864126] linearizableReadLoop","detail":"{readStateIndex:319; appliedIndex:317; }","duration":"363.847684ms","start":"2026-08-24T15:39:20.305884Z","end":"2026-08-24T15:39:20.669732Z","steps":["trace[67864126] 'read index received' (duration: 83.191951ms)","trace[67864126] 'applied index is now lower than readState.Index' (duration: 280.654903ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:39:20.669942Z","caller":"traceutil/trace.go:171","msg":"trace[836750935] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"364.134813ms","start":"2026-08-24T15:39:20.30578Z","end":"2026-08-24T15:39:20.669915Z","steps":["trace[836750935] 'process raft request' (duration: 285.391044ms)","trace[836750935] 'compare' (duration: 78.227802ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:39:20.670249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:20.305761Z","time spent":"364.437474ms","remote":"127.0.0.1:33010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":203,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:39:20.669989Z","caller":"traceutil/trace.go:171","msg":"trace[2057690912] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"362.518049ms","start":"2026-08-24T15:39:20.307457Z","end":"2026-08-24T15:39:20.669976Z","steps":["trace[2057690912] 'process raft request' (duration: 362.231669ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:20.670106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.191995ms","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-08-24T15:39:20.670425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:20.307445Z","time spent":"362.902493ms","remote":"127.0.0.1:32846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:39:20.670484Z","caller":"traceutil/trace.go:171","msg":"trace[632968768] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:306; }","duration":"364.6289ms","start":"2026-08-24T15:39:20.305846Z","end":"2026-08-24T15:39:20.670475Z","steps":["trace[632968768] 'agreement among raft nodes before linearized reading' (duration: 364.143554ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:20.670546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:20.305833Z","time spent":"364.703683ms","remote":"127.0.0.1:33070","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-08-24T15:39:20.670155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.611627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-08-24T15:39:20.670677Z","caller":"traceutil/trace.go:171","msg":"trace[897703317] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:306; }","duration":"277.132174ms","start":"2026-08-24T15:39:20.393497Z","end":"2026-08-24T15:39:20.670629Z","steps":["trace[897703317] 'agreement among raft nodes before linearized reading' (duration: 276.625737ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:20.965708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.436673ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:39:20.965789Z","caller":"traceutil/trace.go:171","msg":"trace[1673089587] linearizableReadLoop","detail":"{readStateIndex:324; appliedIndex:323; }","duration":"193.808289ms","start":"2026-08-24T15:39:20.771968Z","end":"2026-08-24T15:39:20.965776Z","steps":["trace[1673089587] 'read index received' (duration: 60.106118ms)","trace[1673089587] 'applied index is now lower than readState.Index' (duration: 133.700961ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:39:20.96583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.859651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:39:20.96585Z","caller":"traceutil/trace.go:171","msg":"trace[464236809] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:0; response_revision:310; }","duration":"193.892732ms","start":"2026-08-24T15:39:20.771948Z","end":"2026-08-24T15:39:20.965841Z","steps":["trace[464236809] 'agreement among raft nodes before linearized reading' (duration: 193.859271ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:20.965977Z","caller":"traceutil/trace.go:171","msg":"trace[234556711] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"195.213858ms","start":"2026-08-24T15:39:20.770731Z","end":"2026-08-24T15:39:20.965945Z","steps":["trace[234556711] 'process raft request' (duration: 61.36497ms)","trace[234556711] 'compare' (duration: 133.326189ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:39:37.964547Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521228418771224270,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:39:38.465044Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521228418771224270,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:39:38.966056Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521228418771224270,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:39:39.096226Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.849281235s","expected-duration":"1s"} {"level":"info","ts":"2026-08-24T15:39:39.096662Z","caller":"traceutil/trace.go:171","msg":"trace[1756167651] linearizableReadLoop","detail":"{readStateIndex:448; appliedIndex:447; }","duration":"1.633230445s","start":"2026-08-24T15:39:37.463407Z","end":"2026-08-24T15:39:39.096638Z","steps":["trace[1756167651] 'read index received' (duration: 1.632985564s)","trace[1756167651] 'applied index is now lower than readState.Index' (duration: 243.341µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:39:39.09681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.633393682s","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-08-24T15:39:39.096871Z","caller":"traceutil/trace.go:171","msg":"trace[1854190739] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:430; }","duration":"1.633510317s","start":"2026-08-24T15:39:37.463345Z","end":"2026-08-24T15:39:39.096855Z","steps":["trace[1854190739] 'agreement among raft nodes before linearized reading' (duration: 1.633379402s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:39.0969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:37.463321Z","time spent":"1.63357368s","remote":"127.0.0.1:33070","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-08-24T15:39:39.096918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.734106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-08-24T15:39:39.096952Z","caller":"traceutil/trace.go:171","msg":"trace[545244890] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:430; }","duration":"269.798979ms","start":"2026-08-24T15:39:38.827141Z","end":"2026-08-24T15:39:39.09694Z","steps":["trace[545244890] 'agreement among raft nodes before linearized reading' (duration: 269.721966ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:39.096944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.173919455s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-24T15:39:39.096947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.200233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:39:39.096956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.474273ms","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-08-24T15:39:39.09699Z","caller":"traceutil/trace.go:171","msg":"trace[298648174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:430; }","duration":"1.173986378s","start":"2026-08-24T15:39:37.922988Z","end":"2026-08-24T15:39:39.096974Z","steps":["trace[298648174] 'agreement among raft nodes before linearized reading' (duration: 1.173905115s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:39.097025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:37.922975Z","time spent":"1.174042421s","remote":"127.0.0.1:33070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-24T15:39:39.097028Z","caller":"traceutil/trace.go:171","msg":"trace[164300773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:430; }","duration":"862.322408ms","start":"2026-08-24T15:39:38.234682Z","end":"2026-08-24T15:39:39.097004Z","steps":["trace[164300773] 'agreement among raft nodes before linearized reading' (duration: 862.187573ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:39.097029Z","caller":"traceutil/trace.go:171","msg":"trace[1833520622] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:430; }","duration":"330.62223ms","start":"2026-08-24T15:39:38.766388Z","end":"2026-08-24T15:39:39.09701Z","steps":["trace[1833520622] 'agreement among raft nodes before linearized reading' (duration: 330.404751ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:39.097077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:38.766359Z","time spent":"330.708893ms","remote":"127.0.0.1:33070","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-08-24T15:39:39.097077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:38.234662Z","time spent":"862.405752ms","remote":"127.0.0.1:60988","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-08-24T15:39:39.096691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:37.246809Z","time spent":"1.84987317s","remote":"127.0.0.1:32804","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-24T15:39:39.096854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.182919023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-24T15:39:39.097445Z","caller":"traceutil/trace.go:171","msg":"trace[928150082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:430; }","duration":"1.18355448s","start":"2026-08-24T15:39:37.913869Z","end":"2026-08-24T15:39:39.097424Z","steps":["trace[928150082] 'agreement among raft nodes before linearized reading' (duration: 1.182898273s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:39.097487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:37.913851Z","time spent":"1.183619921s","remote":"127.0.0.1:33070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T15:39:40.227648Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.12877713s","expected-duration":"1s"} {"level":"info","ts":"2026-08-24T15:39:40.266791Z","caller":"traceutil/trace.go:171","msg":"trace[1595112572] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"1.168026022s","start":"2026-08-24T15:39:39.098746Z","end":"2026-08-24T15:39:40.266772Z","steps":["trace[1595112572] 'process raft request' (duration: 1.129323043s)","trace[1595112572] 'compare' (duration: 38.50696ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:39:40.266886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:39.098729Z","time spent":"1.168109625s","remote":"127.0.0.1:32804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:39:40.65026Z","caller":"traceutil/trace.go:171","msg":"trace[2103766585] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"1.549143828s","start":"2026-08-24T15:39:39.101093Z","end":"2026-08-24T15:39:40.650237Z","steps":["trace[2103766585] 'process raft request' (duration: 1.549012683s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:40.650333Z","caller":"traceutil/trace.go:171","msg":"trace[1494882754] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"1.549723142s","start":"2026-08-24T15:39:39.10057Z","end":"2026-08-24T15:39:40.650294Z","steps":["trace[1494882754] 'process raft request' (duration: 1.473169551s)","trace[1494882754] 'compare' (duration: 76.085231ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:39:40.650436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:39.101079Z","time spent":"1.549258983s","remote":"127.0.0.1:33070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:39:40.650516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:39.100544Z","time spent":"1.549852877s","remote":"127.0.0.1:33070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:39:40.650752Z","caller":"traceutil/trace.go:171","msg":"trace[1795848270] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"1.549587436s","start":"2026-08-24T15:39:39.101128Z","end":"2026-08-24T15:39:40.650715Z","steps":["trace[1795848270] 'process raft request' (duration: 1.549079625s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:40.651481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:39.101106Z","time spent":"1.550310326s","remote":"127.0.0.1:33070","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-08-24T15:39:40.650981Z","caller":"traceutil/trace.go:171","msg":"trace[1661475877] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"1.549707541s","start":"2026-08-24T15:39:39.101253Z","end":"2026-08-24T15:39:40.650961Z","steps":["trace[1661475877] 'process raft request' (duration: 1.549399989s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:40.651063Z","caller":"traceutil/trace.go:171","msg":"trace[342290992] linearizableReadLoop","detail":"{readStateIndex:455; appliedIndex:449; }","duration":"416.350078ms","start":"2026-08-24T15:39:40.234692Z","end":"2026-08-24T15:39:40.651042Z","steps":["trace[342290992] 'read index received' (duration: 338.971113ms)","trace[342290992] 'applied index is now lower than readState.Index' (duration: 77.377685ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:39:40.651774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:39.101244Z","time spent":"1.550475254s","remote":"127.0.0.1:33070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:39:40.651136Z","caller":"traceutil/trace.go:171","msg":"trace[629647070] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"1.53980267s","start":"2026-08-24T15:39:39.111322Z","end":"2026-08-24T15:39:40.651125Z","steps":["trace[629647070] 'process raft request' (duration: 1.539644684s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:40.651932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.445793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-08-24T15:39:40.651144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.443072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:39:40.651957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:39.111309Z","time spent":"1.540575403s","remote":"127.0.0.1:33070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:39:40.65198Z","caller":"traceutil/trace.go:171","msg":"trace[270143193] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:437; }","duration":"383.519536ms","start":"2026-08-24T15:39:40.268447Z","end":"2026-08-24T15:39:40.651967Z","steps":["trace[270143193] 'agreement among raft nodes before linearized reading' (duration: 383.426302ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:40.652007Z","caller":"traceutil/trace.go:171","msg":"trace[509923975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:437; }","duration":"417.357651ms","start":"2026-08-24T15:39:40.234624Z","end":"2026-08-24T15:39:40.651982Z","steps":["trace[509923975] 'agreement among raft nodes before linearized reading' (duration: 416.471093ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:40.652028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:40.268435Z","time spent":"383.572848ms","remote":"127.0.0.1:32956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-08-24T15:39:40.652068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:40.234602Z","time spent":"417.447535ms","remote":"127.0.0.1:60988","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-08-24T15:39:40.651122Z","caller":"traceutil/trace.go:171","msg":"trace[1056759681] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"1.543912792s","start":"2026-08-24T15:39:39.107172Z","end":"2026-08-24T15:39:40.651085Z","steps":["trace[1056759681] 'process raft request' (duration: 1.543671872s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:40.652262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:39.107164Z","time spent":"1.545043909s","remote":"127.0.0.1:32968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4361,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:39:47.495072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.772422ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:39:47.495188Z","caller":"traceutil/trace.go:171","msg":"trace[537862786] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"193.071903ms","start":"2026-08-24T15:39:47.302096Z","end":"2026-08-24T15:39:47.495168Z","steps":["trace[537862786] 'process raft request' (duration: 69.114994ms)","trace[537862786] 'compare' (duration: 123.654296ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:39:52.989302Z","caller":"traceutil/trace.go:171","msg":"trace[396962729] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:510; }","duration":"157.672513ms","start":"2026-08-24T15:39:52.831608Z","end":"2026-08-24T15:39:52.989281Z","steps":["trace[396962729] 'read index received' (duration: 157.473474ms)","trace[396962729] 'applied index is now lower than readState.Index' (duration: 198.109µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:39:52.989422Z","caller":"traceutil/trace.go:171","msg":"trace[2093070955] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"167.822424ms","start":"2026-08-24T15:39:52.821583Z","end":"2026-08-24T15:39:52.989405Z","steps":["trace[2093070955] 'process raft request' (duration: 167.580464ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:52.989465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.844681ms","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-08-24T15:39:52.989487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.271299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-24T15:39:52.989513Z","caller":"traceutil/trace.go:171","msg":"trace[299181935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:490; }","duration":"156.328972ms","start":"2026-08-24T15:39:52.833176Z","end":"2026-08-24T15:39:52.989505Z","steps":["trace[299181935] 'agreement among raft nodes before linearized reading' (duration: 156.2793ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:52.989499Z","caller":"traceutil/trace.go:171","msg":"trace[433004857] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:490; }","duration":"157.940985ms","start":"2026-08-24T15:39:52.831547Z","end":"2026-08-24T15:39:52.989487Z","steps":["trace[433004857] 'agreement among raft nodes before linearized reading' (duration: 157.853781ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:52.989681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.350632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-24T15:39:52.989757Z","caller":"traceutil/trace.go:171","msg":"trace[891640746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:490; }","duration":"156.540972ms","start":"2026-08-24T15:39:52.833198Z","end":"2026-08-24T15:39:52.989739Z","steps":["trace[891640746] 'agreement among raft nodes before linearized reading' (duration: 156.328552ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:53.13992Z","caller":"traceutil/trace.go:171","msg":"trace[147498208] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"146.66262ms","start":"2026-08-24T15:39:52.993235Z","end":"2026-08-24T15:39:53.139898Z","steps":["trace[147498208] 'process raft request' (duration: 109.177035ms)","trace[147498208] 'compare' (duration: 37.3838ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:39:53.145793Z","caller":"traceutil/trace.go:171","msg":"trace[908610504] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"151.614897ms","start":"2026-08-24T15:39:52.99416Z","end":"2026-08-24T15:39:53.145774Z","steps":["trace[908610504] 'process raft request' (duration: 151.516472ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:53.145909Z","caller":"traceutil/trace.go:171","msg":"trace[456398919] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"150.37054ms","start":"2026-08-24T15:39:52.995532Z","end":"2026-08-24T15:39:53.145903Z","steps":["trace[456398919] 'process raft request' (duration: 150.216832ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:57.241303Z","caller":"traceutil/trace.go:171","msg":"trace[1896977338] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"190.831408ms","start":"2026-08-24T15:39:57.050445Z","end":"2026-08-24T15:39:57.241277Z","steps":["trace[1896977338] 'read index received' (duration: 189.136519ms)","trace[1896977338] 'applied index is now lower than readState.Index' (duration: 1.693628ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:39:57.241407Z","caller":"traceutil/trace.go:171","msg":"trace[72545817] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"194.575862ms","start":"2026-08-24T15:39:57.046799Z","end":"2026-08-24T15:39:57.241375Z","steps":["trace[72545817] 'process raft request' (duration: 192.707765ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:57.241603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.124102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-08-24T15:39:57.241769Z","caller":"traceutil/trace.go:171","msg":"trace[2068743982] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:628; }","duration":"191.326361ms","start":"2026-08-24T15:39:57.050421Z","end":"2026-08-24T15:39:57.241747Z","steps":["trace[2068743982] 'agreement among raft nodes before linearized reading' (duration: 190.950293ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:57.249528Z","caller":"traceutil/trace.go:171","msg":"trace[1818158427] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"165.415187ms","start":"2026-08-24T15:39:57.084094Z","end":"2026-08-24T15:39:57.249509Z","steps":["trace[1818158427] 'process raft request' (duration: 164.860411ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:57.249932Z","caller":"traceutil/trace.go:171","msg":"trace[2032434316] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"110.886974ms","start":"2026-08-24T15:39:57.139038Z","end":"2026-08-24T15:39:57.249925Z","steps":["trace[2032434316] 'process raft request' (duration: 110.18545ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:57.250336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.920521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:39:57.250372Z","caller":"traceutil/trace.go:171","msg":"trace[166944002] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:630; }","duration":"141.003145ms","start":"2026-08-24T15:39:57.109273Z","end":"2026-08-24T15:39:57.250361Z","steps":["trace[166944002] 'agreement among raft nodes before linearized reading' (duration: 140.949582ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:58.408199Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521228418771224635,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:39:58.813895Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.104970769s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-24T15:39:58.816674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.206707264s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:15688"} {"level":"info","ts":"2026-08-24T15:39:58.816736Z","caller":"traceutil/trace.go:171","msg":"trace[1791292133] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:645; }","duration":"1.206816818s","start":"2026-08-24T15:39:57.609906Z","end":"2026-08-24T15:39:58.816723Z","steps":["trace[1791292133] 'range keys from in-memory index tree' (duration: 1.206565977s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:58.816895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:57.609888Z","time spent":"1.206980966s","remote":"127.0.0.1:32828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":15710,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-08-24T15:39:58.817675Z","caller":"traceutil/trace.go:171","msg":"trace[915643357] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"910.385675ms","start":"2026-08-24T15:39:57.90728Z","end":"2026-08-24T15:39:58.817666Z","steps":["trace[915643357] 'read index received' (duration: 906.741964ms)","trace[915643357] 'applied index is now lower than readState.Index' (duration: 3.642861ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:39:58.81801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.729112ms","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":"info","ts":"2026-08-24T15:39:58.818059Z","caller":"traceutil/trace.go:171","msg":"trace[456108451] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:646; }","duration":"910.846977ms","start":"2026-08-24T15:39:57.907202Z","end":"2026-08-24T15:39:58.818049Z","steps":["trace[456108451] 'agreement among raft nodes before linearized reading' (duration: 910.756453ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:58.818085Z","caller":"traceutil/trace.go:171","msg":"trace[199266080] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"1.109330344s","start":"2026-08-24T15:39:57.70873Z","end":"2026-08-24T15:39:58.81806Z","steps":["trace[199266080] 'process raft request' (duration: 1.10542079s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:58.818204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:57.708713Z","time spent":"1.109430988s","remote":"127.0.0.1:32828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:39:58.818108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:57.907181Z","time spent":"910.920671ms","remote":"127.0.0.1:32828","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-08-24T15:39:58.818491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.58507ms","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-08-24T15:39:58.818522Z","caller":"traceutil/trace.go:171","msg":"trace[1654650185] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:646; }","duration":"511.653723ms","start":"2026-08-24T15:39:58.30686Z","end":"2026-08-24T15:39:58.818514Z","steps":["trace[1654650185] 'agreement among raft nodes before linearized reading' (duration: 511.59547ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:58.818566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:58.306839Z","time spent":"511.721386ms","remote":"127.0.0.1:33070","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-08-24T15:39:58.818568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.780129ms","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":"warn","ts":"2026-08-24T15:39:58.818559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.609477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:39:58.818611Z","caller":"traceutil/trace.go:171","msg":"trace[1783079365] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:646; }","duration":"511.855812ms","start":"2026-08-24T15:39:58.306741Z","end":"2026-08-24T15:39:58.818597Z","steps":["trace[1783079365] 'agreement among raft nodes before linearized reading' (duration: 511.710045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:58.818648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:58.306723Z","time spent":"511.917045ms","remote":"127.0.0.1:32828","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-08-24T15:39:58.818659Z","caller":"traceutil/trace.go:171","msg":"trace[1310836015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:646; }","duration":"585.745593ms","start":"2026-08-24T15:39:58.232887Z","end":"2026-08-24T15:39:58.818632Z","steps":["trace[1310836015] 'agreement among raft nodes before linearized reading' (duration: 585.587815ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:58.818712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:58.232871Z","time spent":"585.830227ms","remote":"127.0.0.1:60988","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-08-24T15:39:58.818751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.147683ms","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":"info","ts":"2026-08-24T15:39:58.818802Z","caller":"traceutil/trace.go:171","msg":"trace[4254099] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:646; }","duration":"311.232247ms","start":"2026-08-24T15:39:58.507561Z","end":"2026-08-24T15:39:58.818793Z","steps":["trace[4254099] 'agreement among raft nodes before linearized reading' (duration: 311.136292ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:58.818827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:58.50754Z","time spent":"311.281549ms","remote":"127.0.0.1:32828","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":"warn","ts":"2026-08-24T15:39:58.819059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.47348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-08-24T15:39:58.819279Z","caller":"traceutil/trace.go:171","msg":"trace[1458167155] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:646; }","duration":"111.679979ms","start":"2026-08-24T15:39:58.707535Z","end":"2026-08-24T15:39:58.819215Z","steps":["trace[1458167155] 'agreement among raft nodes before linearized reading' (duration: 110.936043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:58.819316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.108586ms","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":"info","ts":"2026-08-24T15:39:58.819641Z","caller":"traceutil/trace.go:171","msg":"trace[482948318] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:646; }","duration":"711.548446ms","start":"2026-08-24T15:39:58.108001Z","end":"2026-08-24T15:39:58.819549Z","steps":["trace[482948318] 'agreement among raft nodes before linearized reading' (duration: 710.440854ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:58.819712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:58.107982Z","time spent":"711.701134ms","remote":"127.0.0.1:32828","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":"info","ts":"2026-08-24T15:39:59.12768Z","caller":"traceutil/trace.go:171","msg":"trace[1156516363] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"103.625756ms","start":"2026-08-24T15:39:59.024028Z","end":"2026-08-24T15:39:59.127654Z","steps":["trace[1156516363] 'process raft request' (duration: 103.474228ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:05.708006Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521228418771224768,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:06.209293Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521228418771224768,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:06.709885Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521228418771224768,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:06.963757Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.97056806s","expected-duration":"1s"} {"level":"info","ts":"2026-08-24T15:40:06.964636Z","caller":"traceutil/trace.go:171","msg":"trace[209633171] linearizableReadLoop","detail":"{readStateIndex:730; appliedIndex:729; }","duration":"1.757379335s","start":"2026-08-24T15:40:05.207232Z","end":"2026-08-24T15:40:06.964611Z","steps":["trace[209633171] 'read index received' (duration: 1.756749715s)","trace[209633171] 'applied index is now lower than readState.Index' (duration: 628.42µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:40:06.964778Z","caller":"traceutil/trace.go:171","msg":"trace[1465901700] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"1.971912557s","start":"2026-08-24T15:40:04.992825Z","end":"2026-08-24T15:40:06.964737Z","steps":["trace[1465901700] 'process raft request' (duration: 1.971261115s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:06.96488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.757634687s","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-08-24T15:40:06.964913Z","caller":"traceutil/trace.go:171","msg":"trace[604775547] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:702; }","duration":"1.757724372s","start":"2026-08-24T15:40:05.20718Z","end":"2026-08-24T15:40:06.964905Z","steps":["trace[604775547] 'agreement among raft nodes before linearized reading' (duration: 1.757568714s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:06.96494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:05.207155Z","time spent":"1.757778954s","remote":"127.0.0.1:33070","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-08-24T15:40:06.964978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:04.99279Z","time spent":"1.972057583s","remote":"127.0.0.1:32982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:40:06.964985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.731948465s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:40:06.965111Z","caller":"traceutil/trace.go:171","msg":"trace[585571655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:702; }","duration":"1.732131784s","start":"2026-08-24T15:40:05.23295Z","end":"2026-08-24T15:40:06.965082Z","steps":["trace[585571655] 'agreement among raft nodes before linearized reading' (duration: 1.731905643s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:06.965084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.665008541s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-24T15:40:06.965176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:05.232927Z","time spent":"1.732237859s","remote":"127.0.0.1:60988","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-08-24T15:40:06.965197Z","caller":"traceutil/trace.go:171","msg":"trace[850004459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:702; }","duration":"1.66519654s","start":"2026-08-24T15:40:05.299976Z","end":"2026-08-24T15:40:06.965173Z","steps":["trace[850004459] 'agreement among raft nodes before linearized reading' (duration: 1.664894235s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:06.965241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:05.299942Z","time spent":"1.665291154s","remote":"127.0.0.1:33070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T15:40:06.965278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.674689642s","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-08-24T15:40:06.965274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.787862ms","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-08-24T15:40:06.965365Z","caller":"traceutil/trace.go:171","msg":"trace[1687083669] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:702; }","duration":"105.91656ms","start":"2026-08-24T15:40:06.859438Z","end":"2026-08-24T15:40:06.965354Z","steps":["trace[1687083669] 'agreement among raft nodes before linearized reading' (duration: 105.53013ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:40:06.965303Z","caller":"traceutil/trace.go:171","msg":"trace[251373187] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:702; }","duration":"1.674766586s","start":"2026-08-24T15:40:05.290531Z","end":"2026-08-24T15:40:06.965297Z","steps":["trace[251373187] 'agreement among raft nodes before linearized reading' (duration: 1.674693972s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:06.965455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.665501485s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-24T15:40:06.965543Z","caller":"traceutil/trace.go:171","msg":"trace[468781141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:702; }","duration":"1.665657563s","start":"2026-08-24T15:40:05.29986Z","end":"2026-08-24T15:40:06.965518Z","steps":["trace[468781141] 'agreement among raft nodes before linearized reading' (duration: 1.665339536s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:06.96548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:05.290512Z","time spent":"1.674958486s","remote":"127.0.0.1:33070","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-08-24T15:40:06.965592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:05.299847Z","time spent":"1.665734577s","remote":"127.0.0.1:33070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T15:40:08.26585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.838103ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:40:08.266006Z","caller":"traceutil/trace.go:171","msg":"trace[738424978] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"993.828162ms","start":"2026-08-24T15:40:07.272139Z","end":"2026-08-24T15:40:08.265968Z","steps":["trace[738424978] 'process raft request' (duration: 799.78548ms)","trace[738424978] 'compare' (duration: 193.522367ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:40:08.266202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:07.272125Z","time spent":"993.970658ms","remote":"127.0.0.1:32804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:40:11.149793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.163844026s","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-08-24T15:40:11.149856Z","caller":"traceutil/trace.go:171","msg":"trace[1547335287] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:709; }","duration":"2.163947871s","start":"2026-08-24T15:40:08.985894Z","end":"2026-08-24T15:40:11.149842Z","steps":["trace[1547335287] 'range keys from in-memory index tree' (duration: 2.163693039s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:11.149886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:08.985876Z","time spent":"2.164003364s","remote":"127.0.0.1:33070","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-08-24T15:40:11.149959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.144931271s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-24T15:40:11.150029Z","caller":"traceutil/trace.go:171","msg":"trace[1698322216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:709; }","duration":"2.145056257s","start":"2026-08-24T15:40:09.004957Z","end":"2026-08-24T15:40:11.150014Z","steps":["trace[1698322216] 'range keys from in-memory index tree' (duration: 2.144766353s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:11.150065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:09.004936Z","time spent":"2.14512142s","remote":"127.0.0.1:33070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T15:40:11.15019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.144590424s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-24T15:40:11.150215Z","caller":"traceutil/trace.go:171","msg":"trace[1794967164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:709; }","duration":"2.144624025s","start":"2026-08-24T15:40:09.005585Z","end":"2026-08-24T15:40:11.150209Z","steps":["trace[1794967164] 'range keys from in-memory index tree' (duration: 2.14452376s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:11.150233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:09.00558Z","time spent":"2.144648667s","remote":"127.0.0.1:33070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T15:40:11.150229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.91511025s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:40:11.150308Z","caller":"traceutil/trace.go:171","msg":"trace[1804410240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:709; }","duration":"1.915186824s","start":"2026-08-24T15:40:09.235112Z","end":"2026-08-24T15:40:11.150299Z","steps":["trace[1804410240] 'range keys from in-memory index tree' (duration: 1.915008105s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:11.150337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:09.235062Z","time spent":"1.915269167s","remote":"127.0.0.1:60988","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-08-24T15:40:11.150443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.144926261s","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-08-24T15:40:11.150536Z","caller":"traceutil/trace.go:171","msg":"trace[15479817] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:709; }","duration":"2.145031796s","start":"2026-08-24T15:40:09.005477Z","end":"2026-08-24T15:40:11.150509Z","steps":["trace[15479817] 'range keys from in-memory index tree' (duration: 2.144647067s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:11.150552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.143775165s","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-08-24T15:40:11.150586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:09.005471Z","time spent":"2.145104089s","remote":"127.0.0.1:33070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-24T15:40:11.150644Z","caller":"traceutil/trace.go:171","msg":"trace[662097238] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:709; }","duration":"2.14388504s","start":"2026-08-24T15:40:09.00674Z","end":"2026-08-24T15:40:11.150625Z","steps":["trace[662097238] 'range keys from in-memory index tree' (duration: 2.143544273s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:11.150687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:09.006673Z","time spent":"2.144006646s","remote":"127.0.0.1:33070","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":"info","ts":"2026-08-24T15:40:11.317705Z","caller":"traceutil/trace.go:171","msg":"trace[1907802744] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"160.298646ms","start":"2026-08-24T15:40:11.157382Z","end":"2026-08-24T15:40:11.31768Z","steps":["trace[1907802744] 'process raft request' (duration: 160.092056ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:40:11.485492Z","caller":"traceutil/trace.go:171","msg":"trace[1525566899] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"327.110529ms","start":"2026-08-24T15:40:11.158355Z","end":"2026-08-24T15:40:11.485466Z","steps":["trace[1525566899] 'process raft request' (duration: 327.042686ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:40:11.485533Z","caller":"traceutil/trace.go:171","msg":"trace[269732939] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"327.129609ms","start":"2026-08-24T15:40:11.158361Z","end":"2026-08-24T15:40:11.485491Z","steps":["trace[269732939] 'process raft request' (duration: 326.979202ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:11.485676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.14419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:40:11.485715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:11.158338Z","time spent":"327.245446ms","remote":"127.0.0.1:33070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:40:11.485734Z","caller":"traceutil/trace.go:171","msg":"trace[715277424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:714; }","duration":"327.254196ms","start":"2026-08-24T15:40:11.15847Z","end":"2026-08-24T15:40:11.485724Z","steps":["trace[715277424] 'agreement among raft nodes before linearized reading' (duration: 327.13008ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:40:11.485495Z","caller":"traceutil/trace.go:171","msg":"trace[1449975900] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"327.578752ms","start":"2026-08-24T15:40:11.157882Z","end":"2026-08-24T15:40:11.485461Z","steps":["trace[1449975900] 'process raft request' (duration: 326.957831ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:11.485775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:11.158453Z","time spent":"327.314099ms","remote":"127.0.0.1:60988","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-08-24T15:40:11.485772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:11.158349Z","time spent":"327.284617ms","remote":"127.0.0.1:33070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:40:11.485868Z","caller":"traceutil/trace.go:171","msg":"trace[777018502] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"327.386492ms","start":"2026-08-24T15:40:11.158469Z","end":"2026-08-24T15:40:11.485855Z","steps":["trace[777018502] 'process raft request' (duration: 326.964591ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:11.485859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:11.157837Z","time spent":"327.967832ms","remote":"127.0.0.1:33070","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-08-24T15:40:11.485547Z","caller":"traceutil/trace.go:171","msg":"trace[510063176] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:738; }","duration":"327.006384ms","start":"2026-08-24T15:40:11.158513Z","end":"2026-08-24T15:40:11.485519Z","steps":["trace[510063176] 'read index received' (duration: 159.018002ms)","trace[510063176] 'applied index is now lower than readState.Index' (duration: 167.986922ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:40:11.485939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:11.158454Z","time spent":"327.457326ms","remote":"127.0.0.1:33070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:40:12.358387Z","caller":"traceutil/trace.go:171","msg":"trace[2012657521] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"174.676893ms","start":"2026-08-24T15:40:12.183689Z","end":"2026-08-24T15:40:12.358366Z","steps":["trace[2012657521] 'process raft request' (duration: 174.552917ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:12.358493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.080199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:40:12.358521Z","caller":"traceutil/trace.go:171","msg":"trace[835452982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:719; }","duration":"124.148992ms","start":"2026-08-24T15:40:12.234365Z","end":"2026-08-24T15:40:12.358514Z","steps":["trace[835452982] 'agreement among raft nodes before linearized reading' (duration: 124.057638ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:40:12.35838Z","caller":"traceutil/trace.go:171","msg":"trace[609430124] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:747; }","duration":"123.961193ms","start":"2026-08-24T15:40:12.234397Z","end":"2026-08-24T15:40:12.358358Z","steps":["trace[609430124] 'read index received' (duration: 123.850947ms)","trace[609430124] 'applied index is now lower than readState.Index' (duration: 108.966µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:40:15.18155Z","caller":"traceutil/trace.go:171","msg":"trace[1496376442] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"160.649483ms","start":"2026-08-24T15:40:15.020882Z","end":"2026-08-24T15:40:15.181531Z","steps":["trace[1496376442] 'process raft request' (duration: 160.533147ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:40:15.433203Z","caller":"traceutil/trace.go:171","msg":"trace[2002107229] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:763; }","duration":"102.680323ms","start":"2026-08-24T15:40:15.330493Z","end":"2026-08-24T15:40:15.433174Z","steps":["trace[2002107229] 'read index received' (duration: 37.584962ms)","trace[2002107229] 'applied index is now lower than readState.Index' (duration: 65.093771ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:40:15.433264Z","caller":"traceutil/trace.go:171","msg":"trace[1058853709] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"401.081254ms","start":"2026-08-24T15:40:15.032153Z","end":"2026-08-24T15:40:15.433234Z","steps":["trace[1058853709] 'process raft request' (duration: 335.901139ms)","trace[1058853709] 'compare' (duration: 64.642388ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:40:15.4334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.895214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-24T15:40:15.433435Z","caller":"traceutil/trace.go:171","msg":"trace[1141495952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:734; }","duration":"102.973158ms","start":"2026-08-24T15:40:15.330451Z","end":"2026-08-24T15:40:15.433424Z","steps":["trace[1141495952] 'agreement among raft nodes before linearized reading' (duration: 102.80465ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:15.433437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:15.032125Z","time spent":"401.218091ms","remote":"127.0.0.1:32982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18052,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:40:17.369691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.448344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-24T15:40:17.369772Z","caller":"traceutil/trace.go:171","msg":"trace[1636114468] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:746; }","duration":"121.533538ms","start":"2026-08-24T15:40:17.248219Z","end":"2026-08-24T15:40:17.369753Z","steps":["trace[1636114468] 'range keys from in-memory index tree' (duration: 121.232483ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:40:25.611586Z","caller":"traceutil/trace.go:171","msg":"trace[50567511] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"485.230475ms","start":"2026-08-24T15:40:25.126336Z","end":"2026-08-24T15:40:25.611567Z","steps":["trace[50567511] 'process raft request' (duration: 484.841145ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:25.611709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:25.126321Z","time spent":"485.32353ms","remote":"127.0.0.1:32982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4857,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:40:30.147483Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521228418771225112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:30.206211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.444884659s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:40:30.206288Z","caller":"traceutil/trace.go:171","msg":"trace[1572333259] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:831; }","duration":"1.445004365s","start":"2026-08-24T15:40:28.761267Z","end":"2026-08-24T15:40:30.206271Z","steps":["trace[1572333259] 'count revisions from in-memory index tree' (duration: 1.444826236s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:30.206329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:28.761252Z","time spent":"1.445066239s","remote":"127.0.0.1:33088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":27,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:40:30.278568Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.127024212s","expected-duration":"1s"} {"level":"info","ts":"2026-08-24T15:40:30.279028Z","caller":"traceutil/trace.go:171","msg":"trace[1868319984] linearizableReadLoop","detail":"{readStateIndex:867; appliedIndex:866; }","duration":"643.389841ms","start":"2026-08-24T15:40:29.635609Z","end":"2026-08-24T15:40:30.278999Z","steps":["trace[1868319984] 'read index received' (duration: 643.109386ms)","trace[1868319984] 'applied index is now lower than readState.Index' (duration: 279.084µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:40:30.279118Z","caller":"traceutil/trace.go:171","msg":"trace[832618811] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"1.127726992s","start":"2026-08-24T15:40:29.151368Z","end":"2026-08-24T15:40:30.279095Z","steps":["trace[832618811] 'process raft request' (duration: 1.127440865s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:30.279387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.764292ms","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-08-24T15:40:30.279406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.406539ms","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-08-24T15:40:30.279436Z","caller":"traceutil/trace.go:171","msg":"trace[651545734] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:832; }","duration":"643.875417ms","start":"2026-08-24T15:40:29.635547Z","end":"2026-08-24T15:40:30.279423Z","steps":["trace[651545734] 'agreement among raft nodes before linearized reading' (duration: 643.660186ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:30.279372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:29.151331Z","time spent":"1.127866439s","remote":"127.0.0.1:33048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:40:30.279448Z","caller":"traceutil/trace.go:171","msg":"trace[788607322] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:832; }","duration":"612.485874ms","start":"2026-08-24T15:40:29.666951Z","end":"2026-08-24T15:40:30.279437Z","steps":["trace[788607322] 'agreement among raft nodes before linearized reading' (duration: 612.343166ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:30.27948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:29.666936Z","time spent":"612.538177ms","remote":"127.0.0.1:33070","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-08-24T15:40:30.279509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:29.635524Z","time spent":"643.94258ms","remote":"127.0.0.1:33070","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-08-24T15:40:30.279558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.93858ms","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-08-24T15:40:30.279613Z","caller":"traceutil/trace.go:171","msg":"trace[1322277229] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:832; }","duration":"635.034105ms","start":"2026-08-24T15:40:29.644562Z","end":"2026-08-24T15:40:30.279596Z","steps":["trace[1322277229] 'agreement among raft nodes before linearized reading' (duration: 634.887637ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:30.279589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.437854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-24T15:40:30.279649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:29.644541Z","time spent":"635.099879ms","remote":"127.0.0.1:33070","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-08-24T15:40:30.279689Z","caller":"traceutil/trace.go:171","msg":"trace[547305201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:832; }","duration":"634.55924ms","start":"2026-08-24T15:40:29.645109Z","end":"2026-08-24T15:40:30.279668Z","steps":["trace[547305201] 'agreement among raft nodes before linearized reading' (duration: 634.271105ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:30.279737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:29.645103Z","time spent":"634.625214ms","remote":"127.0.0.1:33070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T15:40:30.280238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.101366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-24T15:40:30.280287Z","caller":"traceutil/trace.go:171","msg":"trace[304566098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:832; }","duration":"643.209671ms","start":"2026-08-24T15:40:29.637066Z","end":"2026-08-24T15:40:30.280275Z","steps":["trace[304566098] 'agreement among raft nodes before linearized reading' (duration: 642.201418ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:30.280319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:29.63704Z","time spent":"643.271605ms","remote":"127.0.0.1:33070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-24T15:40:44.545672Z","caller":"traceutil/trace.go:171","msg":"trace[1442066264] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"130.386532ms","start":"2026-08-24T15:40:44.41526Z","end":"2026-08-24T15:40:44.545646Z","steps":["trace[1442066264] 'process raft request' (duration: 129.120082ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:40:44.545959Z","caller":"traceutil/trace.go:171","msg":"trace[1540149375] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"129.07761ms","start":"2026-08-24T15:40:44.41686Z","end":"2026-08-24T15:40:44.545938Z","steps":["trace[1540149375] 'process raft request' (duration: 129.040738ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:40:44.546171Z","caller":"traceutil/trace.go:171","msg":"trace[1150990701] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"129.723855ms","start":"2026-08-24T15:40:44.416422Z","end":"2026-08-24T15:40:44.546146Z","steps":["trace[1150990701] 'process raft request' (duration: 129.44977ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:40:44.546168Z","caller":"traceutil/trace.go:171","msg":"trace[1423928613] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"130.792964ms","start":"2026-08-24T15:40:44.415311Z","end":"2026-08-24T15:40:44.546104Z","steps":["trace[1423928613] 'process raft request' (duration: 130.444925ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:41:11.570035Z","caller":"traceutil/trace.go:171","msg":"trace[569641005] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"121.973376ms","start":"2026-08-24T15:41:11.448023Z","end":"2026-08-24T15:41:11.569997Z","steps":["trace[569641005] 'process raft request' (duration: 121.757262ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:41:12.941747Z","caller":"traceutil/trace.go:171","msg":"trace[971181462] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"198.37618ms","start":"2026-08-24T15:41:12.743352Z","end":"2026-08-24T15:41:12.941728Z","steps":["trace[971181462] 'process raft request' (duration: 198.244273ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:41:12.942783Z","caller":"traceutil/trace.go:171","msg":"trace[1716930273] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"198.255834ms","start":"2026-08-24T15:41:12.744514Z","end":"2026-08-24T15:41:12.94277Z","steps":["trace[1716930273] 'process raft request' (duration: 198.179979ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:41:12.942779Z","caller":"traceutil/trace.go:171","msg":"trace[330741077] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"196.972909ms","start":"2026-08-24T15:41:12.745784Z","end":"2026-08-24T15:41:12.942757Z","steps":["trace[330741077] 'process raft request' (duration: 196.940047ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:41:12.942822Z","caller":"traceutil/trace.go:171","msg":"trace[994916155] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"198.657836ms","start":"2026-08-24T15:41:12.744105Z","end":"2026-08-24T15:41:12.942763Z","steps":["trace[994916155] 'process raft request' (duration: 198.460255ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:41:31.297114Z","caller":"traceutil/trace.go:171","msg":"trace[530122931] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"213.337297ms","start":"2026-08-24T15:41:31.08375Z","end":"2026-08-24T15:41:31.297087Z","steps":["trace[530122931] 'process raft request' (duration: 153.936495ms)","trace[530122931] 'compare' (duration: 59.289836ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:41:41.072688Z","caller":"traceutil/trace.go:171","msg":"trace[881590238] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"270.270499ms","start":"2026-08-24T15:41:40.802373Z","end":"2026-08-24T15:41:41.072643Z","steps":["trace[881590238] 'process raft request' (duration: 270.053966ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:41:41.301021Z","caller":"traceutil/trace.go:171","msg":"trace[415681851] linearizableReadLoop","detail":"{readStateIndex:1247; appliedIndex:1246; }","duration":"119.426686ms","start":"2026-08-24T15:41:41.181566Z","end":"2026-08-24T15:41:41.300993Z","steps":["trace[415681851] 'read index received' (duration: 55.83038ms)","trace[415681851] 'applied index is now lower than readState.Index' (duration: 63.594686ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:41:41.30112Z","caller":"traceutil/trace.go:171","msg":"trace[845813029] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"132.752847ms","start":"2026-08-24T15:41:41.168329Z","end":"2026-08-24T15:41:41.301082Z","steps":["trace[845813029] 'process raft request' (duration: 69.056404ms)","trace[845813029] 'compare' (duration: 63.494801ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:41:41.301193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.600577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:41:41.301237Z","caller":"traceutil/trace.go:171","msg":"trace[1639416248] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1197; }","duration":"119.700782ms","start":"2026-08-24T15:41:41.181523Z","end":"2026-08-24T15:41:41.301224Z","steps":["trace[1639416248] 'agreement among raft nodes before linearized reading' (duration: 119.608967ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:41:41.55927Z","caller":"traceutil/trace.go:171","msg":"trace[691723001] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"167.47369ms","start":"2026-08-24T15:41:41.391745Z","end":"2026-08-24T15:41:41.559219Z","steps":["trace[691723001] 'process raft request' (duration: 153.631369ms)","trace[691723001] 'compare' (duration: 13.717024ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:42:01.345574Z","caller":"traceutil/trace.go:171","msg":"trace[2035818758] linearizableReadLoop","detail":"{readStateIndex:1315; appliedIndex:1314; }","duration":"113.034661ms","start":"2026-08-24T15:42:01.232513Z","end":"2026-08-24T15:42:01.345548Z","steps":["trace[2035818758] 'read index received' (duration: 112.920884ms)","trace[2035818758] 'applied index is now lower than readState.Index' (duration: 111.836µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:42:01.345662Z","caller":"traceutil/trace.go:171","msg":"trace[1643950243] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"125.34767ms","start":"2026-08-24T15:42:01.220263Z","end":"2026-08-24T15:42:01.345611Z","steps":["trace[1643950243] 'process raft request' (duration: 125.117707ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:42:01.345769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.236302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:42:01.345824Z","caller":"traceutil/trace.go:171","msg":"trace[20238813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1261; }","duration":"113.336208ms","start":"2026-08-24T15:42:01.232471Z","end":"2026-08-24T15:42:01.345807Z","steps":["trace[20238813] 'agreement among raft nodes before linearized reading' (duration: 113.226322ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:22.114391Z","caller":"traceutil/trace.go:171","msg":"trace[722182884] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"155.881772ms","start":"2026-08-24T15:42:21.958491Z","end":"2026-08-24T15:42:22.114372Z","steps":["trace[722182884] 'process raft request' (duration: 146.462035ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:25.044658Z","caller":"traceutil/trace.go:171","msg":"trace[1954940888] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"112.754292ms","start":"2026-08-24T15:42:24.931879Z","end":"2026-08-24T15:42:25.044633Z","steps":["trace[1954940888] 'process raft request' (duration: 111.428292ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:25.773196Z","caller":"traceutil/trace.go:171","msg":"trace[848256982] linearizableReadLoop","detail":"{readStateIndex:1438; appliedIndex:1436; }","duration":"194.285141ms","start":"2026-08-24T15:42:25.578884Z","end":"2026-08-24T15:42:25.773169Z","steps":["trace[848256982] 'read index received' (duration: 21.959575ms)","trace[848256982] 'applied index is now lower than readState.Index' (duration: 172.323536ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:42:25.773349Z","caller":"traceutil/trace.go:171","msg":"trace[1929916517] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"201.665536ms","start":"2026-08-24T15:42:25.571657Z","end":"2026-08-24T15:42:25.773323Z","steps":["trace[1929916517] 'process raft request' (duration: 199.050197ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:42:25.773554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.648795ms","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-08-24T15:42:25.773651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.901444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-08-24T15:42:25.77368Z","caller":"traceutil/trace.go:171","msg":"trace[1529357834] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1377; }","duration":"148.984442ms","start":"2026-08-24T15:42:25.624687Z","end":"2026-08-24T15:42:25.773671Z","steps":["trace[1529357834] 'agreement among raft nodes before linearized reading' (duration: 148.850694ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:25.773682Z","caller":"traceutil/trace.go:171","msg":"trace[235047375] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1377; }","duration":"194.822042ms","start":"2026-08-24T15:42:25.578839Z","end":"2026-08-24T15:42:25.773661Z","steps":["trace[235047375] 'agreement among raft nodes before linearized reading' (duration: 194.509207ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:42:25.773889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.592108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-24T15:42:25.774006Z","caller":"traceutil/trace.go:171","msg":"trace[679615359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1377; }","duration":"178.707596ms","start":"2026-08-24T15:42:25.595278Z","end":"2026-08-24T15:42:25.773986Z","steps":["trace[679615359] 'agreement among raft nodes before linearized reading' (duration: 178.230394ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:42:25.773912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.36057ms","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-08-24T15:42:25.774136Z","caller":"traceutil/trace.go:171","msg":"trace[171961960] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1377; }","duration":"178.619457ms","start":"2026-08-24T15:42:25.595499Z","end":"2026-08-24T15:42:25.774118Z","steps":["trace[171961960] 'agreement among raft nodes before linearized reading' (duration: 178.234233ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:42:26.038495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.376435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:42:26.038756Z","caller":"traceutil/trace.go:171","msg":"trace[682078267] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"261.181921ms","start":"2026-08-24T15:42:25.777542Z","end":"2026-08-24T15:42:26.038724Z","steps":["trace[682078267] 'process raft request' (duration: 118.453881ms)","trace[682078267] 'compare' (duration: 142.211898ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:42:26.038788Z","caller":"traceutil/trace.go:171","msg":"trace[1662855050] linearizableReadLoop","detail":"{readStateIndex:1441; appliedIndex:1438; }","duration":"183.168966ms","start":"2026-08-24T15:42:25.855604Z","end":"2026-08-24T15:42:26.038773Z","steps":["trace[1662855050] 'read index received' (duration: 40.388517ms)","trace[1662855050] 'applied index is now lower than readState.Index' (duration: 142.779299ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:42:26.038967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.361572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-24T15:42:26.039007Z","caller":"traceutil/trace.go:171","msg":"trace[1747203427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1380; }","duration":"183.442331ms","start":"2026-08-24T15:42:25.855554Z","end":"2026-08-24T15:42:26.038997Z","steps":["trace[1747203427] 'agreement among raft nodes before linearized reading' (duration: 183.266184ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:26.039092Z","caller":"traceutil/trace.go:171","msg":"trace[632793255] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"260.773107ms","start":"2026-08-24T15:42:25.778308Z","end":"2026-08-24T15:42:26.039081Z","steps":["trace[632793255] 'process raft request' (duration: 260.345094ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:26.039383Z","caller":"traceutil/trace.go:171","msg":"trace[1965533193] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"260.371914ms","start":"2026-08-24T15:42:25.778976Z","end":"2026-08-24T15:42:26.039348Z","steps":["trace[1965533193] 'process raft request' (duration: 259.760563ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:43:04.484202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.837869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:43:04.484361Z","caller":"traceutil/trace.go:171","msg":"trace[1295289716] transaction","detail":"{read_only:false; response_revision:1512; number_of_response:1; }","duration":"137.354272ms","start":"2026-08-24T15:43:04.346993Z","end":"2026-08-24T15:43:04.484348Z","steps":["trace[1295289716] 'process raft request' (duration: 137.293832ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:43:04.48456Z","caller":"traceutil/trace.go:171","msg":"trace[500195961] transaction","detail":"{read_only:false; response_revision:1511; number_of_response:1; }","duration":"264.833718ms","start":"2026-08-24T15:43:04.219714Z","end":"2026-08-24T15:43:04.484548Z","steps":["trace[500195961] 'process raft request' (duration: 128.584828ms)","trace[500195961] 'compare' (duration: 135.624508ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:43:04.484581Z","caller":"traceutil/trace.go:171","msg":"trace[874889432] linearizableReadLoop","detail":"{readStateIndex:1581; appliedIndex:1580; }","duration":"261.259369ms","start":"2026-08-24T15:43:04.223298Z","end":"2026-08-24T15:43:04.484557Z","steps":["trace[874889432] 'read index received' (duration: 125.013599ms)","trace[874889432] 'applied index is now lower than readState.Index' (duration: 136.24332ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:43:04.484725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.425049ms","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-08-24T15:43:04.484768Z","caller":"traceutil/trace.go:171","msg":"trace[1492765713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1512; }","duration":"261.491959ms","start":"2026-08-24T15:43:04.223264Z","end":"2026-08-24T15:43:04.484756Z","steps":["trace[1492765713] 'agreement among raft nodes before linearized reading' (duration: 261.361249ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:43:04.4848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.570124ms","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-08-24T15:43:04.484809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.817155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-wc5r8\" ","response":"range_response_count:1 size:4694"} {"level":"warn","ts":"2026-08-24T15:43:04.484783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.308074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:43:04.484835Z","caller":"traceutil/trace.go:171","msg":"trace[1243233288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1512; }","duration":"255.655094ms","start":"2026-08-24T15:43:04.229171Z","end":"2026-08-24T15:43:04.484826Z","steps":["trace[1243233288] 'agreement among raft nodes before linearized reading' (duration: 255.509524ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:43:04.484846Z","caller":"traceutil/trace.go:171","msg":"trace[227712662] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-wc5r8; range_end:; response_count:1; response_revision:1512; }","duration":"244.875665ms","start":"2026-08-24T15:43:04.239958Z","end":"2026-08-24T15:43:04.484834Z","steps":["trace[227712662] 'agreement among raft nodes before linearized reading' (duration: 244.774665ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:43:04.484877Z","caller":"traceutil/trace.go:171","msg":"trace[327366217] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1512; }","duration":"172.456975ms","start":"2026-08-24T15:43:04.312395Z","end":"2026-08-24T15:43:04.484852Z","steps":["trace[327366217] 'agreement among raft nodes before linearized reading' (duration: 172.300154ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:43:04.484903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.120668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:43:04.484985Z","caller":"traceutil/trace.go:171","msg":"trace[1842431197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1512; }","duration":"253.229628ms","start":"2026-08-24T15:43:04.231736Z","end":"2026-08-24T15:43:04.484965Z","steps":["trace[1842431197] 'agreement among raft nodes before linearized reading' (duration: 253.109608ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:43:04.649174Z","caller":"traceutil/trace.go:171","msg":"trace[1084428582] transaction","detail":"{read_only:false; response_revision:1514; number_of_response:1; }","duration":"159.696572ms","start":"2026-08-24T15:43:04.489458Z","end":"2026-08-24T15:43:04.649154Z","steps":["trace[1084428582] 'process raft request' (duration: 142.249416ms)","trace[1084428582] 'compare' (duration: 17.105605ms)"],"step_count":2}