{"level":"warn","ts":"2026-03-06T00:33:09.745695Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-06T00:33:09.745819Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.65: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.65:2380","--initial-cluster=instance=https://199.204.45.65:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.65:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.65: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-03-06T00:33:09.745925Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-06T00:33:09.745942Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-03-06T00:33:09.74597Z","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-03-06T00:33:09.74722Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"]} {"level":"info","ts":"2026-03-06T00:33:09.747335Z","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.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.65: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-03-06T00:33:09.753425Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.801377ms"} {"level":"info","ts":"2026-03-06T00:33:09.764128Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf8c3a138c9a77a2","cluster-id":"c278183e1f634dff"} {"level":"info","ts":"2026-03-06T00:33:09.764387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=()"} {"level":"info","ts":"2026-03-06T00:33:09.764456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 0"} {"level":"info","ts":"2026-03-06T00:33:09.764624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf8c3a138c9a77a2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-06T00:33:09.764648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 1"} {"level":"info","ts":"2026-03-06T00:33:09.764835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"warn","ts":"2026-03-06T00:33:09.781394Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-06T00:33:09.788604Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-06T00:33:09.790559Z","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-03-06T00:33:09.792609Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf8c3a138c9a77a2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-06T00:33:09.793071Z","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-03-06T00:33:09.793144Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf8c3a138c9a77a2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-06T00:33:09.793566Z","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-03-06T00:33:09.793775Z","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-03-06T00:33:09.794166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"info","ts":"2026-03-06T00:33:09.797739Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","added-peer-id":"cf8c3a138c9a77a2","added-peer-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-03-06T00:33:09.800679Z","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-03-06T00:33:09.800865Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-03-06T00:33:09.800904Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-03-06T00:33:09.801107Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf8c3a138c9a77a2","initial-advertise-peer-urls":["https://199.204.45.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-06T00:33:09.80117Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-06T00:33:09.865448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 is starting a new election at term 1"} {"level":"info","ts":"2026-03-06T00:33:09.865505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-06T00:33:09.865538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgPreVoteResp from cf8c3a138c9a77a2 at term 1"} {"level":"info","ts":"2026-03-06T00:33:09.86555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became candidate at term 2"} {"level":"info","ts":"2026-03-06T00:33:09.865556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgVoteResp from cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-03-06T00:33:09.865568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became leader at term 2"} {"level":"info","ts":"2026-03-06T00:33:09.865579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf8c3a138c9a77a2 elected leader cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-03-06T00:33:09.868481Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T00:33:09.869239Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-06T00:33:09.869241Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf8c3a138c9a77a2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.65:2379]}","request-path":"/0/members/cf8c3a138c9a77a2/attributes","cluster-id":"c278183e1f634dff","publish-timeout":"7s"} {"level":"info","ts":"2026-03-06T00:33:09.869347Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-06T00:33:09.869771Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T00:33:09.869843Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T00:33:09.869869Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T00:33:09.870865Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-06T00:33:09.870901Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-06T00:33:09.871301Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.65:2379"} {"level":"info","ts":"2026-03-06T00:33:09.872491Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-06T00:33:12.963032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.835528ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-06T00:33:12.963199Z","caller":"traceutil/trace.go:171","msg":"trace[182931196] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"358.654277ms","start":"2026-03-06T00:33:12.604526Z","end":"2026-03-06T00:33:12.96318Z","steps":["trace[182931196] 'read index received' (duration: 120.140613ms)","trace[182931196] 'applied index is now lower than readState.Index' (duration: 238.512444ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:33:12.963313Z","caller":"traceutil/trace.go:171","msg":"trace[724634080] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"359.62736ms","start":"2026-03-06T00:33:12.603649Z","end":"2026-03-06T00:33:12.963276Z","steps":["trace[724634080] 'process raft request' (duration: 121.090336ms)","trace[724634080] 'compare' (duration: 237.677464ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:33:12.963381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:33:12.603632Z","time spent":"359.721733ms","remote":"127.0.0.1:53824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T00:33:12.963635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.082207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-06T00:33:12.963746Z","caller":"traceutil/trace.go:171","msg":"trace[2087658189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"359.22319ms","start":"2026-03-06T00:33:12.604501Z","end":"2026-03-06T00:33:12.963724Z","steps":["trace[2087658189] 'agreement among raft nodes before linearized reading' (duration: 358.863982ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:12.963785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:33:12.604489Z","time spent":"359.286402ms","remote":"127.0.0.1:53448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T00:33:12.964002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.269561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-06T00:33:12.964311Z","caller":"traceutil/trace.go:171","msg":"trace[1952942103] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:73; }","duration":"359.65079ms","start":"2026-03-06T00:33:12.604643Z","end":"2026-03-06T00:33:12.964294Z","steps":["trace[1952942103] 'agreement among raft nodes before linearized reading' (duration: 358.660987ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:12.964513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:33:12.604627Z","time spent":"359.872315ms","remote":"127.0.0.1:53806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:aggregate-to-view\" "} {"level":"info","ts":"2026-03-06T00:33:13.241441Z","caller":"traceutil/trace.go:171","msg":"trace[2014183320] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:77; }","duration":"270.885928ms","start":"2026-03-06T00:33:12.970526Z","end":"2026-03-06T00:33:13.241412Z","steps":["trace[2014183320] 'read index received' (duration: 209.262635ms)","trace[2014183320] 'applied index is now lower than readState.Index' (duration: 61.622303ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:33:13.241591Z","caller":"traceutil/trace.go:171","msg":"trace[1004747451] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"271.923392ms","start":"2026-03-06T00:33:12.969532Z","end":"2026-03-06T00:33:13.241455Z","steps":["trace[1004747451] 'process raft request' (duration: 210.3618ms)","trace[1004747451] 'compare' (duration: 61.382737ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:33:13.241721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.106603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-06T00:33:13.24177Z","caller":"traceutil/trace.go:171","msg":"trace[2112962396] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:74; }","duration":"271.235266ms","start":"2026-03-06T00:33:12.970522Z","end":"2026-03-06T00:33:13.241758Z","steps":["trace[2112962396] 'agreement among raft nodes before linearized reading' (duration: 270.99801ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:13.57823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.825251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-06T00:33:13.578466Z","caller":"traceutil/trace.go:171","msg":"trace[1916660295] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"275.509466ms","start":"2026-03-06T00:33:13.302934Z","end":"2026-03-06T00:33:13.578444Z","steps":["trace[1916660295] 'process raft request' (duration: 120.356038ms)","trace[1916660295] 'compare' (duration: 154.530224ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:33:13.976815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.678195ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-06T00:33:13.976925Z","caller":"traceutil/trace.go:171","msg":"trace[841434653] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"330.848831ms","start":"2026-03-06T00:33:13.646054Z","end":"2026-03-06T00:33:13.976903Z","steps":["trace[841434653] 'process raft request' (duration: 51.022082ms)","trace[841434653] 'compare' (duration: 279.526152ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:33:13.97707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:33:13.64604Z","time spent":"331.005505ms","remote":"127.0.0.1:53806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":665,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-06T00:33:14.188894Z","caller":"traceutil/trace.go:171","msg":"trace[201090530] linearizableReadLoop","detail":"{readStateIndex:83; appliedIndex:82; }","duration":"152.925795ms","start":"2026-03-06T00:33:14.03594Z","end":"2026-03-06T00:33:14.188866Z","steps":["trace[201090530] 'read index received' (duration: 120.417099ms)","trace[201090530] 'applied index is now lower than readState.Index' (duration: 32.507566ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:33:14.189034Z","caller":"traceutil/trace.go:171","msg":"trace[168102534] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"206.212102ms","start":"2026-03-06T00:33:13.982787Z","end":"2026-03-06T00:33:14.189Z","steps":["trace[168102534] 'process raft request' (duration: 173.634785ms)","trace[168102534] 'compare' (duration: 32.328101ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:33:14.189191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.238693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-06T00:33:14.189262Z","caller":"traceutil/trace.go:171","msg":"trace[1600135006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:79; }","duration":"153.348285ms","start":"2026-03-06T00:33:14.035876Z","end":"2026-03-06T00:33:14.189224Z","steps":["trace[1600135006] 'agreement among raft nodes before linearized reading' (duration: 153.12023ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:14.382687Z","caller":"traceutil/trace.go:171","msg":"trace[330590213] transaction","detail":"{read_only:false; response_revision:82; number_of_response:1; }","duration":"105.994269ms","start":"2026-03-06T00:33:14.276669Z","end":"2026-03-06T00:33:14.382663Z","steps":["trace[330590213] 'process raft request' (duration: 29.498775ms)","trace[330590213] 'compare' (duration: 76.192617ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:33:14.382754Z","caller":"traceutil/trace.go:171","msg":"trace[636396396] transaction","detail":"{read_only:false; response_revision:83; number_of_response:1; }","duration":"105.264882ms","start":"2026-03-06T00:33:14.277463Z","end":"2026-03-06T00:33:14.382728Z","steps":["trace[636396396] 'process raft request' (duration: 105.027896ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:14.580306Z","caller":"traceutil/trace.go:171","msg":"trace[573974534] transaction","detail":"{read_only:false; response_revision:86; number_of_response:1; }","duration":"114.351236ms","start":"2026-03-06T00:33:14.465924Z","end":"2026-03-06T00:33:14.580275Z","steps":["trace[573974534] 'process raft request' (duration: 41.839567ms)","trace[573974534] 'compare' (duration: 72.336575ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:33:14.826953Z","caller":"traceutil/trace.go:171","msg":"trace[962639793] linearizableReadLoop","detail":"{readStateIndex:92; appliedIndex:91; }","duration":"123.377219ms","start":"2026-03-06T00:33:14.703547Z","end":"2026-03-06T00:33:14.826924Z","steps":["trace[962639793] 'read index received' (duration: 53.194334ms)","trace[962639793] 'applied index is now lower than readState.Index' (duration: 70.181835ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:33:14.827102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.533233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-06T00:33:14.827134Z","caller":"traceutil/trace.go:171","msg":"trace[630634130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:88; }","duration":"123.612365ms","start":"2026-03-06T00:33:14.703512Z","end":"2026-03-06T00:33:14.827124Z","steps":["trace[630634130] 'agreement among raft nodes before linearized reading' (duration: 123.514993ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:14.827172Z","caller":"traceutil/trace.go:171","msg":"trace[1861909749] transaction","detail":"{read_only:false; response_revision:88; number_of_response:1; }","duration":"163.220159ms","start":"2026-03-06T00:33:14.663932Z","end":"2026-03-06T00:33:14.827152Z","steps":["trace[1861909749] 'process raft request' (duration: 92.89179ms)","trace[1861909749] 'compare' (duration: 69.96223ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:33:15.081857Z","caller":"traceutil/trace.go:171","msg":"trace[222736363] transaction","detail":"{read_only:false; response_revision:90; number_of_response:1; }","duration":"214.919618ms","start":"2026-03-06T00:33:14.86688Z","end":"2026-03-06T00:33:15.0818Z","steps":["trace[222736363] 'process raft request' (duration: 131.801018ms)","trace[222736363] 'compare' (duration: 82.892075ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:33:15.262775Z","caller":"traceutil/trace.go:171","msg":"trace[2119778153] linearizableReadLoop","detail":"{readStateIndex:96; appliedIndex:95; }","duration":"166.577848ms","start":"2026-03-06T00:33:15.096163Z","end":"2026-03-06T00:33:15.262741Z","steps":["trace[2119778153] 'read index received' (duration: 118.568186ms)","trace[2119778153] 'applied index is now lower than readState.Index' (duration: 48.008522ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:33:15.262902Z","caller":"traceutil/trace.go:171","msg":"trace[903390705] transaction","detail":"{read_only:false; response_revision:92; number_of_response:1; }","duration":"174.647359ms","start":"2026-03-06T00:33:15.088217Z","end":"2026-03-06T00:33:15.262864Z","steps":["trace[903390705] 'process raft request' (duration: 126.538785ms)","trace[903390705] 'compare' (duration: 47.850918ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:33:15.263017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.825094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:429"} {"level":"info","ts":"2026-03-06T00:33:15.263063Z","caller":"traceutil/trace.go:171","msg":"trace[513796528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:92; }","duration":"166.910086ms","start":"2026-03-06T00:33:15.096141Z","end":"2026-03-06T00:33:15.263051Z","steps":["trace[513796528] 'agreement among raft nodes before linearized reading' (duration: 166.710221ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:15.595315Z","caller":"traceutil/trace.go:171","msg":"trace[1740429344] transaction","detail":"{read_only:false; response_revision:96; number_of_response:1; }","duration":"242.956198ms","start":"2026-03-06T00:33:15.352321Z","end":"2026-03-06T00:33:15.595277Z","steps":["trace[1740429344] 'process raft request' (duration: 199.913534ms)","trace[1740429344] 'compare' (duration: 42.667356ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:33:16.079535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.576022ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-06T00:33:16.079689Z","caller":"traceutil/trace.go:171","msg":"trace[576333418] linearizableReadLoop","detail":"{readStateIndex:105; appliedIndex:104; }","duration":"174.862613ms","start":"2026-03-06T00:33:15.904802Z","end":"2026-03-06T00:33:16.079664Z","steps":["trace[576333418] 'read index received' (duration: 76.962µs)","trace[576333418] 'applied index is now lower than readState.Index' (duration: 174.783711ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:33:16.079842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.036447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-06T00:33:16.079944Z","caller":"traceutil/trace.go:171","msg":"trace[1825721168] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:101; }","duration":"175.19615ms","start":"2026-03-06T00:33:15.90473Z","end":"2026-03-06T00:33:16.079926Z","steps":["trace[1825721168] 'agreement among raft nodes before linearized reading' (duration: 175.005016ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:16.080036Z","caller":"traceutil/trace.go:171","msg":"trace[2086244579] transaction","detail":"{read_only:false; response_revision:101; number_of_response:1; }","duration":"257.129622ms","start":"2026-03-06T00:33:15.82285Z","end":"2026-03-06T00:33:16.07998Z","steps":["trace[2086244579] 'process raft request' (duration: 32.930406ms)","trace[2086244579] 'compare' (duration: 223.362127ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:33:16.514768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.811895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-06T00:33:16.514866Z","caller":"traceutil/trace.go:171","msg":"trace[697274476] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver; range_end:; response_count:0; response_revision:103; }","duration":"334.965039ms","start":"2026-03-06T00:33:16.179876Z","end":"2026-03-06T00:33:16.514841Z","steps":["trace[697274476] 'range keys from in-memory index tree' (duration: 334.691512ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:16.514907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:33:16.179855Z","time spent":"335.04283ms","remote":"127.0.0.1:53806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver\" "} {"level":"warn","ts":"2026-03-06T00:33:16.515134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.47464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-06T00:33:16.515614Z","caller":"traceutil/trace.go:171","msg":"trace[592662203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:103; }","duration":"128.625713ms","start":"2026-03-06T00:33:16.386572Z","end":"2026-03-06T00:33:16.515197Z","steps":["trace[592662203] 'range keys from in-memory index tree' (duration: 128.240094ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:16.768086Z","caller":"traceutil/trace.go:171","msg":"trace[1172924726] linearizableReadLoop","detail":"{readStateIndex:109; appliedIndex:108; }","duration":"174.422663ms","start":"2026-03-06T00:33:16.593638Z","end":"2026-03-06T00:33:16.768061Z","steps":["trace[1172924726] 'read index received' (duration: 174.269449ms)","trace[1172924726] 'applied index is now lower than readState.Index' (duration: 152.104µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:33:16.76822Z","caller":"traceutil/trace.go:171","msg":"trace[1615246852] transaction","detail":"{read_only:false; response_revision:105; number_of_response:1; }","duration":"247.503465ms","start":"2026-03-06T00:33:16.520676Z","end":"2026-03-06T00:33:16.768179Z","steps":["trace[1615246852] 'process raft request' (duration: 247.220009ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:16.768281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.613297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-06T00:33:16.768321Z","caller":"traceutil/trace.go:171","msg":"trace[2073188795] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver; range_end:; response_count:0; response_revision:105; }","duration":"174.72014ms","start":"2026-03-06T00:33:16.59359Z","end":"2026-03-06T00:33:16.76831Z","steps":["trace[2073188795] 'agreement among raft nodes before linearized reading' (duration: 174.606097ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:17.066083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.770624ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-06T00:33:17.066428Z","caller":"traceutil/trace.go:171","msg":"trace[1422498458] transaction","detail":"{read_only:false; response_revision:109; number_of_response:1; }","duration":"204.206365ms","start":"2026-03-06T00:33:16.862175Z","end":"2026-03-06T00:33:17.066381Z","steps":["trace[1422498458] 'process raft request' (duration: 81.041241ms)","trace[1422498458] 'compare' (duration: 122.553509ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:33:17.406159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.598815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-06T00:33:17.406267Z","caller":"traceutil/trace.go:171","msg":"trace[1211150287] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"288.570984ms","start":"2026-03-06T00:33:17.117679Z","end":"2026-03-06T00:33:17.40625Z","steps":["trace[1211150287] 'process raft request' (duration: 164.817356ms)","trace[1211150287] 'compare' (duration: 123.452871ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:33:17.449246Z","caller":"traceutil/trace.go:171","msg":"trace[1684473933] linearizableReadLoop","detail":"{readStateIndex:120; appliedIndex:118; }","duration":"134.494681ms","start":"2026-03-06T00:33:17.314726Z","end":"2026-03-06T00:33:17.44922Z","steps":["trace[1684473933] 'read index received' (duration: 134.277606ms)","trace[1684473933] 'applied index is now lower than readState.Index' (duration: 213.445µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:33:17.449557Z","caller":"traceutil/trace.go:171","msg":"trace[1490138693] transaction","detail":"{read_only:false; response_revision:115; number_of_response:1; }","duration":"318.07629ms","start":"2026-03-06T00:33:17.131461Z","end":"2026-03-06T00:33:17.449537Z","steps":["trace[1490138693] 'process raft request' (duration: 317.690051ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:17.449563Z","caller":"traceutil/trace.go:171","msg":"trace[1740618425] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"330.368671ms","start":"2026-03-06T00:33:17.119168Z","end":"2026-03-06T00:33:17.449537Z","steps":["trace[1740618425] 'process raft request' (duration: 329.837718ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:17.449761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.021124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:429"} {"level":"info","ts":"2026-03-06T00:33:17.449853Z","caller":"traceutil/trace.go:171","msg":"trace[1882426238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:115; }","duration":"135.154046ms","start":"2026-03-06T00:33:17.314669Z","end":"2026-03-06T00:33:17.449823Z","steps":["trace[1882426238] 'agreement among raft nodes before linearized reading' (duration: 134.981792ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:17.450072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:33:17.119148Z","time spent":"330.467212ms","remote":"127.0.0.1:53514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T00:33:17.450243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:33:17.131445Z","time spent":"318.173072ms","remote":"127.0.0.1:53624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4645,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:33:19.482872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.868148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-06T00:33:19.482984Z","caller":"traceutil/trace.go:171","msg":"trace[640988019] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:275; }","duration":"232.074602ms","start":"2026-03-06T00:33:19.250884Z","end":"2026-03-06T00:33:19.482959Z","steps":["trace[640988019] 'range keys from in-memory index tree' (duration: 231.771916ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:23.276756Z","caller":"traceutil/trace.go:171","msg":"trace[366314321] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"125.454958ms","start":"2026-03-06T00:33:23.151276Z","end":"2026-03-06T00:33:23.276731Z","steps":["trace[366314321] 'process raft request' (duration: 125.269284ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:30.557029Z","caller":"traceutil/trace.go:171","msg":"trace[1141362694] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"138.490545ms","start":"2026-03-06T00:33:30.41851Z","end":"2026-03-06T00:33:30.557001Z","steps":["trace[1141362694] 'process raft request' (duration: 138.139887ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:31.02763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.288809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T00:33:31.027968Z","caller":"traceutil/trace.go:171","msg":"trace[1318544147] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"358.550224ms","start":"2026-03-06T00:33:30.669385Z","end":"2026-03-06T00:33:31.027935Z","steps":["trace[1318544147] 'process raft request' (duration: 222.228801ms)","trace[1318544147] 'compare' (duration: 135.193877ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:33:31.028159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:33:30.669364Z","time spent":"358.693798ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T00:33:31.037533Z","caller":"traceutil/trace.go:171","msg":"trace[1207473621] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:435; }","duration":"290.337066ms","start":"2026-03-06T00:33:30.747175Z","end":"2026-03-06T00:33:31.037512Z","steps":["trace[1207473621] 'read index received' (duration: 144.453776ms)","trace[1207473621] 'applied index is now lower than readState.Index' (duration: 145.88217ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:33:31.037633Z","caller":"traceutil/trace.go:171","msg":"trace[686202225] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"362.194001ms","start":"2026-03-06T00:33:30.67541Z","end":"2026-03-06T00:33:31.037604Z","steps":["trace[686202225] 'process raft request' (duration: 362.003467ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:31.037726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.523591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-06T00:33:31.037769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:33:30.675396Z","time spent":"362.306203ms","remote":"127.0.0.1:53624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4115,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T00:33:31.03782Z","caller":"traceutil/trace.go:171","msg":"trace[1962940968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:420; }","duration":"290.672335ms","start":"2026-03-06T00:33:30.747122Z","end":"2026-03-06T00:33:31.037794Z","steps":["trace[1962940968] 'agreement among raft nodes before linearized reading' (duration: 290.49922ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:31.037727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.991472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-06T00:33:31.039268Z","caller":"traceutil/trace.go:171","msg":"trace[1631260324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:420; }","duration":"169.471886ms","start":"2026-03-06T00:33:30.869679Z","end":"2026-03-06T00:33:31.039151Z","steps":["trace[1631260324] 'agreement among raft nodes before linearized reading' (duration: 167.961111ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:31.484387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.378632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4374"} {"level":"info","ts":"2026-03-06T00:33:31.484488Z","caller":"traceutil/trace.go:171","msg":"trace[986100364] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:421; }","duration":"199.557195ms","start":"2026-03-06T00:33:31.284907Z","end":"2026-03-06T00:33:31.484464Z","steps":["trace[986100364] 'range keys from in-memory index tree' (duration: 199.219608ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:32.729179Z","caller":"traceutil/trace.go:171","msg":"trace[382737986] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"375.33529ms","start":"2026-03-06T00:33:32.353821Z","end":"2026-03-06T00:33:32.729156Z","steps":["trace[382737986] 'process raft request' (duration: 375.209867ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:32.729301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:33:32.353798Z","time spent":"375.447332ms","remote":"127.0.0.1:34602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-06T00:33:32.974864Z","caller":"traceutil/trace.go:171","msg":"trace[330246282] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"241.72033ms","start":"2026-03-06T00:33:32.733113Z","end":"2026-03-06T00:33:32.974833Z","steps":["trace[330246282] 'process raft request' (duration: 219.489765ms)","trace[330246282] 'compare' (duration: 21.952888ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:33:33.023525Z","caller":"traceutil/trace.go:171","msg":"trace[554549354] linearizableReadLoop","detail":"{readStateIndex:446; appliedIndex:443; }","duration":"281.342905ms","start":"2026-03-06T00:33:32.742159Z","end":"2026-03-06T00:33:33.023502Z","steps":["trace[554549354] 'read index received' (duration: 210.493953ms)","trace[554549354] 'applied index is now lower than readState.Index' (duration: 70.848092ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:33:33.023638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.467357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:33:33.023675Z","caller":"traceutil/trace.go:171","msg":"trace[897543013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:429; }","duration":"281.54234ms","start":"2026-03-06T00:33:32.74212Z","end":"2026-03-06T00:33:33.023662Z","steps":["trace[897543013] 'agreement among raft nodes before linearized reading' (duration: 281.471348ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:33.02366Z","caller":"traceutil/trace.go:171","msg":"trace[715663243] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"286.384713ms","start":"2026-03-06T00:33:32.737227Z","end":"2026-03-06T00:33:33.023612Z","steps":["trace[715663243] 'process raft request' (duration: 286.22968ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:33.023723Z","caller":"traceutil/trace.go:171","msg":"trace[496673006] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"290.05072ms","start":"2026-03-06T00:33:32.733629Z","end":"2026-03-06T00:33:33.02368Z","steps":["trace[496673006] 'process raft request' (duration: 289.739833ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:33.148104Z","caller":"traceutil/trace.go:171","msg":"trace[931593652] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"118.903414ms","start":"2026-03-06T00:33:33.029176Z","end":"2026-03-06T00:33:33.14808Z","steps":["trace[931593652] 'process raft request' (duration: 88.672841ms)","trace[931593652] 'compare' (duration: 30.036748ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:33:33.14815Z","caller":"traceutil/trace.go:171","msg":"trace[2114660431] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"117.481051ms","start":"2026-03-06T00:33:33.030646Z","end":"2026-03-06T00:33:33.148127Z","steps":["trace[2114660431] 'process raft request' (duration: 117.377829ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:33.148469Z","caller":"traceutil/trace.go:171","msg":"trace[1007818096] linearizableReadLoop","detail":"{readStateIndex:450; appliedIndex:446; }","duration":"116.964007ms","start":"2026-03-06T00:33:33.031482Z","end":"2026-03-06T00:33:33.148446Z","steps":["trace[1007818096] 'read index received' (duration: 14.819579ms)","trace[1007818096] 'applied index is now lower than readState.Index' (duration: 102.143428ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:33:33.148553Z","caller":"traceutil/trace.go:171","msg":"trace[1343466041] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"117.128343ms","start":"2026-03-06T00:33:33.031415Z","end":"2026-03-06T00:33:33.148543Z","steps":["trace[1343466041] 'process raft request' (duration: 116.670992ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:33.148568Z","caller":"traceutil/trace.go:171","msg":"trace[877314715] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"116.898526ms","start":"2026-03-06T00:33:33.031656Z","end":"2026-03-06T00:33:33.148555Z","steps":["trace[877314715] 'process raft request' (duration: 116.686891ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:33.148746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.247474ms","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-03-06T00:33:33.14884Z","caller":"traceutil/trace.go:171","msg":"trace[1479593027] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:434; }","duration":"117.397309ms","start":"2026-03-06T00:33:33.031417Z","end":"2026-03-06T00:33:33.148815Z","steps":["trace[1479593027] 'agreement among raft nodes before linearized reading' (duration: 117.210845ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:33.452686Z","caller":"traceutil/trace.go:171","msg":"trace[615743001] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"299.654666ms","start":"2026-03-06T00:33:33.152995Z","end":"2026-03-06T00:33:33.45265Z","steps":["trace[615743001] 'process raft request' (duration: 199.821411ms)","trace[615743001] 'compare' (duration: 99.61574ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:33:33.452804Z","caller":"traceutil/trace.go:171","msg":"trace[791346539] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"299.547793ms","start":"2026-03-06T00:33:33.153234Z","end":"2026-03-06T00:33:33.452782Z","steps":["trace[791346539] 'process raft request' (duration: 299.336798ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:33.453008Z","caller":"traceutil/trace.go:171","msg":"trace[712728011] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"299.459801ms","start":"2026-03-06T00:33:33.153544Z","end":"2026-03-06T00:33:33.453003Z","steps":["trace[712728011] 'process raft request' (duration: 299.143963ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:33.453051Z","caller":"traceutil/trace.go:171","msg":"trace[1516823321] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"298.421887ms","start":"2026-03-06T00:33:33.154625Z","end":"2026-03-06T00:33:33.453047Z","steps":["trace[1516823321] 'process raft request' (duration: 298.108869ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:33.45316Z","caller":"traceutil/trace.go:171","msg":"trace[1093242262] linearizableReadLoop","detail":"{readStateIndex:456; appliedIndex:451; }","duration":"297.898474ms","start":"2026-03-06T00:33:33.155256Z","end":"2026-03-06T00:33:33.453154Z","steps":["trace[1093242262] 'read index received' (duration: 9.248048ms)","trace[1093242262] 'applied index is now lower than readState.Index' (duration: 288.649826ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:33:33.79286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.050048ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-03-06T00:33:33.792973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.710866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-03-06T00:33:33.793004Z","caller":"traceutil/trace.go:171","msg":"trace[2086270030] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:439; }","duration":"637.765487ms","start":"2026-03-06T00:33:33.155225Z","end":"2026-03-06T00:33:33.79299Z","steps":["trace[2086270030] 'agreement among raft nodes before linearized reading' (duration: 297.948505ms)","trace[2086270030] 'range keys from in-memory index tree' (duration: 339.764921ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:33:33.793031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:33:33.155216Z","time spent":"637.809659ms","remote":"127.0.0.1:34562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4219,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-03-06T00:33:33.793063Z","caller":"traceutil/trace.go:171","msg":"trace[2041540610] transaction","detail":"{read_only:false; number_of_response:1; response_revision:440; }","duration":"338.913972ms","start":"2026-03-06T00:33:33.454143Z","end":"2026-03-06T00:33:33.793057Z","steps":["trace[2041540610] 'process raft request' (duration: 338.84168ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:33.793156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:33:33.45413Z","time spent":"338.957242ms","remote":"127.0.0.1:53624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":55,"response count":0,"response size":42,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-03-06T00:33:33.822381Z","caller":"traceutil/trace.go:171","msg":"trace[1403834240] transaction","detail":"{read_only:false; number_of_response:1; response_revision:441; }","duration":"363.554573ms","start":"2026-03-06T00:33:33.458809Z","end":"2026-03-06T00:33:33.822364Z","steps":["trace[1403834240] 'process raft request' (duration: 363.502731ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:33.822481Z","caller":"traceutil/trace.go:171","msg":"trace[494809996] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"366.192855ms","start":"2026-03-06T00:33:33.456269Z","end":"2026-03-06T00:33:33.822462Z","steps":["trace[494809996] 'process raft request' (duration: 365.956429ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:33.822507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:33:33.458802Z","time spent":"363.642634ms","remote":"127.0.0.1:34602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":56,"response count":0,"response size":3890,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:33:33.822569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:33:33.456244Z","time spent":"366.284567ms","remote":"127.0.0.1:53514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":721,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-06T00:33:33.822404Z","caller":"traceutil/trace.go:171","msg":"trace[163930383] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:460; }","duration":"210.875122ms","start":"2026-03-06T00:33:33.611503Z","end":"2026-03-06T00:33:33.822378Z","steps":["trace[163930383] 'read index received' (duration: 210.866372ms)","trace[163930383] 'applied index is now lower than readState.Index' (duration: 6.81µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:33:33.822746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.233381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-06T00:33:33.822955Z","caller":"traceutil/trace.go:171","msg":"trace[1473001253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:441; }","duration":"211.496877ms","start":"2026-03-06T00:33:33.611443Z","end":"2026-03-06T00:33:33.82294Z","steps":["trace[1473001253] 'agreement among raft nodes before linearized reading' (duration: 211.19558ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:34.03004Z","caller":"traceutil/trace.go:171","msg":"trace[896485178] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"225.721023ms","start":"2026-03-06T00:33:33.804296Z","end":"2026-03-06T00:33:34.030017Z","steps":["trace[896485178] 'process raft request' (duration: 225.672592ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:34.030326Z","caller":"traceutil/trace.go:171","msg":"trace[588414006] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"229.507102ms","start":"2026-03-06T00:33:33.800808Z","end":"2026-03-06T00:33:34.030315Z","steps":["trace[588414006] 'process raft request' (duration: 228.99131ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:34.030619Z","caller":"traceutil/trace.go:171","msg":"trace[1325043984] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"229.43159ms","start":"2026-03-06T00:33:33.801177Z","end":"2026-03-06T00:33:34.030608Z","steps":["trace[1325043984] 'process raft request' (duration: 228.733334ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:34.031073Z","caller":"traceutil/trace.go:171","msg":"trace[367941704] linearizableReadLoop","detail":"{readStateIndex:463; appliedIndex:463; }","duration":"208.291641ms","start":"2026-03-06T00:33:33.822757Z","end":"2026-03-06T00:33:34.031049Z","steps":["trace[367941704] 'read index received' (duration: 208.278ms)","trace[367941704] 'applied index is now lower than readState.Index' (duration: 11.681µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:33:34.031163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.542893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:33:34.031212Z","caller":"traceutil/trace.go:171","msg":"trace[203302106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:444; }","duration":"285.629585ms","start":"2026-03-06T00:33:33.745571Z","end":"2026-03-06T00:33:34.031201Z","steps":["trace[203302106] 'agreement among raft nodes before linearized reading' (duration: 285.544743ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:34.059907Z","caller":"traceutil/trace.go:171","msg":"trace[313706795] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"233.308232ms","start":"2026-03-06T00:33:33.826574Z","end":"2026-03-06T00:33:34.059882Z","steps":["trace[313706795] 'process raft request' (duration: 233.037236ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:34.059952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.531973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3855"} {"level":"info","ts":"2026-03-06T00:33:34.060043Z","caller":"traceutil/trace.go:171","msg":"trace[666121722] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:445; }","duration":"232.663726ms","start":"2026-03-06T00:33:33.827355Z","end":"2026-03-06T00:33:34.060019Z","steps":["trace[666121722] 'agreement among raft nodes before linearized reading' (duration: 232.452501ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:34.246642Z","caller":"traceutil/trace.go:171","msg":"trace[668612984] linearizableReadLoop","detail":"{readStateIndex:468; appliedIndex:467; }","duration":"152.206479ms","start":"2026-03-06T00:33:34.094399Z","end":"2026-03-06T00:33:34.246605Z","steps":["trace[668612984] 'read index received' (duration: 124.828633ms)","trace[668612984] 'applied index is now lower than readState.Index' (duration: 27.376186ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:33:34.246724Z","caller":"traceutil/trace.go:171","msg":"trace[565884831] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"181.483789ms","start":"2026-03-06T00:33:34.065204Z","end":"2026-03-06T00:33:34.246688Z","steps":["trace[565884831] 'process raft request' (duration: 153.870917ms)","trace[565884831] 'compare' (duration: 27.370356ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:33:34.246939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.408316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3855"} {"level":"info","ts":"2026-03-06T00:33:34.24703Z","caller":"traceutil/trace.go:171","msg":"trace[1596812536] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:449; }","duration":"178.57375ms","start":"2026-03-06T00:33:34.068435Z","end":"2026-03-06T00:33:34.247008Z","steps":["trace[1596812536] 'agreement among raft nodes before linearized reading' (duration: 178.314244ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:34.344182Z","caller":"traceutil/trace.go:171","msg":"trace[307686135] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"239.748253ms","start":"2026-03-06T00:33:34.104402Z","end":"2026-03-06T00:33:34.34415Z","steps":["trace[307686135] 'process raft request' (duration: 239.593849ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:34.560699Z","caller":"traceutil/trace.go:171","msg":"trace[1939430684] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"309.900947ms","start":"2026-03-06T00:33:34.250765Z","end":"2026-03-06T00:33:34.560666Z","steps":["trace[1939430684] 'process raft request' (duration: 249.403591ms)","trace[1939430684] 'compare' (duration: 60.332222ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:33:34.560857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:33:34.250747Z","time spent":"310.031229ms","remote":"127.0.0.1:53624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-06T00:33:45.798467Z","caller":"traceutil/trace.go:171","msg":"trace[761431019] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"273.364467ms","start":"2026-03-06T00:33:45.525076Z","end":"2026-03-06T00:33:45.798441Z","steps":["trace[761431019] 'read index received' (duration: 273.209443ms)","trace[761431019] 'applied index is now lower than readState.Index' (duration: 153.384µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:33:45.798728Z","caller":"traceutil/trace.go:171","msg":"trace[2089634918] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"280.156796ms","start":"2026-03-06T00:33:45.518521Z","end":"2026-03-06T00:33:45.798678Z","steps":["trace[2089634918] 'process raft request' (duration: 279.768217ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:45.798753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.663903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-06T00:33:45.798808Z","caller":"traceutil/trace.go:171","msg":"trace[555239539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:515; }","duration":"273.767006ms","start":"2026-03-06T00:33:45.525028Z","end":"2026-03-06T00:33:45.798795Z","steps":["trace[555239539] 'agreement among raft nodes before linearized reading' (duration: 273.51348ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:45.911784Z","caller":"traceutil/trace.go:171","msg":"trace[1692204909] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"108.843497ms","start":"2026-03-06T00:33:45.802899Z","end":"2026-03-06T00:33:45.911742Z","steps":["trace[1692204909] 'process raft request' (duration: 90.874414ms)","trace[1692204909] 'compare' (duration: 17.747498ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:33:46.464671Z","caller":"traceutil/trace.go:171","msg":"trace[1377526950] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:538; }","duration":"113.491237ms","start":"2026-03-06T00:33:46.351164Z","end":"2026-03-06T00:33:46.464655Z","steps":["trace[1377526950] 'read index received' (duration: 113.357024ms)","trace[1377526950] 'applied index is now lower than readState.Index' (duration: 133.633µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:33:46.464778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.601889ms","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-03-06T00:33:46.464796Z","caller":"traceutil/trace.go:171","msg":"trace[215710134] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:518; }","duration":"113.65727ms","start":"2026-03-06T00:33:46.351133Z","end":"2026-03-06T00:33:46.46479Z","steps":["trace[215710134] 'agreement among raft nodes before linearized reading' (duration: 113.593249ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:33:46.465045Z","caller":"traceutil/trace.go:171","msg":"trace[1035881964] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"284.727784ms","start":"2026-03-06T00:33:46.180312Z","end":"2026-03-06T00:33:46.46504Z","steps":["trace[1035881964] 'process raft request' (duration: 284.254683ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:46.999409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.224815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T00:33:46.999613Z","caller":"traceutil/trace.go:171","msg":"trace[1459524697] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:539; }","duration":"255.558807ms","start":"2026-03-06T00:33:46.744038Z","end":"2026-03-06T00:33:46.999596Z","steps":["trace[1459524697] 'read index received' (duration: 56.761µs)","trace[1459524697] 'applied index is now lower than readState.Index' (duration: 255.500826ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:33:46.999645Z","caller":"traceutil/trace.go:171","msg":"trace[1430232290] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"526.119506ms","start":"2026-03-06T00:33:46.473496Z","end":"2026-03-06T00:33:46.999616Z","steps":["trace[1430232290] 'process raft request' (duration: 190.621995ms)","trace[1430232290] 'compare' (duration: 334.833835ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:33:47.000283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:33:46.473472Z","time spent":"526.766311ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:33:46.999703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.658359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:33:47.00036Z","caller":"traceutil/trace.go:171","msg":"trace[1726333494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"256.352145ms","start":"2026-03-06T00:33:46.743988Z","end":"2026-03-06T00:33:47.00034Z","steps":["trace[1726333494] 'agreement among raft nodes before linearized reading' (duration: 255.659439ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:52.483144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.604926ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T00:33:52.483903Z","caller":"traceutil/trace.go:171","msg":"trace[1052763196] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"543.344632ms","start":"2026-03-06T00:33:51.940524Z","end":"2026-03-06T00:33:52.483869Z","steps":["trace[1052763196] 'process raft request' (duration: 21.929447ms)","trace[1052763196] 'compare' (duration: 520.385381ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:33:52.483959Z","caller":"traceutil/trace.go:171","msg":"trace[1110788784] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:555; }","duration":"423.717741ms","start":"2026-03-06T00:33:52.060222Z","end":"2026-03-06T00:33:52.48394Z","steps":["trace[1110788784] 'read index received' (duration: 84.632µs)","trace[1110788784] 'applied index is now lower than readState.Index' (duration: 423.630719ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:33:52.484071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:33:51.940497Z","time spent":"543.486025ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:33:52.484118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.903615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-m5s6l\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-06T00:33:52.484222Z","caller":"traceutil/trace.go:171","msg":"trace[725619703] range","detail":"{range_begin:/registry/pods/kube-system/cilium-m5s6l; range_end:; response_count:1; response_revision:534; }","duration":"424.019088ms","start":"2026-03-06T00:33:52.060137Z","end":"2026-03-06T00:33:52.484156Z","steps":["trace[725619703] 'agreement among raft nodes before linearized reading' (duration: 423.861515ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:52.484269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:33:52.060103Z","time spent":"424.156153ms","remote":"127.0.0.1:53624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-m5s6l\" "} {"level":"info","ts":"2026-03-06T00:33:53.196347Z","caller":"traceutil/trace.go:171","msg":"trace[1735959374] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"697.733682ms","start":"2026-03-06T00:33:52.49858Z","end":"2026-03-06T00:33:53.196314Z","steps":["trace[1735959374] 'process raft request' (duration: 697.421215ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:53.196636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:33:52.498566Z","time spent":"697.883585ms","remote":"127.0.0.1:53624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:33:54.26806Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.072088419s","expected-duration":"1s"} {"level":"info","ts":"2026-03-06T00:33:54.269044Z","caller":"traceutil/trace.go:171","msg":"trace[1116657640] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.769553926s","start":"2026-03-06T00:33:52.499472Z","end":"2026-03-06T00:33:54.269026Z","steps":["trace[1116657640] 'process raft request' (duration: 1.769379331s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:54.269173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:33:52.499457Z","time spent":"1.769648928s","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T00:33:54.269241Z","caller":"traceutil/trace.go:171","msg":"trace[777455982] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.73489989s","start":"2026-03-06T00:33:52.534303Z","end":"2026-03-06T00:33:54.269203Z","steps":["trace[777455982] 'process raft request' (duration: 1.734683435s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:54.269531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:33:52.534283Z","time spent":"1.735127374s","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T00:33:54.269822Z","caller":"traceutil/trace.go:171","msg":"trace[620748633] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:556; }","duration":"1.523644728s","start":"2026-03-06T00:33:52.746161Z","end":"2026-03-06T00:33:54.269805Z","steps":["trace[620748633] 'read index received' (duration: 449.793007ms)","trace[620748633] 'applied index is now lower than readState.Index' (duration: 1.073850371s)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:33:54.26991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.52375328s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:33:54.269936Z","caller":"traceutil/trace.go:171","msg":"trace[436016075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"1.523817431s","start":"2026-03-06T00:33:52.74611Z","end":"2026-03-06T00:33:54.269928Z","steps":["trace[436016075] 'agreement among raft nodes before linearized reading' (duration: 1.52376351s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:54.269961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:33:52.746091Z","time spent":"1.523865132s","remote":"127.0.0.1:53432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T00:33:54.273662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.140183ms","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-03-06T00:33:54.27369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.253869496s","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-03-06T00:33:54.273696Z","caller":"traceutil/trace.go:171","msg":"trace[1533861392] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:537; }","duration":"408.213605ms","start":"2026-03-06T00:33:53.865473Z","end":"2026-03-06T00:33:54.273686Z","steps":["trace[1533861392] 'agreement among raft nodes before linearized reading' (duration: 408.116373ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:54.273718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:33:53.865453Z","time spent":"408.261136ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-06T00:33:54.273717Z","caller":"traceutil/trace.go:171","msg":"trace[1112228624] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:537; }","duration":"1.253898956s","start":"2026-03-06T00:33:53.019811Z","end":"2026-03-06T00:33:54.27371Z","steps":["trace[1112228624] 'agreement among raft nodes before linearized reading' (duration: 1.253810494s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:54.273759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:33:53.019741Z","time spent":"1.253990938s","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-06T00:33:56.534121Z","caller":"traceutil/trace.go:171","msg":"trace[844370475] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:590; }","duration":"258.973856ms","start":"2026-03-06T00:33:56.275123Z","end":"2026-03-06T00:33:56.534097Z","steps":["trace[844370475] 'read index received' (duration: 258.848003ms)","trace[844370475] 'applied index is now lower than readState.Index' (duration: 124.643µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:33:56.534162Z","caller":"traceutil/trace.go:171","msg":"trace[828842335] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"274.726128ms","start":"2026-03-06T00:33:56.259398Z","end":"2026-03-06T00:33:56.534124Z","steps":["trace[828842335] 'process raft request' (duration: 274.583735ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:56.534391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.252643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-06T00:33:56.534455Z","caller":"traceutil/trace.go:171","msg":"trace[1505193035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:568; }","duration":"259.329615ms","start":"2026-03-06T00:33:56.27511Z","end":"2026-03-06T00:33:56.534439Z","steps":["trace[1505193035] 'agreement among raft nodes before linearized reading' (duration: 259.12089ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:56.543898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.987216ms","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-03-06T00:33:56.544036Z","caller":"traceutil/trace.go:171","msg":"trace[787715380] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:569; }","duration":"264.232161ms","start":"2026-03-06T00:33:56.279786Z","end":"2026-03-06T00:33:56.544018Z","steps":["trace[787715380] 'agreement among raft nodes before linearized reading' (duration: 263.972575ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:33:56.54403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.183206ms","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-03-06T00:33:56.544157Z","caller":"traceutil/trace.go:171","msg":"trace[1409524421] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:569; }","duration":"263.335189ms","start":"2026-03-06T00:33:56.280807Z","end":"2026-03-06T00:33:56.544142Z","steps":["trace[1409524421] 'agreement among raft nodes before linearized reading' (duration: 263.091873ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:34:03.017137Z","caller":"traceutil/trace.go:171","msg":"trace[771933281] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"105.029376ms","start":"2026-03-06T00:34:02.912078Z","end":"2026-03-06T00:34:03.017108Z","steps":["trace[771933281] 'read index received' (duration: 103.481331ms)","trace[771933281] 'applied index is now lower than readState.Index' (duration: 1.546975ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:34:03.0175Z","caller":"traceutil/trace.go:171","msg":"trace[1043694910] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"113.449273ms","start":"2026-03-06T00:34:02.90404Z","end":"2026-03-06T00:34:03.017489Z","steps":["trace[1043694910] 'process raft request' (duration: 111.674281ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:03.017705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.5895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:34:03.017737Z","caller":"traceutil/trace.go:171","msg":"trace[558151484] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:647; }","duration":"105.677662ms","start":"2026-03-06T00:34:02.91205Z","end":"2026-03-06T00:34:03.017728Z","steps":["trace[558151484] 'agreement among raft nodes before linearized reading' (duration: 105.59048ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:03.0179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.771074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:34:03.017926Z","caller":"traceutil/trace.go:171","msg":"trace[1774222730] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:647; }","duration":"105.799785ms","start":"2026-03-06T00:34:02.912118Z","end":"2026-03-06T00:34:03.017918Z","steps":["trace[1774222730] 'agreement among raft nodes before linearized reading' (duration: 105.756974ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:03.018119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.390255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:34:03.018148Z","caller":"traceutil/trace.go:171","msg":"trace[1506831943] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:647; }","duration":"105.419576ms","start":"2026-03-06T00:34:02.912719Z","end":"2026-03-06T00:34:03.018138Z","steps":["trace[1506831943] 'agreement among raft nodes before linearized reading' (duration: 105.377725ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:34:04.733206Z","caller":"traceutil/trace.go:171","msg":"trace[213799194] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"104.097655ms","start":"2026-03-06T00:34:04.629087Z","end":"2026-03-06T00:34:04.733185Z","steps":["trace[213799194] 'process raft request' (duration: 103.993173ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:34:06.067355Z","caller":"traceutil/trace.go:171","msg":"trace[1273470703] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"165.866534ms","start":"2026-03-06T00:34:05.90146Z","end":"2026-03-06T00:34:06.067327Z","steps":["trace[1273470703] 'process raft request' (duration: 165.739471ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:34:09.291997Z","caller":"traceutil/trace.go:171","msg":"trace[1668267406] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"139.768027ms","start":"2026-03-06T00:34:09.152211Z","end":"2026-03-06T00:34:09.291979Z","steps":["trace[1668267406] 'process raft request' (duration: 139.655634ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:34:14.963013Z","caller":"traceutil/trace.go:171","msg":"trace[902884886] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:752; }","duration":"224.241235ms","start":"2026-03-06T00:34:14.738741Z","end":"2026-03-06T00:34:14.962982Z","steps":["trace[902884886] 'read index received' (duration: 224.085552ms)","trace[902884886] 'applied index is now lower than readState.Index' (duration: 154.403µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:34:14.963076Z","caller":"traceutil/trace.go:171","msg":"trace[2043330842] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"592.864844ms","start":"2026-03-06T00:34:14.370185Z","end":"2026-03-06T00:34:14.963049Z","steps":["trace[2043330842] 'process raft request' (duration: 592.641989ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:14.9632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:14.370163Z","time spent":"592.967256ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:34:14.963234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.47623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-06T00:34:14.963269Z","caller":"traceutil/trace.go:171","msg":"trace[2040595910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:724; }","duration":"224.551491ms","start":"2026-03-06T00:34:14.738706Z","end":"2026-03-06T00:34:14.963257Z","steps":["trace[2040595910] 'agreement among raft nodes before linearized reading' (duration: 224.402198ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:34:15.183602Z","caller":"traceutil/trace.go:171","msg":"trace[636851763] linearizableReadLoop","detail":"{readStateIndex:754; appliedIndex:753; }","duration":"220.497667ms","start":"2026-03-06T00:34:14.963083Z","end":"2026-03-06T00:34:15.18358Z","steps":["trace[636851763] 'read index received' (duration: 220.426775ms)","trace[636851763] 'applied index is now lower than readState.Index' (duration: 70.062µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:34:15.183699Z","caller":"traceutil/trace.go:171","msg":"trace[809648974] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"242.94358ms","start":"2026-03-06T00:34:14.940724Z","end":"2026-03-06T00:34:15.183668Z","steps":["trace[809648974] 'process raft request' (duration: 242.707355ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:15.183781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.784141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-06T00:34:15.183809Z","caller":"traceutil/trace.go:171","msg":"trace[954417851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:725; }","duration":"434.842972ms","start":"2026-03-06T00:34:14.748959Z","end":"2026-03-06T00:34:15.183802Z","steps":["trace[954417851] 'agreement among raft nodes before linearized reading' (duration: 434.699758ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:15.183838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:14.748946Z","time spent":"434.886464ms","remote":"127.0.0.1:53716","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-03-06T00:34:15.184016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.614777ms","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-03-06T00:34:15.184089Z","caller":"traceutil/trace.go:171","msg":"trace[1288538082] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:725; }","duration":"334.725339ms","start":"2026-03-06T00:34:14.849348Z","end":"2026-03-06T00:34:15.184073Z","steps":["trace[1288538082] 'agreement among raft nodes before linearized reading' (duration: 334.521295ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:15.18409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.482012ms","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-03-06T00:34:15.184154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:14.849331Z","time spent":"334.813321ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-06T00:34:15.18419Z","caller":"traceutil/trace.go:171","msg":"trace[544640863] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:725; }","duration":"361.610385ms","start":"2026-03-06T00:34:14.822551Z","end":"2026-03-06T00:34:15.184161Z","steps":["trace[544640863] 'agreement among raft nodes before linearized reading' (duration: 361.3569ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:15.18424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:14.822535Z","time spent":"361.696737ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-06T00:34:15.223211Z","caller":"traceutil/trace.go:171","msg":"trace[1427821312] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"256.051707ms","start":"2026-03-06T00:34:14.967135Z","end":"2026-03-06T00:34:15.223187Z","steps":["trace[1427821312] 'process raft request' (duration: 255.889933ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:15.860132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.512111ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T00:34:15.860394Z","caller":"traceutil/trace.go:171","msg":"trace[440167100] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:755; }","duration":"304.230029ms","start":"2026-03-06T00:34:15.556147Z","end":"2026-03-06T00:34:15.860377Z","steps":["trace[440167100] 'read index received' (duration: 32.15µs)","trace[440167100] 'applied index is now lower than readState.Index' (duration: 304.194729ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:34:15.860417Z","caller":"traceutil/trace.go:171","msg":"trace[1058640559] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"671.917485ms","start":"2026-03-06T00:34:15.188477Z","end":"2026-03-06T00:34:15.860394Z","steps":["trace[1058640559] 'process raft request' (duration: 671.761602ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:34:15.86046Z","caller":"traceutil/trace.go:171","msg":"trace[1667969399] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"671.863764ms","start":"2026-03-06T00:34:15.188566Z","end":"2026-03-06T00:34:15.86043Z","steps":["trace[1667969399] 'process raft request' (duration: 671.766122ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:15.860495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.350751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:34:15.860466Z","caller":"traceutil/trace.go:171","msg":"trace[310575871] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"672.316475ms","start":"2026-03-06T00:34:15.188113Z","end":"2026-03-06T00:34:15.860429Z","steps":["trace[310575871] 'process raft request' (duration: 71.388024ms)","trace[310575871] 'compare' (duration: 600.206015ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:34:15.860529Z","caller":"traceutil/trace.go:171","msg":"trace[1428360353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:729; }","duration":"304.411803ms","start":"2026-03-06T00:34:15.556107Z","end":"2026-03-06T00:34:15.860519Z","steps":["trace[1428360353] 'agreement among raft nodes before linearized reading' (duration: 304.360542ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:15.860562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:15.556084Z","time spent":"304.471265ms","remote":"127.0.0.1:53432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T00:34:15.860556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.368618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-06T00:34:15.860606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:15.188558Z","time spent":"671.964427ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T00:34:15.860617Z","caller":"traceutil/trace.go:171","msg":"trace[490238987] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:729; }","duration":"224.46029ms","start":"2026-03-06T00:34:15.636142Z","end":"2026-03-06T00:34:15.860603Z","steps":["trace[490238987] 'agreement among raft nodes before linearized reading' (duration: 224.343467ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:15.860619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:15.188452Z","time spent":"672.020378ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:34:15.860628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:15.188093Z","time spent":"672.446708ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:34:15.860666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.00969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:34:15.86072Z","caller":"traceutil/trace.go:171","msg":"trace[1710344081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:729; }","duration":"115.094391ms","start":"2026-03-06T00:34:15.745608Z","end":"2026-03-06T00:34:15.860702Z","steps":["trace[1710344081] 'agreement among raft nodes before linearized reading' (duration: 115.013609ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:17.623145Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620624987604421403,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-06T00:34:17.887795Z","caller":"traceutil/trace.go:171","msg":"trace[1368757089] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:758; }","duration":"765.072968ms","start":"2026-03-06T00:34:17.122691Z","end":"2026-03-06T00:34:17.887764Z","steps":["trace[1368757089] 'read index received' (duration: 764.935765ms)","trace[1368757089] 'applied index is now lower than readState.Index' (duration: 135.743µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:34:17.887963Z","caller":"traceutil/trace.go:171","msg":"trace[184622343] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"915.250096ms","start":"2026-03-06T00:34:16.972703Z","end":"2026-03-06T00:34:17.887953Z","steps":["trace[184622343] 'process raft request' (duration: 914.864227ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:17.888112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:16.972677Z","time spent":"915.313278ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:34:17.888529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.817555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T00:34:17.888612Z","caller":"traceutil/trace.go:171","msg":"trace[740628920] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:730; }","duration":"765.952938ms","start":"2026-03-06T00:34:17.122637Z","end":"2026-03-06T00:34:17.88859Z","steps":["trace[740628920] 'agreement among raft nodes before linearized reading' (duration: 765.829855ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:17.888652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:17.122615Z","time spent":"766.026669ms","remote":"127.0.0.1:53532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":4,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-03-06T00:34:17.889036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.832421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-06T00:34:17.889093Z","caller":"traceutil/trace.go:171","msg":"trace[826874434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:730; }","duration":"661.906993ms","start":"2026-03-06T00:34:17.227163Z","end":"2026-03-06T00:34:17.88907Z","steps":["trace[826874434] 'agreement among raft nodes before linearized reading' (duration: 660.862699ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:17.889131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:17.227147Z","time spent":"661.974545ms","remote":"127.0.0.1:53716","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-03-06T00:34:18.507621Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620624987604421406,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T00:34:19.008549Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620624987604421406,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T00:34:19.069861Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.176619105s","expected-duration":"1s"} {"level":"info","ts":"2026-03-06T00:34:19.070201Z","caller":"traceutil/trace.go:171","msg":"trace[2011125270] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"1.177044035s","start":"2026-03-06T00:34:17.893124Z","end":"2026-03-06T00:34:19.070168Z","steps":["trace[2011125270] 'process raft request' (duration: 1.176887332s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:19.070378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:17.893098Z","time spent":"1.177181148s","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T00:34:19.12729Z","caller":"traceutil/trace.go:171","msg":"trace[232286101] linearizableReadLoop","detail":"{readStateIndex:763; appliedIndex:759; }","duration":"1.119930634s","start":"2026-03-06T00:34:18.007341Z","end":"2026-03-06T00:34:19.127271Z","steps":["trace[232286101] 'read index received' (duration: 1.062779203s)","trace[232286101] 'applied index is now lower than readState.Index' (duration: 57.150301ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:34:19.127324Z","caller":"traceutil/trace.go:171","msg":"trace[1507893015] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"1.232528688s","start":"2026-03-06T00:34:17.894767Z","end":"2026-03-06T00:34:19.127296Z","steps":["trace[1507893015] 'process raft request' (duration: 1.232440066s)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:34:19.127389Z","caller":"traceutil/trace.go:171","msg":"trace[466641834] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"1.232055697s","start":"2026-03-06T00:34:17.895326Z","end":"2026-03-06T00:34:19.127382Z","steps":["trace[466641834] 'process raft request' (duration: 1.231906944s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:19.127457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.44344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:34:19.127447Z","caller":"traceutil/trace.go:171","msg":"trace[729654141] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"1.232762494s","start":"2026-03-06T00:34:17.894643Z","end":"2026-03-06T00:34:19.127406Z","steps":["trace[729654141] 'process raft request' (duration: 1.200947853s)","trace[729654141] 'compare' (duration: 31.524834ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:34:19.127479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:17.89476Z","time spent":"1.23263769s","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T00:34:19.127515Z","caller":"traceutil/trace.go:171","msg":"trace[1399483779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:734; }","duration":"382.566823ms","start":"2026-03-06T00:34:18.744935Z","end":"2026-03-06T00:34:19.127501Z","steps":["trace[1399483779] 'agreement among raft nodes before linearized reading' (duration: 382.476061ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:19.127556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:18.744914Z","time spent":"382.634865ms","remote":"127.0.0.1:53432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T00:34:19.127588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.120259273s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-06T00:34:19.127619Z","caller":"traceutil/trace.go:171","msg":"trace[529078461] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:734; }","duration":"1.120337384s","start":"2026-03-06T00:34:18.007272Z","end":"2026-03-06T00:34:19.127609Z","steps":["trace[529078461] 'agreement among raft nodes before linearized reading' (duration: 1.120240472s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:19.127482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:17.895317Z","time spent":"1.232097659s","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:34:19.127623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:17.894626Z","time spent":"1.232911527s","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:34:19.127645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:18.007249Z","time spent":"1.120390055s","remote":"127.0.0.1:53472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.65\" "} {"level":"warn","ts":"2026-03-06T00:34:20.493362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.241412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-06T00:34:20.493443Z","caller":"traceutil/trace.go:171","msg":"trace[1378143163] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:735; }","duration":"599.548369ms","start":"2026-03-06T00:34:19.893877Z","end":"2026-03-06T00:34:20.493425Z","steps":["trace[1378143163] 'range keys from in-memory index tree' (duration: 599.043588ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:20.493482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:19.89386Z","time spent":"599.61308ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-06T00:34:21.387403Z","caller":"traceutil/trace.go:171","msg":"trace[2069044907] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:767; }","duration":"174.167118ms","start":"2026-03-06T00:34:21.213215Z","end":"2026-03-06T00:34:21.387382Z","steps":["trace[2069044907] 'read index received' (duration: 162.283111ms)","trace[2069044907] 'applied index is now lower than readState.Index' (duration: 11.882907ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:34:21.387406Z","caller":"traceutil/trace.go:171","msg":"trace[1948265288] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"225.584026ms","start":"2026-03-06T00:34:21.161796Z","end":"2026-03-06T00:34:21.38738Z","steps":["trace[1948265288] 'process raft request' (duration: 213.683138ms)","trace[1948265288] 'compare' (duration: 11.696873ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:34:21.387594Z","caller":"traceutil/trace.go:171","msg":"trace[1501100961] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"147.439365ms","start":"2026-03-06T00:34:21.240114Z","end":"2026-03-06T00:34:21.387553Z","steps":["trace[1501100961] 'process raft request' (duration: 147.238091ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:21.387662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.425433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/coredns-7c96b6546b-c42hk.189a1953d75d4188\" ","response":"range_response_count:1 size:828"} {"level":"info","ts":"2026-03-06T00:34:21.387678Z","caller":"traceutil/trace.go:171","msg":"trace[1522833396] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"224.800917ms","start":"2026-03-06T00:34:21.162859Z","end":"2026-03-06T00:34:21.38766Z","steps":["trace[1522833396] 'process raft request' (duration: 224.438269ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:34:21.387703Z","caller":"traceutil/trace.go:171","msg":"trace[982406120] range","detail":"{range_begin:/registry/events/kube-system/coredns-7c96b6546b-c42hk.189a1953d75d4188; range_end:; response_count:1; response_revision:741; }","duration":"174.495014ms","start":"2026-03-06T00:34:21.213187Z","end":"2026-03-06T00:34:21.387682Z","steps":["trace[982406120] 'agreement among raft nodes before linearized reading' (duration: 174.381292ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:34:21.387595Z","caller":"traceutil/trace.go:171","msg":"trace[979923507] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"224.228474ms","start":"2026-03-06T00:34:21.163326Z","end":"2026-03-06T00:34:21.387554Z","steps":["trace[979923507] 'process raft request' (duration: 224.002898ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:34:35.326147Z","caller":"traceutil/trace.go:171","msg":"trace[2094832725] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"115.447141ms","start":"2026-03-06T00:34:35.210676Z","end":"2026-03-06T00:34:35.326124Z","steps":["trace[2094832725] 'process raft request' (duration: 115.296158ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:34:37.647538Z","caller":"traceutil/trace.go:171","msg":"trace[215347005] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"131.089933ms","start":"2026-03-06T00:34:37.516416Z","end":"2026-03-06T00:34:37.647506Z","steps":["trace[215347005] 'process raft request' (duration: 129.879475ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:41.8898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.855705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T00:34:41.889913Z","caller":"traceutil/trace.go:171","msg":"trace[143733661] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:835; }","duration":"325.499044ms","start":"2026-03-06T00:34:41.564396Z","end":"2026-03-06T00:34:41.889895Z","steps":["trace[143733661] 'read index received' (duration: 180.381202ms)","trace[143733661] 'applied index is now lower than readState.Index' (duration: 145.114902ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:34:41.890192Z","caller":"traceutil/trace.go:171","msg":"trace[1035255514] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"353.958618ms","start":"2026-03-06T00:34:41.536111Z","end":"2026-03-06T00:34:41.89007Z","steps":["trace[1035255514] 'process raft request' (duration: 208.750954ms)","trace[1035255514] 'compare' (duration: 144.742873ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:34:41.890249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.830761ms","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-03-06T00:34:41.890323Z","caller":"traceutil/trace.go:171","msg":"trace[1491551765] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:801; }","duration":"325.934163ms","start":"2026-03-06T00:34:41.564369Z","end":"2026-03-06T00:34:41.890303Z","steps":["trace[1491551765] 'agreement among raft nodes before linearized reading' (duration: 325.692688ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:41.890369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.080043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-06T00:34:41.890379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:41.564355Z","time spent":"326.016256ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-06T00:34:41.890475Z","caller":"traceutil/trace.go:171","msg":"trace[1835457157] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:801; }","duration":"143.149295ms","start":"2026-03-06T00:34:41.747252Z","end":"2026-03-06T00:34:41.890401Z","steps":["trace[1835457157] 'agreement among raft nodes before linearized reading' (duration: 143.078164ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:41.890533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.73561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-06T00:34:41.890724Z","caller":"traceutil/trace.go:171","msg":"trace[2030933900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:801; }","duration":"228.969265ms","start":"2026-03-06T00:34:41.661736Z","end":"2026-03-06T00:34:41.890706Z","steps":["trace[2030933900] 'agreement among raft nodes before linearized reading' (duration: 228.396862ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:41.890561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:41.5361Z","time spent":"354.183543ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:34:42.434893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.373325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T00:34:42.435181Z","caller":"traceutil/trace.go:171","msg":"trace[792926837] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"540.35049ms","start":"2026-03-06T00:34:41.894788Z","end":"2026-03-06T00:34:42.435139Z","steps":["trace[792926837] 'process raft request' (duration: 152.616476ms)","trace[792926837] 'compare' (duration: 387.14258ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:34:42.435356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:41.894775Z","time spent":"540.498533ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T00:34:42.435358Z","caller":"traceutil/trace.go:171","msg":"trace[159946440] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:837; }","duration":"485.177534ms","start":"2026-03-06T00:34:41.950142Z","end":"2026-03-06T00:34:42.43532Z","steps":["trace[159946440] 'read index received' (duration: 97.212545ms)","trace[159946440] 'applied index is now lower than readState.Index' (duration: 387.960289ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:34:42.435544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.37812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T00:34:42.435591Z","caller":"traceutil/trace.go:171","msg":"trace[300959142] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:804; }","duration":"485.485352ms","start":"2026-03-06T00:34:41.950094Z","end":"2026-03-06T00:34:42.43558Z","steps":["trace[300959142] 'agreement among raft nodes before linearized reading' (duration: 485.322437ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:34:42.435582Z","caller":"traceutil/trace.go:171","msg":"trace[1258609819] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"354.91818ms","start":"2026-03-06T00:34:42.080642Z","end":"2026-03-06T00:34:42.43556Z","steps":["trace[1258609819] 'process raft request' (duration: 354.374446ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:42.43563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:41.949991Z","time spent":"485.629455ms","remote":"127.0.0.1:53632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":40,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-03-06T00:34:42.435833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:42.080615Z","time spent":"355.132525ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:34:43.187716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.367863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-06T00:34:43.187903Z","caller":"traceutil/trace.go:171","msg":"trace[1437850283] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:804; }","duration":"356.608289ms","start":"2026-03-06T00:34:42.831269Z","end":"2026-03-06T00:34:43.187877Z","steps":["trace[1437850283] 'range keys from in-memory index tree' (duration: 355.925562ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:43.188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:42.831245Z","time spent":"356.736421ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-06T00:34:43.187912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.756025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:34:43.188295Z","caller":"traceutil/trace.go:171","msg":"trace[471347109] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:804; }","duration":"461.156284ms","start":"2026-03-06T00:34:42.727085Z","end":"2026-03-06T00:34:43.188241Z","steps":["trace[471347109] 'count revisions from in-memory index tree' (duration: 460.636982ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:43.189111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:42.727066Z","time spent":"461.997573ms","remote":"127.0.0.1:53590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-03-06T00:34:43.978082Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620624987604421621,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T00:34:44.847282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.252653ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-06T00:34:44.847403Z","caller":"traceutil/trace.go:171","msg":"trace[304263586] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:840; }","duration":"1.36998619s","start":"2026-03-06T00:34:43.477397Z","end":"2026-03-06T00:34:44.847383Z","steps":["trace[304263586] 'read index received' (duration: 797.530152ms)","trace[304263586] 'applied index is now lower than readState.Index' (duration: 572.454518ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:34:44.847817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.324752126s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T00:34:44.847865Z","caller":"traceutil/trace.go:171","msg":"trace[1889271532] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:805; }","duration":"1.324852819s","start":"2026-03-06T00:34:43.523001Z","end":"2026-03-06T00:34:44.847853Z","steps":["trace[1889271532] 'agreement among raft nodes before linearized reading' (duration: 1.324759347s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:44.8479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:43.522981Z","time spent":"1.3249104s","remote":"127.0.0.1:53560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-03-06T00:34:44.847927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.774473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-06T00:34:44.847927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.849816ms","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-03-06T00:34:44.847957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102917557s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:34:44.847987Z","caller":"traceutil/trace.go:171","msg":"trace[997135012] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:805; }","duration":"408.948948ms","start":"2026-03-06T00:34:44.439023Z","end":"2026-03-06T00:34:44.847972Z","steps":["trace[997135012] 'agreement among raft nodes before linearized reading' (duration: 408.752734ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:34:44.84798Z","caller":"traceutil/trace.go:171","msg":"trace[1361948043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:805; }","duration":"953.866745ms","start":"2026-03-06T00:34:43.894098Z","end":"2026-03-06T00:34:44.847965Z","steps":["trace[1361948043] 'agreement among raft nodes before linearized reading' (duration: 953.696381ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:44.84803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:44.439005Z","time spent":"409.014269ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-06T00:34:44.848034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:43.894078Z","time spent":"953.945067ms","remote":"127.0.0.1:53716","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-03-06T00:34:44.848029Z","caller":"traceutil/trace.go:171","msg":"trace[942650468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"1.103057041s","start":"2026-03-06T00:34:43.744954Z","end":"2026-03-06T00:34:44.848011Z","steps":["trace[942650468] 'agreement among raft nodes before linearized reading' (duration: 1.102930308s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:44.848077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:43.744913Z","time spent":"1.103156563s","remote":"127.0.0.1:53432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T00:34:44.848065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.633457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-06T00:34:44.84819Z","caller":"traceutil/trace.go:171","msg":"trace[782422927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:805; }","duration":"901.817972ms","start":"2026-03-06T00:34:43.946342Z","end":"2026-03-06T00:34:44.84816Z","steps":["trace[782422927] 'agreement among raft nodes before linearized reading' (duration: 901.430613ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:44.848244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:43.946322Z","time spent":"901.912664ms","remote":"127.0.0.1:53716","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-03-06T00:34:44.849717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.372320994s","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-03-06T00:34:44.849772Z","caller":"traceutil/trace.go:171","msg":"trace[1704019904] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:805; }","duration":"1.372405056s","start":"2026-03-06T00:34:43.477353Z","end":"2026-03-06T00:34:44.849758Z","steps":["trace[1704019904] 'agreement among raft nodes before linearized reading' (duration: 1.371078416s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:44.849802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:43.477332Z","time spent":"1.372462468s","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-06T00:34:46.599947Z","caller":"traceutil/trace.go:171","msg":"trace[1130299064] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"536.684585ms","start":"2026-03-06T00:34:46.063238Z","end":"2026-03-06T00:34:46.599923Z","steps":["trace[1130299064] 'process raft request' (duration: 536.545711ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:46.60022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:46.063208Z","time spent":"536.805488ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:34:47.05352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.473436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-06T00:34:47.053546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.899421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-06T00:34:47.053569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.282992ms","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-03-06T00:34:47.053593Z","caller":"traceutil/trace.go:171","msg":"trace[261701560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:811; }","duration":"150.587899ms","start":"2026-03-06T00:34:46.902987Z","end":"2026-03-06T00:34:47.053575Z","steps":["trace[261701560] 'range keys from in-memory index tree' (duration: 150.309143ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:34:47.053608Z","caller":"traceutil/trace.go:171","msg":"trace[1584659191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:811; }","duration":"307.003814ms","start":"2026-03-06T00:34:46.746587Z","end":"2026-03-06T00:34:47.053591Z","steps":["trace[1584659191] 'range keys from in-memory index tree' (duration: 306.815179ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:34:47.053617Z","caller":"traceutil/trace.go:171","msg":"trace[1345094382] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:811; }","duration":"159.370793ms","start":"2026-03-06T00:34:46.894233Z","end":"2026-03-06T00:34:47.053604Z","steps":["trace[1345094382] 'range keys from in-memory index tree' (duration: 159.143738ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:47.053644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:46.746568Z","time spent":"307.068555ms","remote":"127.0.0.1:53432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T00:34:47.053781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.457256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-06T00:34:47.05386Z","caller":"traceutil/trace.go:171","msg":"trace[1252139342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:811; }","duration":"150.6052ms","start":"2026-03-06T00:34:46.903237Z","end":"2026-03-06T00:34:47.053842Z","steps":["trace[1252139342] 'range keys from in-memory index tree' (duration: 150.258921ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:47.054269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.911436ms","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-03-06T00:34:47.054364Z","caller":"traceutil/trace.go:171","msg":"trace[1404034363] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:811; }","duration":"151.652934ms","start":"2026-03-06T00:34:46.902676Z","end":"2026-03-06T00:34:47.054329Z","steps":["trace[1404034363] 'range keys from in-memory index tree' (duration: 150.751223ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:34:47.269649Z","caller":"traceutil/trace.go:171","msg":"trace[531410255] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"212.608684ms","start":"2026-03-06T00:34:47.057016Z","end":"2026-03-06T00:34:47.269625Z","steps":["trace[531410255] 'process raft request' (duration: 212.45987ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:34:47.283834Z","caller":"traceutil/trace.go:171","msg":"trace[297524958] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"225.899963ms","start":"2026-03-06T00:34:47.057924Z","end":"2026-03-06T00:34:47.283824Z","steps":["trace[297524958] 'process raft request' (duration: 225.825231ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:34:47.283961Z","caller":"traceutil/trace.go:171","msg":"trace[1327578389] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"225.860002ms","start":"2026-03-06T00:34:47.058094Z","end":"2026-03-06T00:34:47.283954Z","steps":["trace[1327578389] 'process raft request' (duration: 225.707488ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:34:47.28437Z","caller":"traceutil/trace.go:171","msg":"trace[228448415] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"225.986124ms","start":"2026-03-06T00:34:47.058343Z","end":"2026-03-06T00:34:47.284329Z","steps":["trace[228448415] 'process raft request' (duration: 225.575395ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:34:47.451483Z","caller":"traceutil/trace.go:171","msg":"trace[1879147193] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"163.585551ms","start":"2026-03-06T00:34:47.287876Z","end":"2026-03-06T00:34:47.451461Z","steps":["trace[1879147193] 'process raft request' (duration: 147.972337ms)","trace[1879147193] 'compare' (duration: 15.417509ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:34:57.83014Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620624987604421805,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-06T00:34:57.839773Z","caller":"traceutil/trace.go:171","msg":"trace[7210992] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"518.264615ms","start":"2026-03-06T00:34:57.3215Z","end":"2026-03-06T00:34:57.839764Z","steps":["trace[7210992] 'process raft request' (duration: 518.129112ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:57.839866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:57.321488Z","time spent":"518.319437ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T00:34:57.839948Z","caller":"traceutil/trace.go:171","msg":"trace[894160510] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:930; }","duration":"509.918401ms","start":"2026-03-06T00:34:57.329811Z","end":"2026-03-06T00:34:57.839729Z","steps":["trace[894160510] 'read index received' (duration: 509.743247ms)","trace[894160510] 'applied index is now lower than readState.Index' (duration: 174.614µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:34:57.840022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.193547ms","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-03-06T00:34:57.840128Z","caller":"traceutil/trace.go:171","msg":"trace[1914338261] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:892; }","duration":"510.335101ms","start":"2026-03-06T00:34:57.329773Z","end":"2026-03-06T00:34:57.840108Z","steps":["trace[1914338261] 'agreement among raft nodes before linearized reading' (duration: 510.095295ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:57.84014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.684821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-06T00:34:57.840169Z","caller":"traceutil/trace.go:171","msg":"trace[1250875288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:892; }","duration":"508.750503ms","start":"2026-03-06T00:34:57.331411Z","end":"2026-03-06T00:34:57.840161Z","steps":["trace[1250875288] 'agreement among raft nodes before linearized reading' (duration: 508.695762ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:57.840182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:57.331384Z","time spent":"508.795244ms","remote":"127.0.0.1:53716","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-03-06T00:34:57.840174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:57.329757Z","time spent":"510.408802ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-06T00:34:57.840079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.039565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-06T00:34:57.840281Z","caller":"traceutil/trace.go:171","msg":"trace[1428379217] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:892; }","duration":"347.28075ms","start":"2026-03-06T00:34:57.49299Z","end":"2026-03-06T00:34:57.840271Z","steps":["trace[1428379217] 'agreement among raft nodes before linearized reading' (duration: 347.014354ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:57.840312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:57.492969Z","time spent":"347.333702ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-06T00:34:57.840406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.03637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-06T00:34:57.840451Z","caller":"traceutil/trace.go:171","msg":"trace[1996619470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:892; }","duration":"509.091811ms","start":"2026-03-06T00:34:57.331352Z","end":"2026-03-06T00:34:57.840444Z","steps":["trace[1996619470] 'agreement among raft nodes before linearized reading' (duration: 508.63929ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:34:57.840465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:57.331334Z","time spent":"509.127563ms","remote":"127.0.0.1:53716","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-03-06T00:34:58.077915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.774194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T00:34:58.07815Z","caller":"traceutil/trace.go:171","msg":"trace[1289234174] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"233.979201ms","start":"2026-03-06T00:34:57.844144Z","end":"2026-03-06T00:34:58.078123Z","steps":["trace[1289234174] 'process raft request' (duration: 27.552653ms)","trace[1289234174] 'compare' (duration: 205.653931ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:34:58.07866Z","caller":"traceutil/trace.go:171","msg":"trace[949795514] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"233.307147ms","start":"2026-03-06T00:34:57.845309Z","end":"2026-03-06T00:34:58.078616Z","steps":["trace[949795514] 'process raft request' (duration: 232.788564ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:34:58.078685Z","caller":"traceutil/trace.go:171","msg":"trace[1316517879] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"233.033391ms","start":"2026-03-06T00:34:57.845629Z","end":"2026-03-06T00:34:58.078662Z","steps":["trace[1316517879] 'process raft request' (duration: 232.516168ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:34:58.079006Z","caller":"traceutil/trace.go:171","msg":"trace[699739212] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"234.803181ms","start":"2026-03-06T00:34:57.844187Z","end":"2026-03-06T00:34:58.07899Z","steps":["trace[699739212] 'process raft request' (duration: 233.837788ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:00.583849Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620624987604421823,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-06T00:35:00.972208Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.123147788s","expected-duration":"1s"} {"level":"info","ts":"2026-03-06T00:35:00.972511Z","caller":"traceutil/trace.go:171","msg":"trace[1218386504] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:937; }","duration":"889.534077ms","start":"2026-03-06T00:35:00.082957Z","end":"2026-03-06T00:35:00.972491Z","steps":["trace[1218386504] 'read index received' (duration: 889.355013ms)","trace[1218386504] 'applied index is now lower than readState.Index' (duration: 178.234µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:35:00.97281Z","caller":"traceutil/trace.go:171","msg":"trace[1628421298] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"1.123927667s","start":"2026-03-06T00:34:59.848873Z","end":"2026-03-06T00:35:00.9728Z","steps":["trace[1628421298] 'process raft request' (duration: 1.123505187s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:00.972888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:34:59.848843Z","time spent":"1.12398838s","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:35:00.973097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.13682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-06T00:35:00.973122Z","caller":"traceutil/trace.go:171","msg":"trace[116903235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:898; }","duration":"890.183221ms","start":"2026-03-06T00:35:00.08293Z","end":"2026-03-06T00:35:00.973113Z","steps":["trace[116903235] 'agreement among raft nodes before linearized reading' (duration: 890.096069ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:00.973145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:00.082919Z","time spent":"890.219573ms","remote":"127.0.0.1:53716","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-03-06T00:35:00.973266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.307804ms","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-03-06T00:35:00.973287Z","caller":"traceutil/trace.go:171","msg":"trace[1492773240] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:898; }","duration":"890.365786ms","start":"2026-03-06T00:35:00.082914Z","end":"2026-03-06T00:35:00.97328Z","steps":["trace[1492773240] 'agreement among raft nodes before linearized reading' (duration: 890.305974ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:00.973305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:00.082896Z","time spent":"890.404236ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-06T00:35:00.973428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.306074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-06T00:35:00.973451Z","caller":"traceutil/trace.go:171","msg":"trace[73828425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:898; }","duration":"890.360876ms","start":"2026-03-06T00:35:00.083084Z","end":"2026-03-06T00:35:00.973445Z","steps":["trace[73828425] 'agreement among raft nodes before linearized reading' (duration: 890.300704ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:00.97347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:00.083067Z","time spent":"890.397826ms","remote":"127.0.0.1:53716","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-03-06T00:35:00.973639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.549442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:35:00.97367Z","caller":"traceutil/trace.go:171","msg":"trace[600539934] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:898; }","duration":"227.609323ms","start":"2026-03-06T00:35:00.746054Z","end":"2026-03-06T00:35:00.973663Z","steps":["trace[600539934] 'agreement among raft nodes before linearized reading' (duration: 227.563842ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:00.97379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.628674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:35:00.973819Z","caller":"traceutil/trace.go:171","msg":"trace[40709759] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:898; }","duration":"706.683775ms","start":"2026-03-06T00:35:00.267129Z","end":"2026-03-06T00:35:00.973813Z","steps":["trace[40709759] 'agreement among raft nodes before linearized reading' (duration: 706.641824ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:00.973839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:00.267112Z","time spent":"706.721646ms","remote":"127.0.0.1:53576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-03-06T00:35:00.974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.243413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-06T00:35:00.974023Z","caller":"traceutil/trace.go:171","msg":"trace[1195989011] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:898; }","duration":"890.282544ms","start":"2026-03-06T00:35:00.083734Z","end":"2026-03-06T00:35:00.974016Z","steps":["trace[1195989011] 'agreement among raft nodes before linearized reading' (duration: 890.219382ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:00.974043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:00.083725Z","time spent":"890.312474ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-06T00:35:16.619433Z","caller":"traceutil/trace.go:171","msg":"trace[1240177917] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"237.281429ms","start":"2026-03-06T00:35:16.382126Z","end":"2026-03-06T00:35:16.619407Z","steps":["trace[1240177917] 'process raft request' (duration: 237.123825ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:35:16.747267Z","caller":"traceutil/trace.go:171","msg":"trace[1040913079] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"118.358949ms","start":"2026-03-06T00:35:16.62889Z","end":"2026-03-06T00:35:16.747249Z","steps":["trace[1040913079] 'process raft request' (duration: 118.212995ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:35:16.747467Z","caller":"traceutil/trace.go:171","msg":"trace[1686818747] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"116.346901ms","start":"2026-03-06T00:35:16.631092Z","end":"2026-03-06T00:35:16.747439Z","steps":["trace[1686818747] 'process raft request' (duration: 116.125816ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:18.315518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.522116ms","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-03-06T00:35:18.315612Z","caller":"traceutil/trace.go:171","msg":"trace[910134893] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:967; }","duration":"580.676901ms","start":"2026-03-06T00:35:17.734916Z","end":"2026-03-06T00:35:18.315593Z","steps":["trace[910134893] 'count revisions from in-memory index tree' (duration: 580.444764ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:18.315657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:17.734894Z","time spent":"580.751762ms","remote":"127.0.0.1:53746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-06T00:35:18.31564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.157747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-06T00:35:18.315757Z","caller":"traceutil/trace.go:171","msg":"trace[424454004] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:967; }","duration":"304.345792ms","start":"2026-03-06T00:35:18.011383Z","end":"2026-03-06T00:35:18.315729Z","steps":["trace[424454004] 'range keys from in-memory index tree' (duration: 303.960982ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:18.315843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:18.011368Z","time spent":"304.463524ms","remote":"127.0.0.1:53472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.65\" "} {"level":"warn","ts":"2026-03-06T00:35:19.000347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.73203ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T00:35:19.000417Z","caller":"traceutil/trace.go:171","msg":"trace[24684698] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1011; }","duration":"254.416798ms","start":"2026-03-06T00:35:18.74599Z","end":"2026-03-06T00:35:19.000406Z","steps":["trace[24684698] 'read index received' (duration: 152.470793ms)","trace[24684698] 'applied index is now lower than readState.Index' (duration: 101.945085ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:35:19.000463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.480939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:35:19.000486Z","caller":"traceutil/trace.go:171","msg":"trace[1556133884] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:968; }","duration":"254.536251ms","start":"2026-03-06T00:35:18.745946Z","end":"2026-03-06T00:35:19.000482Z","steps":["trace[1556133884] 'agreement among raft nodes before linearized reading' (duration: 254.48747ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:35:19.000612Z","caller":"traceutil/trace.go:171","msg":"trace[1465527291] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"462.725672ms","start":"2026-03-06T00:35:18.53788Z","end":"2026-03-06T00:35:19.000606Z","steps":["trace[1465527291] 'process raft request' (duration: 360.671654ms)","trace[1465527291] 'compare' (duration: 101.648608ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:35:19.000647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:18.537841Z","time spent":"462.790453ms","remote":"127.0.0.1:53472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:35:19.234408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.291523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-03-06T00:35:19.234472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.890287ms","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-03-06T00:35:19.234506Z","caller":"traceutil/trace.go:171","msg":"trace[657766531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:968; }","duration":"108.442777ms","start":"2026-03-06T00:35:19.126039Z","end":"2026-03-06T00:35:19.234481Z","steps":["trace[657766531] 'range keys from in-memory index tree' (duration: 108.115578ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:35:19.234562Z","caller":"traceutil/trace.go:171","msg":"trace[1794342987] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:968; }","duration":"109.030361ms","start":"2026-03-06T00:35:19.125512Z","end":"2026-03-06T00:35:19.234542Z","steps":["trace[1794342987] 'range keys from in-memory index tree' (duration: 108.711922ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:19.234569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.867966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-03-06T00:35:19.23467Z","caller":"traceutil/trace.go:171","msg":"trace[1893145353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:968; }","duration":"108.99684ms","start":"2026-03-06T00:35:19.125648Z","end":"2026-03-06T00:35:19.234645Z","steps":["trace[1893145353] 'range keys from in-memory index tree' (duration: 108.717383ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:19.234959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.792428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-06T00:35:19.235018Z","caller":"traceutil/trace.go:171","msg":"trace[144002972] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:968; }","duration":"133.88824ms","start":"2026-03-06T00:35:19.101116Z","end":"2026-03-06T00:35:19.235005Z","steps":["trace[144002972] 'range keys from in-memory index tree' (duration: 133.618023ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:19.235115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.189678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-06T00:35:19.235159Z","caller":"traceutil/trace.go:171","msg":"trace[1117091535] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:968; }","duration":"122.267699ms","start":"2026-03-06T00:35:19.112882Z","end":"2026-03-06T00:35:19.23515Z","steps":["trace[1117091535] 'range keys from in-memory index tree' (duration: 122.054684ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:35:19.416394Z","caller":"traceutil/trace.go:171","msg":"trace[1321259589] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"175.321634ms","start":"2026-03-06T00:35:19.241044Z","end":"2026-03-06T00:35:19.416365Z","steps":["trace[1321259589] 'process raft request' (duration: 129.862125ms)","trace[1321259589] 'compare' (duration: 45.269075ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:35:19.416667Z","caller":"traceutil/trace.go:171","msg":"trace[736953809] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"175.330906ms","start":"2026-03-06T00:35:19.24132Z","end":"2026-03-06T00:35:19.416651Z","steps":["trace[736953809] 'process raft request' (duration: 175.284085ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:35:19.416793Z","caller":"traceutil/trace.go:171","msg":"trace[969775832] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"175.707325ms","start":"2026-03-06T00:35:19.241077Z","end":"2026-03-06T00:35:19.416784Z","steps":["trace[969775832] 'process raft request' (duration: 175.241533ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:35:19.416859Z","caller":"traceutil/trace.go:171","msg":"trace[1867371563] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"175.646723ms","start":"2026-03-06T00:35:19.241179Z","end":"2026-03-06T00:35:19.416825Z","steps":["trace[1867371563] 'process raft request' (duration: 175.349996ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:35:20.443474Z","caller":"traceutil/trace.go:171","msg":"trace[53509607] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1018; }","duration":"463.728584ms","start":"2026-03-06T00:35:19.979721Z","end":"2026-03-06T00:35:20.443449Z","steps":["trace[53509607] 'read index received' (duration: 463.54422ms)","trace[53509607] 'applied index is now lower than readState.Index' (duration: 183.434µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:35:20.443566Z","caller":"traceutil/trace.go:171","msg":"trace[614561426] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"546.896722ms","start":"2026-03-06T00:35:19.896614Z","end":"2026-03-06T00:35:20.443511Z","steps":["trace[614561426] 'process raft request' (duration: 546.715158ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:20.443631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.888017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:35:20.443812Z","caller":"traceutil/trace.go:171","msg":"trace[1868392937] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:975; }","duration":"464.118303ms","start":"2026-03-06T00:35:19.979678Z","end":"2026-03-06T00:35:20.443797Z","steps":["trace[1868392937] 'agreement among raft nodes before linearized reading' (duration: 463.865307ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:20.44381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:19.89659Z","time spent":"547.118727ms","remote":"127.0.0.1:53514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T00:35:20.443856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:19.979662Z","time spent":"464.183995ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-06T00:35:20.867445Z","caller":"traceutil/trace.go:171","msg":"trace[1576431051] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"418.299896ms","start":"2026-03-06T00:35:20.449115Z","end":"2026-03-06T00:35:20.867415Z","steps":["trace[1576431051] 'process raft request' (duration: 331.577935ms)","trace[1576431051] 'compare' (duration: 86.588568ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:35:20.867583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:20.449085Z","time spent":"418.434339ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T00:35:21.810421Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.001879583s","expected-duration":"1s"} {"level":"info","ts":"2026-03-06T00:35:21.810923Z","caller":"traceutil/trace.go:171","msg":"trace[1790290642] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"1.306719735s","start":"2026-03-06T00:35:20.50417Z","end":"2026-03-06T00:35:21.81089Z","steps":["trace[1790290642] 'process raft request' (duration: 1.30650533s)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:35:21.810903Z","caller":"traceutil/trace.go:171","msg":"trace[1532781422] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1019; }","duration":"1.065370312s","start":"2026-03-06T00:35:20.7455Z","end":"2026-03-06T00:35:21.81087Z","steps":["trace[1532781422] 'read index received' (duration: 35.180709ms)","trace[1532781422] 'applied index is now lower than readState.Index' (duration: 1.030188163s)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:35:21.811057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:20.504147Z","time spent":"1.306842148s","remote":"127.0.0.1:53514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T00:35:21.811561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065576476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:35:21.811638Z","caller":"traceutil/trace.go:171","msg":"trace[367694888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:977; }","duration":"1.06613963s","start":"2026-03-06T00:35:20.745466Z","end":"2026-03-06T00:35:21.811606Z","steps":["trace[367694888] 'agreement among raft nodes before linearized reading' (duration: 1.065509595s)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:21.811702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:20.745432Z","time spent":"1.066254874s","remote":"127.0.0.1:53448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-06T00:35:21.859444Z","caller":"traceutil/trace.go:171","msg":"trace[1948952520] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"986.945345ms","start":"2026-03-06T00:35:20.872418Z","end":"2026-03-06T00:35:21.859363Z","steps":["trace[1948952520] 'process raft request' (duration: 986.702389ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:21.859611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:20.872409Z","time spent":"987.12014ms","remote":"127.0.0.1:53514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-06T00:35:21.859634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.530943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-03-06T00:35:21.85963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.672841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-06T00:35:21.85964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.554595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-06T00:35:21.85971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.356676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-03-06T00:35:21.859741Z","caller":"traceutil/trace.go:171","msg":"trace[82975339] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:978; }","duration":"438.833715ms","start":"2026-03-06T00:35:21.420886Z","end":"2026-03-06T00:35:21.859719Z","steps":["trace[82975339] 'agreement among raft nodes before linearized reading' (duration: 438.488117ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:35:21.859753Z","caller":"traceutil/trace.go:171","msg":"trace[1321379596] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:978; }","duration":"437.687469ms","start":"2026-03-06T00:35:21.422044Z","end":"2026-03-06T00:35:21.859731Z","steps":["trace[1321379596] 'agreement among raft nodes before linearized reading' (duration: 437.475484ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:21.859755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.75398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-03-06T00:35:21.859793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:21.420863Z","time spent":"438.922527ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-06T00:35:21.859802Z","caller":"traceutil/trace.go:171","msg":"trace[1239533575] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:978; }","duration":"987.487468ms","start":"2026-03-06T00:35:20.872286Z","end":"2026-03-06T00:35:21.859774Z","steps":["trace[1239533575] 'agreement among raft nodes before linearized reading' (duration: 987.174221ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:21.85981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:21.422033Z","time spent":"437.76401ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-06T00:35:21.859826Z","caller":"traceutil/trace.go:171","msg":"trace[1705288566] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:978; }","duration":"437.842482ms","start":"2026-03-06T00:35:21.421966Z","end":"2026-03-06T00:35:21.859808Z","steps":["trace[1705288566] 'agreement among raft nodes before linearized reading' (duration: 437.577846ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:21.859851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:20.872272Z","time spent":"987.571631ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-06T00:35:21.859737Z","caller":"traceutil/trace.go:171","msg":"trace[1798709056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:978; }","duration":"579.680406ms","start":"2026-03-06T00:35:21.280028Z","end":"2026-03-06T00:35:21.859708Z","steps":["trace[1798709056] 'agreement among raft nodes before linearized reading' (duration: 579.4008ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:21.859873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:21.421954Z","time spent":"437.907474ms","remote":"127.0.0.1:53716","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-03-06T00:35:21.859916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:21.280014Z","time spent":"579.885861ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-06T00:35:21.860125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.273455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-06T00:35:21.8602Z","caller":"traceutil/trace.go:171","msg":"trace[790842169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:978; }","duration":"439.393047ms","start":"2026-03-06T00:35:21.420788Z","end":"2026-03-06T00:35:21.860181Z","steps":["trace[790842169] 'agreement among raft nodes before linearized reading' (duration: 438.578629ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:21.860308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:21.420755Z","time spent":"439.539151ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-06T00:35:21.995583Z","caller":"traceutil/trace.go:171","msg":"trace[710799937] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1022; }","duration":"136.208943ms","start":"2026-03-06T00:35:21.859341Z","end":"2026-03-06T00:35:21.99555Z","steps":["trace[710799937] 'read index received' (duration: 46.710238ms)","trace[710799937] 'applied index is now lower than readState.Index' (duration: 89.497005ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:35:21.995738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.977856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:35:21.995819Z","caller":"traceutil/trace.go:171","msg":"trace[1740866507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:978; }","duration":"175.068248ms","start":"2026-03-06T00:35:21.820728Z","end":"2026-03-06T00:35:21.995796Z","steps":["trace[1740866507] 'agreement among raft nodes before linearized reading' (duration: 174.918015ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:35:22.0335Z","caller":"traceutil/trace.go:171","msg":"trace[1660406490] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"169.247594ms","start":"2026-03-06T00:35:21.864231Z","end":"2026-03-06T00:35:22.033479Z","steps":["trace[1660406490] 'process raft request' (duration: 169.023819ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:35:22.033692Z","caller":"traceutil/trace.go:171","msg":"trace[222531333] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"165.730001ms","start":"2026-03-06T00:35:21.867936Z","end":"2026-03-06T00:35:22.033666Z","steps":["trace[222531333] 'process raft request' (duration: 165.489796ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:35:22.033836Z","caller":"traceutil/trace.go:171","msg":"trace[318872871] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"165.872704ms","start":"2026-03-06T00:35:21.867949Z","end":"2026-03-06T00:35:22.033822Z","steps":["trace[318872871] 'process raft request' (duration: 165.629798ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:35:22.033905Z","caller":"traceutil/trace.go:171","msg":"trace[1879964091] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"165.498365ms","start":"2026-03-06T00:35:21.86839Z","end":"2026-03-06T00:35:22.033889Z","steps":["trace[1879964091] 'process raft request' (duration: 165.461424ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:35:22.033982Z","caller":"traceutil/trace.go:171","msg":"trace[81181803] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"165.889135ms","start":"2026-03-06T00:35:21.868075Z","end":"2026-03-06T00:35:22.033964Z","steps":["trace[81181803] 'process raft request' (duration: 165.68538ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:35:22.034011Z","caller":"traceutil/trace.go:171","msg":"trace[1462656290] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"165.891425ms","start":"2026-03-06T00:35:21.868109Z","end":"2026-03-06T00:35:22.034001Z","steps":["trace[1462656290] 'process raft request' (duration: 165.703241ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:23.319159Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620624987604422024,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-06T00:35:23.355692Z","caller":"traceutil/trace.go:171","msg":"trace[510541599] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1030; }","duration":"536.602293ms","start":"2026-03-06T00:35:22.819066Z","end":"2026-03-06T00:35:23.355669Z","steps":["trace[510541599] 'read index received' (duration: 536.398249ms)","trace[510541599] 'applied index is now lower than readState.Index' (duration: 203.284µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:35:23.355757Z","caller":"traceutil/trace.go:171","msg":"trace[1440800580] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"628.666669ms","start":"2026-03-06T00:35:22.727056Z","end":"2026-03-06T00:35:23.355723Z","steps":["trace[1440800580] 'process raft request' (duration: 628.463984ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:23.355816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.727826ms","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-03-06T00:35:23.355867Z","caller":"traceutil/trace.go:171","msg":"trace[741586908] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:986; }","duration":"536.821218ms","start":"2026-03-06T00:35:22.819031Z","end":"2026-03-06T00:35:23.355852Z","steps":["trace[741586908] 'agreement among raft nodes before linearized reading' (duration: 536.726885ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:23.355911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:22.819012Z","time spent":"536.88843ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-03-06T00:35:23.355937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:22.727029Z","time spent":"628.807311ms","remote":"127.0.0.1:53614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5957,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:35:23.906807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.85835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-06T00:35:23.906962Z","caller":"traceutil/trace.go:171","msg":"trace[993527204] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"529.898487ms","start":"2026-03-06T00:35:23.377022Z","end":"2026-03-06T00:35:23.90692Z","steps":["trace[993527204] 'process raft request' (duration: 349.728319ms)","trace[993527204] 'compare' (duration: 179.695347ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:35:23.907091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:23.376993Z","time spent":"530.022599ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T00:35:23.959198Z","caller":"traceutil/trace.go:171","msg":"trace[2119343657] linearizableReadLoop","detail":"{readStateIndex:1033; appliedIndex:1031; }","duration":"533.295155ms","start":"2026-03-06T00:35:23.425874Z","end":"2026-03-06T00:35:23.959169Z","steps":["trace[2119343657] 'read index received' (duration: 300.89937ms)","trace[2119343657] 'applied index is now lower than readState.Index' (duration: 232.394365ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:35:23.959372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.467469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-06T00:35:23.959416Z","caller":"traceutil/trace.go:171","msg":"trace[1797725033] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:987; }","duration":"533.569902ms","start":"2026-03-06T00:35:23.425834Z","end":"2026-03-06T00:35:23.959404Z","steps":["trace[1797725033] 'agreement among raft nodes before linearized reading' (duration: 533.465359ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:23.959449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:23.425806Z","time spent":"533.636243ms","remote":"127.0.0.1:34534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-03-06T00:35:23.959383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.921728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:35:23.959524Z","caller":"traceutil/trace.go:171","msg":"trace[744885261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:987; }","duration":"215.137973ms","start":"2026-03-06T00:35:23.744374Z","end":"2026-03-06T00:35:23.959512Z","steps":["trace[744885261] 'agreement among raft nodes before linearized reading' (duration: 214.942469ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:35:24.190385Z","caller":"traceutil/trace.go:171","msg":"trace[572081438] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"147.055016ms","start":"2026-03-06T00:35:24.043312Z","end":"2026-03-06T00:35:24.190367Z","steps":["trace[572081438] 'process raft request' (duration: 146.926443ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:35:24.315231Z","caller":"traceutil/trace.go:171","msg":"trace[1500432944] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"271.864794ms","start":"2026-03-06T00:35:24.043334Z","end":"2026-03-06T00:35:24.315199Z","steps":["trace[1500432944] 'process raft request' (duration: 271.581968ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:35:24.315244Z","caller":"traceutil/trace.go:171","msg":"trace[1416370227] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"271.780533ms","start":"2026-03-06T00:35:24.043431Z","end":"2026-03-06T00:35:24.315212Z","steps":["trace[1416370227] 'process raft request' (duration: 271.6732ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:35:24.315699Z","caller":"traceutil/trace.go:171","msg":"trace[175282595] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"272.185502ms","start":"2026-03-06T00:35:24.043502Z","end":"2026-03-06T00:35:24.315688Z","steps":["trace[175282595] 'process raft request' (duration: 271.66023ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:35:24.315606Z","caller":"traceutil/trace.go:171","msg":"trace[1952275775] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"271.25696ms","start":"2026-03-06T00:35:24.044323Z","end":"2026-03-06T00:35:24.31558Z","steps":["trace[1952275775] 'process raft request' (duration: 271.136907ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:31.136751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.912539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:35:31.136832Z","caller":"traceutil/trace.go:171","msg":"trace[336851946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1020; }","duration":"361.036192ms","start":"2026-03-06T00:35:30.775779Z","end":"2026-03-06T00:35:31.136815Z","steps":["trace[336851946] 'range keys from in-memory index tree' (duration: 360.836917ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:31.136871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:30.775759Z","time spent":"361.102873ms","remote":"127.0.0.1:53432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-06T00:35:31.137297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.570893ms","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-03-06T00:35:31.137319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.210158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-06T00:35:31.137305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.561246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-06T00:35:31.137362Z","caller":"traceutil/trace.go:171","msg":"trace[553806390] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1020; }","duration":"770.654626ms","start":"2026-03-06T00:35:30.366694Z","end":"2026-03-06T00:35:31.137349Z","steps":["trace[553806390] 'range keys from in-memory index tree' (duration: 770.458801ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:35:31.137361Z","caller":"traceutil/trace.go:171","msg":"trace[666171050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1020; }","duration":"774.251749ms","start":"2026-03-06T00:35:30.3631Z","end":"2026-03-06T00:35:31.137351Z","steps":["trace[666171050] 'range keys from in-memory index tree' (duration: 774.051044ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:31.13739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:30.36669Z","time spent":"770.693297ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-06T00:35:31.137391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:30.363061Z","time spent":"774.323501ms","remote":"127.0.0.1:53716","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-03-06T00:35:31.137391Z","caller":"traceutil/trace.go:171","msg":"trace[1957306640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1020; }","duration":"771.67375ms","start":"2026-03-06T00:35:30.365693Z","end":"2026-03-06T00:35:31.137367Z","steps":["trace[1957306640] 'range keys from in-memory index tree' (duration: 771.418913ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:31.137432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.789646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-03-06T00:35:31.137484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:30.365688Z","time spent":"771.753902ms","remote":"127.0.0.1:53716","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-03-06T00:35:31.137528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.857104ms","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-03-06T00:35:31.137526Z","caller":"traceutil/trace.go:171","msg":"trace[421733995] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1020; }","duration":"442.931961ms","start":"2026-03-06T00:35:30.694564Z","end":"2026-03-06T00:35:31.137496Z","steps":["trace[421733995] 'range keys from in-memory index tree' (duration: 442.594902ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:35:31.137559Z","caller":"traceutil/trace.go:171","msg":"trace[2114108522] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1020; }","duration":"771.911556ms","start":"2026-03-06T00:35:30.36564Z","end":"2026-03-06T00:35:31.137551Z","steps":["trace[2114108522] 'range keys from in-memory index tree' (duration: 771.772842ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:31.137592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:30.365623Z","time spent":"771.957136ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-06T00:35:31.137596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:30.694536Z","time spent":"443.025543ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-06T00:35:31.517179Z","caller":"traceutil/trace.go:171","msg":"trace[1151040919] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"375.652613ms","start":"2026-03-06T00:35:31.141494Z","end":"2026-03-06T00:35:31.517147Z","steps":["trace[1151040919] 'process raft request' (duration: 375.457748ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:31.517551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:31.141477Z","time spent":"375.799216ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T00:35:31.665134Z","caller":"traceutil/trace.go:171","msg":"trace[696206566] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"523.273792ms","start":"2026-03-06T00:35:31.141788Z","end":"2026-03-06T00:35:31.665062Z","steps":["trace[696206566] 'process raft request' (duration: 522.623527ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:35:31.665331Z","caller":"traceutil/trace.go:171","msg":"trace[224647358] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"522.76559ms","start":"2026-03-06T00:35:31.142511Z","end":"2026-03-06T00:35:31.665277Z","steps":["trace[224647358] 'process raft request' (duration: 522.362141ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:35:31.665519Z","caller":"traceutil/trace.go:171","msg":"trace[527939386] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"522.32414ms","start":"2026-03-06T00:35:31.143185Z","end":"2026-03-06T00:35:31.665509Z","steps":["trace[527939386] 'process raft request' (duration: 522.228727ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:31.665531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:31.142501Z","time spent":"522.912004ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:35:31.665579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:31.143177Z","time spent":"522.37344ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:35:31.665703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:31.141768Z","time spent":"523.64639ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T00:35:31.666344Z","caller":"traceutil/trace.go:171","msg":"trace[2134327506] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"522.934024ms","start":"2026-03-06T00:35:31.142687Z","end":"2026-03-06T00:35:31.665621Z","steps":["trace[2134327506] 'process raft request' (duration: 522.509314ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:35:31.666501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:35:31.14267Z","time spent":"523.747023ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"}