{"level":"warn","ts":"2026-03-14T07:00:52.193747Z","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-14T07:00:52.193864Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.140: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.140:2380","--initial-cluster=instance=https://199.204.45.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.140: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-14T07:00:52.19394Z","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-14T07:00:52.193953Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-03-14T07:00:52.193969Z","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-14T07:00:52.194728Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"]} {"level":"info","ts":"2026-03-14T07:00:52.194844Z","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.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.140: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-14T07:00:52.201362Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.872405ms"} {"level":"info","ts":"2026-03-14T07:00:52.208975Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"35b2766da46a76bb","cluster-id":"f5fd3aeb8d9f53dc"} {"level":"info","ts":"2026-03-14T07:00:52.209027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=()"} {"level":"info","ts":"2026-03-14T07:00:52.209042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 0"} {"level":"info","ts":"2026-03-14T07:00:52.209079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 35b2766da46a76bb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-14T07:00:52.209089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 1"} {"level":"info","ts":"2026-03-14T07:00:52.209176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"warn","ts":"2026-03-14T07:00:53.518853Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-14T07:00:54.029901Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-14T07:00:54.033838Z","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-14T07:00:54.037037Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"35b2766da46a76bb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-14T07:00:54.037276Z","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-14T07:00:54.037328Z","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-14T07:00:54.037338Z","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-14T07:00:54.03732Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"35b2766da46a76bb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-14T07:00:54.040897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"info","ts":"2026-03-14T07:00:54.04104Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","added-peer-id":"35b2766da46a76bb","added-peer-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-03-14T07:00:54.041693Z","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-14T07:00:54.041859Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-03-14T07:00:54.041877Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-03-14T07:00:54.041897Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"35b2766da46a76bb","initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-14T07:00:54.041943Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-14T07:00:54.910398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb is starting a new election at term 1"} {"level":"info","ts":"2026-03-14T07:00:54.91046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became pre-candidate at term 1"} {"level":"info","ts":"2026-03-14T07:00:54.910496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgPreVoteResp from 35b2766da46a76bb at term 1"} {"level":"info","ts":"2026-03-14T07:00:54.910515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became candidate at term 2"} {"level":"info","ts":"2026-03-14T07:00:54.910527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgVoteResp from 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-03-14T07:00:54.910544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became leader at term 2"} {"level":"info","ts":"2026-03-14T07:00:54.910559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 35b2766da46a76bb elected leader 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-03-14T07:00:54.911622Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"35b2766da46a76bb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.140:2379]}","request-path":"/0/members/35b2766da46a76bb/attributes","cluster-id":"f5fd3aeb8d9f53dc","publish-timeout":"7s"} {"level":"info","ts":"2026-03-14T07:00:54.911632Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-14T07:00:54.911678Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-14T07:00:54.911898Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-14T07:00:54.911958Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-14T07:00:54.912191Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T07:00:54.913454Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T07:00:54.91376Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T07:00:54.913974Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T07:00:54.915282Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.140:2379"} {"level":"info","ts":"2026-03-14T07:00:54.915809Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-14T07:01:20.603048Z","caller":"traceutil/trace.go:171","msg":"trace[1582723543] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:489; }","duration":"192.418963ms","start":"2026-03-14T07:01:20.410599Z","end":"2026-03-14T07:01:20.603018Z","steps":["trace[1582723543] 'read index received' (duration: 192.196398ms)","trace[1582723543] 'applied index is now lower than readState.Index' (duration: 220.695µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:01:20.603171Z","caller":"traceutil/trace.go:171","msg":"trace[1620655544] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"206.807722ms","start":"2026-03-14T07:01:20.396316Z","end":"2026-03-14T07:01:20.603123Z","steps":["trace[1620655544] 'process raft request' (duration: 206.494454ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:20.603334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.176433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:01:20.603453Z","caller":"traceutil/trace.go:171","msg":"trace[1618895592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:474; }","duration":"156.336867ms","start":"2026-03-14T07:01:20.447091Z","end":"2026-03-14T07:01:20.603428Z","steps":["trace[1618895592] 'agreement among raft nodes before linearized reading' (duration: 156.159293ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:20.603383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.72782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-14T07:01:20.603809Z","caller":"traceutil/trace.go:171","msg":"trace[987254526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:474; }","duration":"193.250982ms","start":"2026-03-14T07:01:20.410541Z","end":"2026-03-14T07:01:20.603792Z","steps":["trace[987254526] 'agreement among raft nodes before linearized reading' (duration: 192.685989ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:24.606891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.493852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:01:24.607169Z","caller":"traceutil/trace.go:171","msg":"trace[1760613600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"160.754618ms","start":"2026-03-14T07:01:24.446297Z","end":"2026-03-14T07:01:24.607052Z","steps":["trace[1760613600] 'range keys from in-memory index tree' (duration: 160.342839ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:29.489107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.174323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:01:29.48936Z","caller":"traceutil/trace.go:171","msg":"trace[307685354] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"743.613905ms","start":"2026-03-14T07:01:28.745707Z","end":"2026-03-14T07:01:29.489321Z","steps":["trace[307685354] 'process raft request' (duration: 426.51497ms)","trace[307685354] 'compare' (duration: 316.01062ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:01:29.489574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:28.745683Z","time spent":"743.771548ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:01:32.496662Z","caller":"traceutil/trace.go:171","msg":"trace[1286398345] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"375.495691ms","start":"2026-03-14T07:01:32.121146Z","end":"2026-03-14T07:01:32.496642Z","steps":["trace[1286398345] 'process raft request' (duration: 375.373339ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:32.496846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:32.12113Z","time spent":"375.641744ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:01:33.255469Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555604450886645143,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:01:33.755867Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555604450886645143,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:01:34.067049Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.477394724s","expected-duration":"1s"} {"level":"info","ts":"2026-03-14T07:01:34.067644Z","caller":"traceutil/trace.go:171","msg":"trace[240140706] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.478114371s","start":"2026-03-14T07:01:32.589506Z","end":"2026-03-14T07:01:34.067621Z","steps":["trace[240140706] 'process raft request' (duration: 1.477946757s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:34.067765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:32.589477Z","time spent":"1.478215142s","remote":"127.0.0.1:56200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:01:34.132575Z","caller":"traceutil/trace.go:171","msg":"trace[168806933] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:556; }","duration":"1.377987219s","start":"2026-03-14T07:01:32.754553Z","end":"2026-03-14T07:01:34.13254Z","steps":["trace[168806933] 'read index received' (duration: 1.312853909s)","trace[168806933] 'applied index is now lower than readState.Index' (duration: 65.13084ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:01:34.132883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.377404875s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-14T07:01:34.132941Z","caller":"traceutil/trace.go:171","msg":"trace[424641225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:539; }","duration":"1.377517168s","start":"2026-03-14T07:01:32.755411Z","end":"2026-03-14T07:01:34.132929Z","steps":["trace[424641225] 'agreement among raft nodes before linearized reading' (duration: 1.377352864s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:34.132961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.457787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-14T07:01:34.132992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:32.755397Z","time spent":"1.377586399s","remote":"127.0.0.1:56294","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-14T07:01:34.133019Z","caller":"traceutil/trace.go:171","msg":"trace[1447708635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:539; }","duration":"627.556879ms","start":"2026-03-14T07:01:33.505438Z","end":"2026-03-14T07:01:34.132994Z","steps":["trace[1447708635] 'agreement among raft nodes before linearized reading' (duration: 627.491808ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:34.133077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:33.505408Z","time spent":"627.659732ms","remote":"127.0.0.1:56294","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-14T07:01:34.132858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.378273436s","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-14T07:01:34.133273Z","caller":"traceutil/trace.go:171","msg":"trace[127296210] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:539; }","duration":"1.378781698s","start":"2026-03-14T07:01:32.754483Z","end":"2026-03-14T07:01:34.133264Z","steps":["trace[127296210] 'agreement among raft nodes before linearized reading' (duration: 1.378183284s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:34.1333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:32.754455Z","time spent":"1.378839289s","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-14T07:01:34.13353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.676049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:01:34.133564Z","caller":"traceutil/trace.go:171","msg":"trace[1164239903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"685.70963ms","start":"2026-03-14T07:01:33.447846Z","end":"2026-03-14T07:01:34.133555Z","steps":["trace[1164239903] 'agreement among raft nodes before linearized reading' (duration: 685.658209ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:34.133589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:33.44776Z","time spent":"685.822942ms","remote":"127.0.0.1:46568","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-14T07:01:36.521801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.350934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:01:36.521919Z","caller":"traceutil/trace.go:171","msg":"trace[1386370028] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"661.271121ms","start":"2026-03-14T07:01:35.860629Z","end":"2026-03-14T07:01:36.5219Z","steps":["trace[1386370028] 'process raft request' (duration: 144.714212ms)","trace[1386370028] 'compare' (duration: 516.15718ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:01:36.521978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:35.8606Z","time spent":"661.355022ms","remote":"127.0.0.1:56048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-14T07:01:36.530973Z","caller":"traceutil/trace.go:171","msg":"trace[1192517692] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"660.8744ms","start":"2026-03-14T07:01:35.870079Z","end":"2026-03-14T07:01:36.530953Z","steps":["trace[1192517692] 'process raft request' (duration: 660.831429ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:36.531084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:35.87007Z","time spent":"660.962952ms","remote":"127.0.0.1:56082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-14T07:01:36.531132Z","caller":"traceutil/trace.go:171","msg":"trace[4815409] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"670.412999ms","start":"2026-03-14T07:01:35.860698Z","end":"2026-03-14T07:01:36.531111Z","steps":["trace[4815409] 'process raft request' (duration: 670.110532ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:36.53138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:35.860681Z","time spent":"670.516603ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:01:36.543647Z","caller":"traceutil/trace.go:171","msg":"trace[1809529410] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:577; }","duration":"390.281979ms","start":"2026-03-14T07:01:36.153358Z","end":"2026-03-14T07:01:36.54364Z","steps":["trace[1809529410] 'read index received' (duration: 377.561537ms)","trace[1809529410] 'applied index is now lower than readState.Index' (duration: 12.720082ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:01:36.543635Z","caller":"traceutil/trace.go:171","msg":"trace[1246718504] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"487.071054ms","start":"2026-03-14T07:01:36.056537Z","end":"2026-03-14T07:01:36.543608Z","steps":["trace[1246718504] 'process raft request' (duration: 486.945141ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:36.543722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:36.056507Z","time spent":"487.176867ms","remote":"127.0.0.1:56048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-14T07:01:36.543658Z","caller":"traceutil/trace.go:171","msg":"trace[1986961563] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"284.595282ms","start":"2026-03-14T07:01:36.259035Z","end":"2026-03-14T07:01:36.54363Z","steps":["trace[1986961563] 'process raft request' (duration: 284.52656ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:36.543922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.596606ms","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-14T07:01:36.543948Z","caller":"traceutil/trace.go:171","msg":"trace[98270902] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:564; }","duration":"390.624427ms","start":"2026-03-14T07:01:36.153317Z","end":"2026-03-14T07:01:36.543941Z","steps":["trace[98270902] 'agreement among raft nodes before linearized reading' (duration: 390.504594ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:36.543963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:36.153236Z","time spent":"390.724159ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-14T07:01:36.54405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.744549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-14T07:01:36.544082Z","caller":"traceutil/trace.go:171","msg":"trace[724289732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:564; }","duration":"386.820941ms","start":"2026-03-14T07:01:36.157253Z","end":"2026-03-14T07:01:36.544074Z","steps":["trace[724289732] 'agreement among raft nodes before linearized reading' (duration: 386.703918ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:36.544103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:36.157232Z","time spent":"386.866101ms","remote":"127.0.0.1:56294","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-14T07:01:36.544141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.477022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-14T07:01:36.544163Z","caller":"traceutil/trace.go:171","msg":"trace[319099937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:564; }","duration":"386.521443ms","start":"2026-03-14T07:01:36.157635Z","end":"2026-03-14T07:01:36.544157Z","steps":["trace[319099937] 'agreement among raft nodes before linearized reading' (duration: 386.447062ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:36.544177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:36.157622Z","time spent":"386.552104ms","remote":"127.0.0.1:56294","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-14T07:01:36.749502Z","caller":"traceutil/trace.go:171","msg":"trace[1452337369] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"122.359319ms","start":"2026-03-14T07:01:36.627118Z","end":"2026-03-14T07:01:36.749477Z","steps":["trace[1452337369] 'process raft request' (duration: 122.150994ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:36.749728Z","caller":"traceutil/trace.go:171","msg":"trace[1798644213] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"121.258265ms","start":"2026-03-14T07:01:36.628448Z","end":"2026-03-14T07:01:36.749706Z","steps":["trace[1798644213] 'process raft request' (duration: 120.965808ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:37.002288Z","caller":"traceutil/trace.go:171","msg":"trace[76134216] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"102.898334ms","start":"2026-03-14T07:01:36.89935Z","end":"2026-03-14T07:01:37.002248Z","steps":["trace[76134216] 'process raft request' (duration: 102.28255ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:37.494155Z","caller":"traceutil/trace.go:171","msg":"trace[813792742] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"435.908323ms","start":"2026-03-14T07:01:37.058206Z","end":"2026-03-14T07:01:37.494114Z","steps":["trace[813792742] 'process raft request' (duration: 435.706158ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:37.494353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:37.058173Z","time spent":"436.088547ms","remote":"127.0.0.1:56048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-14T07:01:37.679015Z","caller":"traceutil/trace.go:171","msg":"trace[685851616] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"424.089603ms","start":"2026-03-14T07:01:37.254886Z","end":"2026-03-14T07:01:37.678976Z","steps":["trace[685851616] 'process raft request' (duration: 349.400345ms)","trace[685851616] 'compare' (duration: 74.492843ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:01:37.679224Z","caller":"traceutil/trace.go:171","msg":"trace[288434713] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"221.061328ms","start":"2026-03-14T07:01:37.45813Z","end":"2026-03-14T07:01:37.679191Z","steps":["trace[288434713] 'process raft request' (duration: 220.780651ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:37.679279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.348336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:01:37.679382Z","caller":"traceutil/trace.go:171","msg":"trace[449263562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"232.461759ms","start":"2026-03-14T07:01:37.446906Z","end":"2026-03-14T07:01:37.679367Z","steps":["trace[449263562] 'agreement among raft nodes before linearized reading' (duration: 232.175602ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:37.679042Z","caller":"traceutil/trace.go:171","msg":"trace[1994829913] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:608; }","duration":"232.098731ms","start":"2026-03-14T07:01:37.446924Z","end":"2026-03-14T07:01:37.679023Z","steps":["trace[1994829913] 'read index received' (duration: 47.008006ms)","trace[1994829913] 'applied index is now lower than readState.Index' (duration: 185.089345ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:01:37.679592Z","caller":"traceutil/trace.go:171","msg":"trace[976115363] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"195.145765ms","start":"2026-03-14T07:01:37.484412Z","end":"2026-03-14T07:01:37.679557Z","steps":["trace[976115363] 'process raft request' (duration: 194.547031ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:37.684036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:37.254846Z","time spent":"427.247485ms","remote":"127.0.0.1:56048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-14T07:01:37.684471Z","caller":"traceutil/trace.go:171","msg":"trace[1185992850] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"185.33276ms","start":"2026-03-14T07:01:37.499121Z","end":"2026-03-14T07:01:37.684454Z","steps":["trace[1185992850] 'process raft request' (duration: 184.749127ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:38.033003Z","caller":"traceutil/trace.go:171","msg":"trace[632164002] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"341.920494ms","start":"2026-03-14T07:01:37.691048Z","end":"2026-03-14T07:01:38.032969Z","steps":["trace[632164002] 'process raft request' (duration: 264.588394ms)","trace[632164002] 'compare' (duration: 77.199237ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:01:38.033173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:37.691026Z","time spent":"342.058587ms","remote":"127.0.0.1:56048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:01:38.060255Z","caller":"traceutil/trace.go:171","msg":"trace[1533347250] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"368.625274ms","start":"2026-03-14T07:01:37.691603Z","end":"2026-03-14T07:01:38.060228Z","steps":["trace[1533347250] 'process raft request' (duration: 368.424379ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:38.060333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:37.691583Z","time spent":"368.712916ms","remote":"127.0.0.1:56048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4165,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:01:38.060227Z","caller":"traceutil/trace.go:171","msg":"trace[319147398] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:613; }","duration":"367.219352ms","start":"2026-03-14T07:01:37.692986Z","end":"2026-03-14T07:01:38.060205Z","steps":["trace[319147398] 'read index received' (duration: 262.675411ms)","trace[319147398] 'applied index is now lower than readState.Index' (duration: 104.541201ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:01:38.060439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.433617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zq65g\" ","response":"range_response_count:1 size:17790"} {"level":"info","ts":"2026-03-14T07:01:38.060449Z","caller":"traceutil/trace.go:171","msg":"trace[1570615945] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"366.470204ms","start":"2026-03-14T07:01:37.693915Z","end":"2026-03-14T07:01:38.060385Z","steps":["trace[1570615945] 'process raft request' (duration: 366.238999ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:38.06049Z","caller":"traceutil/trace.go:171","msg":"trace[89114992] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zq65g; range_end:; response_count:1; response_revision:597; }","duration":"367.536779ms","start":"2026-03-14T07:01:37.692941Z","end":"2026-03-14T07:01:38.060478Z","steps":["trace[89114992] 'agreement among raft nodes before linearized reading' (duration: 367.345695ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:38.060671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:37.693882Z","time spent":"366.694509ms","remote":"127.0.0.1:56082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":771,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-14T07:01:38.06058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:37.69293Z","time spent":"367.640011ms","remote":"127.0.0.1:56200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17813,"request content":"key:\"/registry/pods/kube-system/cilium-zq65g\" "} {"level":"warn","ts":"2026-03-14T07:01:38.126135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.786325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-14T07:01:38.126199Z","caller":"traceutil/trace.go:171","msg":"trace[89116125] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:598; }","duration":"322.901717ms","start":"2026-03-14T07:01:37.803283Z","end":"2026-03-14T07:01:38.126185Z","steps":["trace[89116125] 'agreement among raft nodes before linearized reading' (duration: 322.767444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:38.126237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:37.803258Z","time spent":"322.968169ms","remote":"127.0.0.1:56026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.140\" "} {"level":"warn","ts":"2026-03-14T07:01:38.126315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.204841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:01:38.126447Z","caller":"traceutil/trace.go:171","msg":"trace[1780447835] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:598; }","duration":"273.336344ms","start":"2026-03-14T07:01:37.853064Z","end":"2026-03-14T07:01:38.126401Z","steps":["trace[1780447835] 'agreement among raft nodes before linearized reading' (duration: 273.122929ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:38.275554Z","caller":"traceutil/trace.go:171","msg":"trace[1242058895] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"209.33432ms","start":"2026-03-14T07:01:38.06619Z","end":"2026-03-14T07:01:38.275525Z","steps":["trace[1242058895] 'process raft request' (duration: 194.264716ms)","trace[1242058895] 'compare' (duration: 14.805318ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:01:38.275799Z","caller":"traceutil/trace.go:171","msg":"trace[1752149360] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"200.597839ms","start":"2026-03-14T07:01:38.075181Z","end":"2026-03-14T07:01:38.275779Z","steps":["trace[1752149360] 'process raft request' (duration: 200.260262ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:38.434612Z","caller":"traceutil/trace.go:171","msg":"trace[240485992] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"143.987894ms","start":"2026-03-14T07:01:38.290594Z","end":"2026-03-14T07:01:38.434581Z","steps":["trace[240485992] 'process raft request' (duration: 120.450806ms)","trace[240485992] 'compare' (duration: 23.429766ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:01:41.050921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.391939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:01:41.050977Z","caller":"traceutil/trace.go:171","msg":"trace[1415774087] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:643; }","duration":"132.464ms","start":"2026-03-14T07:01:40.918502Z","end":"2026-03-14T07:01:41.050966Z","steps":["trace[1415774087] 'range keys from in-memory index tree' (duration: 132.347448ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:41.051041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.331648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T07:01:41.05098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.760217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T07:01:41.051097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.300849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:01:41.051119Z","caller":"traceutil/trace.go:171","msg":"trace[139736800] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:643; }","duration":"171.357551ms","start":"2026-03-14T07:01:40.879756Z","end":"2026-03-14T07:01:41.051114Z","steps":["trace[139736800] 'range keys from in-memory index tree' (duration: 171.248308ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:41.051123Z","caller":"traceutil/trace.go:171","msg":"trace[1359173817] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:643; }","duration":"132.995483ms","start":"2026-03-14T07:01:40.918093Z","end":"2026-03-14T07:01:41.051088Z","steps":["trace[1359173817] 'count revisions from in-memory index tree' (duration: 132.615624ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:41.051224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.088355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:01:41.051247Z","caller":"traceutil/trace.go:171","msg":"trace[1798497654] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:643; }","duration":"171.127976ms","start":"2026-03-14T07:01:40.880114Z","end":"2026-03-14T07:01:41.051242Z","steps":["trace[1798497654] 'count revisions from in-memory index tree' (duration: 171.046044ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:41.051069Z","caller":"traceutil/trace.go:171","msg":"trace[1526222009] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:643; }","duration":"170.381709ms","start":"2026-03-14T07:01:40.880682Z","end":"2026-03-14T07:01:41.051063Z","steps":["trace[1526222009] 'range keys from in-memory index tree' (duration: 170.281447ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:41.051999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.111748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:01:41.052053Z","caller":"traceutil/trace.go:171","msg":"trace[1493455231] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:643; }","duration":"134.185991ms","start":"2026-03-14T07:01:40.917858Z","end":"2026-03-14T07:01:41.052044Z","steps":["trace[1493455231] 'range keys from in-memory index tree' (duration: 133.560427ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:52.624252Z","caller":"traceutil/trace.go:171","msg":"trace[2132305729] linearizableReadLoop","detail":"{readStateIndex:754; appliedIndex:753; }","duration":"177.916429ms","start":"2026-03-14T07:01:52.446303Z","end":"2026-03-14T07:01:52.62422Z","steps":["trace[2132305729] 'read index received' (duration: 177.723395ms)","trace[2132305729] 'applied index is now lower than readState.Index' (duration: 191.444µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:01:52.624266Z","caller":"traceutil/trace.go:171","msg":"trace[1134560314] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"206.163793ms","start":"2026-03-14T07:01:52.418067Z","end":"2026-03-14T07:01:52.624231Z","steps":["trace[1134560314] 'process raft request' (duration: 205.974469ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:52.624489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.160545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:01:52.624606Z","caller":"traceutil/trace.go:171","msg":"trace[1615798430] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:731; }","duration":"178.356399ms","start":"2026-03-14T07:01:52.446221Z","end":"2026-03-14T07:01:52.624577Z","steps":["trace[1615798430] 'agreement among raft nodes before linearized reading' (duration: 178.107953ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:53.156002Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555604450886645499,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:01:53.656281Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555604450886645499,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:01:54.156506Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555604450886645499,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:01:54.232854Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.599502773s","expected-duration":"1s"} {"level":"info","ts":"2026-03-14T07:01:54.233175Z","caller":"traceutil/trace.go:171","msg":"trace[1702832849] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"1.599902034s","start":"2026-03-14T07:01:52.633247Z","end":"2026-03-14T07:01:54.233149Z","steps":["trace[1702832849] 'process raft request' (duration: 1.59976972s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:54.233298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:52.633229Z","time spent":"1.599983856s","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:01:54.401976Z","caller":"traceutil/trace.go:171","msg":"trace[385347513] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"1.768281121s","start":"2026-03-14T07:01:52.63366Z","end":"2026-03-14T07:01:54.401941Z","steps":["trace[385347513] 'process raft request' (duration: 1.751319278s)","trace[385347513] 'compare' (duration: 16.677606ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:01:54.402184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:52.633629Z","time spent":"1.768434965s","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:01:54.432825Z","caller":"traceutil/trace.go:171","msg":"trace[1898023295] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:755; }","duration":"1.776835709s","start":"2026-03-14T07:01:52.655962Z","end":"2026-03-14T07:01:54.432798Z","steps":["trace[1898023295] 'read index received' (duration: 1.57733556s)","trace[1898023295] 'applied index is now lower than readState.Index' (duration: 199.499259ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:01:54.433061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.777080925s","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-14T07:01:54.433104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.770769969s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-14T07:01:54.433133Z","caller":"traceutil/trace.go:171","msg":"trace[1745511906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:733; }","duration":"1.770848321s","start":"2026-03-14T07:01:52.662276Z","end":"2026-03-14T07:01:54.433124Z","steps":["trace[1745511906] 'agreement among raft nodes before linearized reading' (duration: 1.770680507s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:54.433161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:52.662265Z","time spent":"1.770889602s","remote":"127.0.0.1:56294","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-14T07:01:54.433148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.770677817s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-14T07:01:54.433315Z","caller":"traceutil/trace.go:171","msg":"trace[1826357284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:733; }","duration":"1.770814089s","start":"2026-03-14T07:01:52.662413Z","end":"2026-03-14T07:01:54.433227Z","steps":["trace[1826357284] 'agreement among raft nodes before linearized reading' (duration: 1.770598114s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:54.4334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:52.662372Z","time spent":"1.770989763s","remote":"127.0.0.1:56294","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-14T07:01:54.43378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.079531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:01:54.434039Z","caller":"traceutil/trace.go:171","msg":"trace[1179678846] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:733; }","duration":"1.777161177s","start":"2026-03-14T07:01:52.655952Z","end":"2026-03-14T07:01:54.433113Z","steps":["trace[1179678846] 'agreement among raft nodes before linearized reading' (duration: 1.776941142s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:54.434084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:52.655911Z","time spent":"1.778162499s","remote":"127.0.0.1:56294","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-14T07:01:54.434013Z","caller":"traceutil/trace.go:171","msg":"trace[1501345889] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:733; }","duration":"986.379919ms","start":"2026-03-14T07:01:53.447606Z","end":"2026-03-14T07:01:54.433986Z","steps":["trace[1501345889] 'agreement among raft nodes before linearized reading' (duration: 986.060261ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:54.434269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:53.447576Z","time spent":"986.634984ms","remote":"127.0.0.1:46568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-14T07:01:56.550207Z","caller":"traceutil/trace.go:171","msg":"trace[1872784858] linearizableReadLoop","detail":"{readStateIndex:761; appliedIndex:760; }","duration":"142.805466ms","start":"2026-03-14T07:01:56.407378Z","end":"2026-03-14T07:01:56.550184Z","steps":["trace[1872784858] 'read index received' (duration: 142.571401ms)","trace[1872784858] 'applied index is now lower than readState.Index' (duration: 220.725µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:01:56.550273Z","caller":"traceutil/trace.go:171","msg":"trace[1121966204] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"307.794447ms","start":"2026-03-14T07:01:56.242447Z","end":"2026-03-14T07:01:56.550241Z","steps":["trace[1121966204] 'process raft request' (duration: 307.549011ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:56.550423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.48323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:01:56.550507Z","caller":"traceutil/trace.go:171","msg":"trace[1929417064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:737; }","duration":"104.639853ms","start":"2026-03-14T07:01:56.445849Z","end":"2026-03-14T07:01:56.550489Z","steps":["trace[1929417064] 'agreement among raft nodes before linearized reading' (duration: 104.49514ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:56.55065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:56.242419Z","time spent":"307.93693ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:01:56.550831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.423661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-14T07:01:56.550962Z","caller":"traceutil/trace.go:171","msg":"trace[1731736506] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:737; }","duration":"143.629495ms","start":"2026-03-14T07:01:56.407308Z","end":"2026-03-14T07:01:56.550937Z","steps":["trace[1731736506] 'agreement among raft nodes before linearized reading' (duration: 143.38376ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:57.847684Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.29226835s","expected-duration":"1s"} {"level":"info","ts":"2026-03-14T07:01:57.848127Z","caller":"traceutil/trace.go:171","msg":"trace[2099385339] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"1.292854994s","start":"2026-03-14T07:01:56.55524Z","end":"2026-03-14T07:01:57.848095Z","steps":["trace[2099385339] 'process raft request' (duration: 1.292664689s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:57.848329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:56.555214Z","time spent":"1.293012078s","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:01:57.853121Z","caller":"traceutil/trace.go:171","msg":"trace[496776829] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"1.296864307s","start":"2026-03-14T07:01:56.556232Z","end":"2026-03-14T07:01:57.853096Z","steps":["trace[496776829] 'process raft request' (duration: 1.296532609s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:57.853209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:56.55621Z","time spent":"1.296957418s","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:01:57.85323Z","caller":"traceutil/trace.go:171","msg":"trace[1818582022] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:761; }","duration":"406.902211ms","start":"2026-03-14T07:01:57.446306Z","end":"2026-03-14T07:01:57.853208Z","steps":["trace[1818582022] 'read index received' (duration: 401.567258ms)","trace[1818582022] 'applied index is now lower than readState.Index' (duration: 5.333813ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:01:57.8534Z","caller":"traceutil/trace.go:171","msg":"trace[1399218749] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"1.29612111s","start":"2026-03-14T07:01:56.557253Z","end":"2026-03-14T07:01:57.853374Z","steps":["trace[1399218749] 'process raft request' (duration: 1.295909105s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:57.853437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.112996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:01:57.853485Z","caller":"traceutil/trace.go:171","msg":"trace[385581543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:741; }","duration":"407.221528ms","start":"2026-03-14T07:01:57.446252Z","end":"2026-03-14T07:01:57.853473Z","steps":["trace[385581543] 'agreement among raft nodes before linearized reading' (duration: 407.095665ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:57.853513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:56.557239Z","time spent":"1.296210572s","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:01:57.853401Z","caller":"traceutil/trace.go:171","msg":"trace[946354683] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"1.296978969s","start":"2026-03-14T07:01:56.556376Z","end":"2026-03-14T07:01:57.853355Z","steps":["trace[946354683] 'process raft request' (duration: 1.296527539s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:57.853523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:57.446233Z","time spent":"407.281919ms","remote":"127.0.0.1:46568","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-14T07:01:57.853639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:56.556347Z","time spent":"1.297206664s","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:02:21.370308Z","caller":"traceutil/trace.go:171","msg":"trace[2067621583] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"675.03931ms","start":"2026-03-14T07:02:20.695238Z","end":"2026-03-14T07:02:21.370277Z","steps":["trace[2067621583] 'process raft request' (duration: 674.841455ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:21.370506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:20.695213Z","time spent":"675.199373ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:02:21.916577Z","caller":"traceutil/trace.go:171","msg":"trace[774225544] linearizableReadLoop","detail":"{readStateIndex:842; appliedIndex:841; }","duration":"469.249765ms","start":"2026-03-14T07:02:21.447301Z","end":"2026-03-14T07:02:21.916551Z","steps":["trace[774225544] 'read index received' (duration: 469.048721ms)","trace[774225544] 'applied index is now lower than readState.Index' (duration: 200.274µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:02:21.916731Z","caller":"traceutil/trace.go:171","msg":"trace[2021499183] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"661.471115ms","start":"2026-03-14T07:02:21.255195Z","end":"2026-03-14T07:02:21.916667Z","steps":["trace[2021499183] 'process raft request' (duration: 661.212999ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:21.916793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.47935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:02:21.916858Z","caller":"traceutil/trace.go:171","msg":"trace[149281835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:812; }","duration":"469.617043ms","start":"2026-03-14T07:02:21.44723Z","end":"2026-03-14T07:02:21.916847Z","steps":["trace[149281835] 'agreement among raft nodes before linearized reading' (duration: 469.520311ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:21.9169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:21.447057Z","time spent":"469.834428ms","remote":"127.0.0.1:46568","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-14T07:02:21.916899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.746964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-14T07:02:21.916958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:21.255166Z","time spent":"661.66923ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:02:21.917014Z","caller":"traceutil/trace.go:171","msg":"trace[1231755199] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:812; }","duration":"443.941438ms","start":"2026-03-14T07:02:21.473049Z","end":"2026-03-14T07:02:21.916991Z","steps":["trace[1231755199] 'agreement among raft nodes before linearized reading' (duration: 443.736604ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:21.917068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:21.473024Z","time spent":"444.032971ms","remote":"127.0.0.1:56388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":13,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-03-14T07:02:22.067336Z","caller":"traceutil/trace.go:171","msg":"trace[1903768064] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"102.913092ms","start":"2026-03-14T07:02:21.964358Z","end":"2026-03-14T07:02:22.067271Z","steps":["trace[1903768064] 'process raft request' (duration: 35.482991ms)","trace[1903768064] 'compare' (duration: 66.644193ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:02:23.876848Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555604450886645739,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:02:24.045949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.055818ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-14T07:02:24.046087Z","caller":"traceutil/trace.go:171","msg":"trace[2133076139] linearizableReadLoop","detail":"{readStateIndex:847; appliedIndex:846; }","duration":"670.165067ms","start":"2026-03-14T07:02:23.3759Z","end":"2026-03-14T07:02:24.046065Z","steps":["trace[2133076139] 'read index received' (duration: 544.032396ms)","trace[2133076139] 'applied index is now lower than readState.Index' (duration: 126.131121ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:02:24.046316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.406562ms","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-14T07:02:24.046288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.166686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:02:24.046385Z","caller":"traceutil/trace.go:171","msg":"trace[1274803276] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:816; }","duration":"600.326619ms","start":"2026-03-14T07:02:23.446034Z","end":"2026-03-14T07:02:24.04636Z","steps":["trace[1274803276] 'agreement among raft nodes before linearized reading' (duration: 600.151105ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:24.046424Z","caller":"traceutil/trace.go:171","msg":"trace[1811203355] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:816; }","duration":"670.527975ms","start":"2026-03-14T07:02:23.375815Z","end":"2026-03-14T07:02:24.046343Z","steps":["trace[1811203355] 'agreement among raft nodes before linearized reading' (duration: 670.339181ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:24.046442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:23.446019Z","time spent":"600.412991ms","remote":"127.0.0.1:46568","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-14T07:02:24.046472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:23.375788Z","time spent":"670.672328ms","remote":"127.0.0.1:56294","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-14T07:02:24.227226Z","caller":"traceutil/trace.go:171","msg":"trace[1254265982] linearizableReadLoop","detail":"{readStateIndex:848; appliedIndex:847; }","duration":"155.271665ms","start":"2026-03-14T07:02:24.071924Z","end":"2026-03-14T07:02:24.227195Z","steps":["trace[1254265982] 'read index received' (duration: 155.06797ms)","trace[1254265982] 'applied index is now lower than readState.Index' (duration: 202.655µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:02:24.227296Z","caller":"traceutil/trace.go:171","msg":"trace[1195897883] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"175.231227ms","start":"2026-03-14T07:02:24.05203Z","end":"2026-03-14T07:02:24.227262Z","steps":["trace[1195897883] 'process raft request' (duration: 174.967711ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:24.227593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.642314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-14T07:02:24.227688Z","caller":"traceutil/trace.go:171","msg":"trace[1993402777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:817; }","duration":"155.800247ms","start":"2026-03-14T07:02:24.071863Z","end":"2026-03-14T07:02:24.227663Z","steps":["trace[1993402777] 'agreement among raft nodes before linearized reading' (duration: 155.49419ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:24.228184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.658823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-14T07:02:24.228282Z","caller":"traceutil/trace.go:171","msg":"trace[189908930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:817; }","duration":"107.820997ms","start":"2026-03-14T07:02:24.120441Z","end":"2026-03-14T07:02:24.228262Z","steps":["trace[189908930] 'agreement among raft nodes before linearized reading' (duration: 106.918056ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:24.946689Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555604450886645749,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-14T07:02:25.198664Z","caller":"traceutil/trace.go:171","msg":"trace[2035093425] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"966.612749ms","start":"2026-03-14T07:02:24.23202Z","end":"2026-03-14T07:02:25.198633Z","steps":["trace[2035093425] 'process raft request' (duration: 966.429275ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:25.198887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:24.231997Z","time spent":"966.764013ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:02:25.233426Z","caller":"traceutil/trace.go:171","msg":"trace[1930039469] linearizableReadLoop","detail":"{readStateIndex:852; appliedIndex:848; }","duration":"787.139675ms","start":"2026-03-14T07:02:24.446261Z","end":"2026-03-14T07:02:25.233401Z","steps":["trace[1930039469] 'read index received' (duration: 752.190325ms)","trace[1930039469] 'applied index is now lower than readState.Index' (duration: 34.94861ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:02:25.233469Z","caller":"traceutil/trace.go:171","msg":"trace[2092488022] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"1.000550087s","start":"2026-03-14T07:02:24.232893Z","end":"2026-03-14T07:02:25.233443Z","steps":["trace[2092488022] 'process raft request' (duration: 1.000439044s)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:25.23366Z","caller":"traceutil/trace.go:171","msg":"trace[352995700] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"1.000798842s","start":"2026-03-14T07:02:24.232741Z","end":"2026-03-14T07:02:25.23354Z","steps":["trace[352995700] 'process raft request' (duration: 1.000481195s)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:25.233541Z","caller":"traceutil/trace.go:171","msg":"trace[1328587326] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"1.000260959s","start":"2026-03-14T07:02:24.233266Z","end":"2026-03-14T07:02:25.233527Z","steps":["trace[1328587326] 'process raft request' (duration: 1.000102465s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:25.233848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.444756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-14T07:02:25.233845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:24.232884Z","time spent":"1.000855414s","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:02:25.233921Z","caller":"traceutil/trace.go:171","msg":"trace[1087604769] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:821; }","duration":"668.56511ms","start":"2026-03-14T07:02:24.565319Z","end":"2026-03-14T07:02:25.233884Z","steps":["trace[1087604769] 'agreement among raft nodes before linearized reading' (duration: 668.447837ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:25.233949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:24.232718Z","time spent":"1.001093378s","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:02:25.23396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:24.233254Z","time spent":"1.000623847s","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:02:25.233921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.558714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:02:25.234114Z","caller":"traceutil/trace.go:171","msg":"trace[2129859882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:821; }","duration":"787.864092ms","start":"2026-03-14T07:02:24.446224Z","end":"2026-03-14T07:02:25.234088Z","steps":["trace[2129859882] 'agreement among raft nodes before linearized reading' (duration: 787.390411ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:25.234214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:24.446209Z","time spent":"787.992534ms","remote":"127.0.0.1:46568","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-14T07:02:25.233992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:24.565297Z","time spent":"668.659652ms","remote":"127.0.0.1:56194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-03-14T07:02:27.542313Z","caller":"traceutil/trace.go:171","msg":"trace[1823685091] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"241.654425ms","start":"2026-03-14T07:02:27.300624Z","end":"2026-03-14T07:02:27.542278Z","steps":["trace[1823685091] 'process raft request' (duration: 241.512592ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:27.542333Z","caller":"traceutil/trace.go:171","msg":"trace[1463333124] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"242.518045ms","start":"2026-03-14T07:02:27.299758Z","end":"2026-03-14T07:02:27.542276Z","steps":["trace[1463333124] 'process raft request' (duration: 169.953145ms)","trace[1463333124] 'compare' (duration: 72.297164ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:02:37.702466Z","caller":"traceutil/trace.go:171","msg":"trace[1647790528] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"110.927169ms","start":"2026-03-14T07:02:37.591516Z","end":"2026-03-14T07:02:37.702443Z","steps":["trace[1647790528] 'process raft request' (duration: 110.802246ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:37.704393Z","caller":"traceutil/trace.go:171","msg":"trace[1593717334] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"112.174788ms","start":"2026-03-14T07:02:37.592201Z","end":"2026-03-14T07:02:37.704375Z","steps":["trace[1593717334] 'process raft request' (duration: 112.099436ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:38.351701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.600592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:02:38.351785Z","caller":"traceutil/trace.go:171","msg":"trace[1645691074] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"347.559667ms","start":"2026-03-14T07:02:38.00421Z","end":"2026-03-14T07:02:38.35177Z","steps":["trace[1645691074] 'process raft request' (duration: 172.822051ms)","trace[1645691074] 'compare' (duration: 174.50914ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:02:38.351909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:38.004187Z","time spent":"347.693961ms","remote":"127.0.0.1:56026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:02:39.947146Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555604450886645958,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:02:40.448274Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555604450886645958,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:02:40.615408Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.236211723s","expected-duration":"1s"} {"level":"info","ts":"2026-03-14T07:02:40.615829Z","caller":"traceutil/trace.go:171","msg":"trace[180655893] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:945; }","duration":"1.169441977s","start":"2026-03-14T07:02:39.44636Z","end":"2026-03-14T07:02:40.615802Z","steps":["trace[180655893] 'read index received' (duration: 1.169187351s)","trace[180655893] 'applied index is now lower than readState.Index' (duration: 253.696µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:02:40.615974Z","caller":"traceutil/trace.go:171","msg":"trace[1094873112] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"1.236796267s","start":"2026-03-14T07:02:39.379123Z","end":"2026-03-14T07:02:40.615919Z","steps":["trace[1094873112] 'process raft request' (duration: 1.23651736s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:40.616049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169654952s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:02:40.616097Z","caller":"traceutil/trace.go:171","msg":"trace[138294666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:910; }","duration":"1.169749443s","start":"2026-03-14T07:02:39.446334Z","end":"2026-03-14T07:02:40.616084Z","steps":["trace[138294666] 'agreement among raft nodes before linearized reading' (duration: 1.169569659s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:40.616103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.116195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-14T07:02:40.616132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:39.446324Z","time spent":"1.169800565s","remote":"127.0.0.1:46568","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-14T07:02:40.616156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.564622ms","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-14T07:02:40.616177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168221338s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-14T07:02:40.616178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.112486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-14T07:02:40.616194Z","caller":"traceutil/trace.go:171","msg":"trace[1580802047] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:910; }","duration":"543.255148ms","start":"2026-03-14T07:02:40.072916Z","end":"2026-03-14T07:02:40.616171Z","steps":["trace[1580802047] 'agreement among raft nodes before linearized reading' (duration: 543.041793ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:40.616213Z","caller":"traceutil/trace.go:171","msg":"trace[804344354] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:910; }","duration":"253.653544ms","start":"2026-03-14T07:02:40.362542Z","end":"2026-03-14T07:02:40.616196Z","steps":["trace[804344354] 'agreement among raft nodes before linearized reading' (duration: 253.47297ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:40.616232Z","caller":"traceutil/trace.go:171","msg":"trace[1013452340] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:910; }","duration":"1.16830291s","start":"2026-03-14T07:02:39.447914Z","end":"2026-03-14T07:02:40.616217Z","steps":["trace[1013452340] 'agreement among raft nodes before linearized reading' (duration: 1.168033714s)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:40.616241Z","caller":"traceutil/trace.go:171","msg":"trace[1417509980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:910; }","duration":"908.221758ms","start":"2026-03-14T07:02:39.708005Z","end":"2026-03-14T07:02:40.616227Z","steps":["trace[1417509980] 'agreement among raft nodes before linearized reading' (duration: 908.021983ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:40.616227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:39.379107Z","time spent":"1.236991901s","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:02:40.616254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:40.072898Z","time spent":"543.34128ms","remote":"127.0.0.1:56506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-03-14T07:02:40.616269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:39.707986Z","time spent":"908.278699ms","remote":"127.0.0.1:56294","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-14T07:02:40.616273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:39.447905Z","time spent":"1.168360351s","remote":"127.0.0.1:56294","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-14T07:02:40.616356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.58353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-14T07:02:40.616369Z","caller":"traceutil/trace.go:171","msg":"trace[1896812196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:910; }","duration":"909.619621ms","start":"2026-03-14T07:02:39.706745Z","end":"2026-03-14T07:02:40.616365Z","steps":["trace[1896812196] 'agreement among raft nodes before linearized reading' (duration: 909.57706ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:40.616381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:39.706729Z","time spent":"909.648992ms","remote":"127.0.0.1:56294","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-14T07:02:41.125722Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555604450886645962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:02:41.625905Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555604450886645962,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:02:42.444515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.163015ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:02:42.444724Z","caller":"traceutil/trace.go:171","msg":"trace[1449520017] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:946; }","duration":"1.819079828s","start":"2026-03-14T07:02:40.625629Z","end":"2026-03-14T07:02:42.444708Z","steps":["trace[1449520017] 'read index received' (duration: 1.437652396s)","trace[1449520017] 'applied index is now lower than readState.Index' (duration: 381.426542ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:02:42.444915Z","caller":"traceutil/trace.go:171","msg":"trace[273567133] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"1.823618052s","start":"2026-03-14T07:02:40.621285Z","end":"2026-03-14T07:02:42.444903Z","steps":["trace[273567133] 'process raft request' (duration: 1.441947475s)","trace[273567133] 'compare' (duration: 381.060893ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:02:42.444975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:40.621261Z","time spent":"1.823680964s","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:02:42.445005Z","caller":"traceutil/trace.go:171","msg":"trace[1886860140] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"1.822640041s","start":"2026-03-14T07:02:40.622328Z","end":"2026-03-14T07:02:42.444968Z","steps":["trace[1886860140] 'process raft request' (duration: 1.822351683s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:42.445096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.819425176s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T07:02:42.445148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:40.622317Z","time spent":"1.822747363s","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:02:42.44518Z","caller":"traceutil/trace.go:171","msg":"trace[1163062215] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:914; }","duration":"1.819549999s","start":"2026-03-14T07:02:40.62561Z","end":"2026-03-14T07:02:42.445161Z","steps":["trace[1163062215] 'agreement among raft nodes before linearized reading' (duration: 1.819385486s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:42.445216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:40.625604Z","time spent":"1.81960656s","remote":"127.0.0.1:46568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-14T07:02:42.445236Z","caller":"traceutil/trace.go:171","msg":"trace[1223536876] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"1.823812967s","start":"2026-03-14T07:02:40.621386Z","end":"2026-03-14T07:02:42.445199Z","steps":["trace[1223536876] 'process raft request' (duration: 1.823219083s)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:42.445306Z","caller":"traceutil/trace.go:171","msg":"trace[673115554] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"1.823230874s","start":"2026-03-14T07:02:40.622063Z","end":"2026-03-14T07:02:42.445294Z","steps":["trace[673115554] 'process raft request' (duration: 1.822588689s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:42.44536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:40.622053Z","time spent":"1.823278525s","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:02:42.445403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:40.621363Z","time spent":"1.823958461s","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:02:42.522221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.260037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T07:02:42.522326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.168885ms","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":"warn","ts":"2026-03-14T07:02:42.522359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.726626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-14T07:02:42.522367Z","caller":"traceutil/trace.go:171","msg":"trace[1102490304] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:915; }","duration":"362.478643ms","start":"2026-03-14T07:02:42.159863Z","end":"2026-03-14T07:02:42.522342Z","steps":["trace[1102490304] 'agreement among raft nodes before linearized reading' (duration: 362.220497ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:42.522372Z","caller":"traceutil/trace.go:171","msg":"trace[807467510] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"228.785367ms","start":"2026-03-14T07:02:42.293548Z","end":"2026-03-14T07:02:42.522334Z","steps":["trace[807467510] 'process raft request' (duration: 228.296886ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:42.522404Z","caller":"traceutil/trace.go:171","msg":"trace[1894119595] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:915; }","duration":"904.317248ms","start":"2026-03-14T07:02:41.618067Z","end":"2026-03-14T07:02:42.522385Z","steps":["trace[1894119595] 'agreement among raft nodes before linearized reading' (duration: 904.189415ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:42.522427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:42.159837Z","time spent":"362.575085ms","remote":"127.0.0.1:56446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-03-14T07:02:42.522458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:41.618038Z","time spent":"904.41124ms","remote":"127.0.0.1:56170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-03-14T07:02:42.522413Z","caller":"traceutil/trace.go:171","msg":"trace[1702114737] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:915; }","duration":"241.814168ms","start":"2026-03-14T07:02:42.280573Z","end":"2026-03-14T07:02:42.522387Z","steps":["trace[1702114737] 'agreement among raft nodes before linearized reading' (duration: 241.506781ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:57.158185Z","caller":"traceutil/trace.go:171","msg":"trace[856484341] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"119.457657ms","start":"2026-03-14T07:02:57.038704Z","end":"2026-03-14T07:02:57.158162Z","steps":["trace[856484341] 'process raft request' (duration: 119.330035ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:57.326382Z","caller":"traceutil/trace.go:171","msg":"trace[1870324100] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"162.299681ms","start":"2026-03-14T07:02:57.164057Z","end":"2026-03-14T07:02:57.326356Z","steps":["trace[1870324100] 'process raft request' (duration: 162.128418ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:57.466376Z","caller":"traceutil/trace.go:171","msg":"trace[190599751] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"300.166124ms","start":"2026-03-14T07:02:57.166185Z","end":"2026-03-14T07:02:57.466351Z","steps":["trace[190599751] 'process raft request' (duration: 292.675902ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:57.466433Z","caller":"traceutil/trace.go:171","msg":"trace[1683074140] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"297.840725ms","start":"2026-03-14T07:02:57.168568Z","end":"2026-03-14T07:02:57.466409Z","steps":["trace[1683074140] 'process raft request' (duration: 297.707032ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:57.466524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:57.166177Z","time spent":"300.272747ms","remote":"127.0.0.1:56182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:02:58.007064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.777057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5184"} {"level":"info","ts":"2026-03-14T07:02:58.007165Z","caller":"traceutil/trace.go:171","msg":"trace[1332060787] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:972; }","duration":"395.92823ms","start":"2026-03-14T07:02:57.611218Z","end":"2026-03-14T07:02:58.007146Z","steps":["trace[1332060787] 'range keys from in-memory index tree' (duration: 395.542602ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:58.007205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:57.611197Z","time spent":"395.999513ms","remote":"127.0.0.1:56194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5207,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-14T07:02:58.007401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.857447ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:02:58.007474Z","caller":"traceutil/trace.go:171","msg":"trace[337932715] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:972; }","duration":"305.934478ms","start":"2026-03-14T07:02:57.701531Z","end":"2026-03-14T07:02:58.007466Z","steps":["trace[337932715] 'range keys from in-memory index tree' (duration: 305.849507ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:58.008091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.243207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-14T07:02:58.008131Z","caller":"traceutil/trace.go:171","msg":"trace[807180727] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:972; }","duration":"199.324449ms","start":"2026-03-14T07:02:57.808797Z","end":"2026-03-14T07:02:58.008121Z","steps":["trace[807180727] 'range keys from in-memory index tree' (duration: 198.789198ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:58.82455Z","caller":"traceutil/trace.go:171","msg":"trace[1031027484] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1012; }","duration":"737.877466ms","start":"2026-03-14T07:02:58.086637Z","end":"2026-03-14T07:02:58.824515Z","steps":["trace[1031027484] 'read index received' (duration: 222.038359ms)","trace[1031027484] 'applied index is now lower than readState.Index' (duration: 515.835867ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:02:58.82474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.06759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:02:58.824822Z","caller":"traceutil/trace.go:171","msg":"trace[845739609] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:973; }","duration":"738.201793ms","start":"2026-03-14T07:02:58.086608Z","end":"2026-03-14T07:02:58.82481Z","steps":["trace[845739609] 'agreement among raft nodes before linearized reading' (duration: 738.06306ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:58.8249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:58.086596Z","time spent":"738.291655ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-03-14T07:02:58.824852Z","caller":"traceutil/trace.go:171","msg":"trace[1579910572] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"801.376135ms","start":"2026-03-14T07:02:58.023392Z","end":"2026-03-14T07:02:58.824768Z","steps":["trace[1579910572] 'process raft request' (duration: 800.867013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:58.825671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:58.023373Z","time spent":"802.201752ms","remote":"127.0.0.1:56194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5310,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:02:58.844202Z","caller":"traceutil/trace.go:171","msg":"trace[1454481128] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"534.952098ms","start":"2026-03-14T07:02:58.30921Z","end":"2026-03-14T07:02:58.844162Z","steps":["trace[1454481128] 'process raft request' (duration: 534.772334ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:58.844276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.103282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-14T07:02:58.844348Z","caller":"traceutil/trace.go:171","msg":"trace[1995965476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:974; }","duration":"293.220425ms","start":"2026-03-14T07:02:58.551108Z","end":"2026-03-14T07:02:58.844328Z","steps":["trace[1995965476] 'agreement among raft nodes before linearized reading' (duration: 293.030351ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:58.844335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.151892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-14T07:02:58.844374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:58.30919Z","time spent":"535.096101ms","remote":"127.0.0.1:56026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:02:58.844424Z","caller":"traceutil/trace.go:171","msg":"trace[846832411] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:974; }","duration":"292.271574ms","start":"2026-03-14T07:02:58.552137Z","end":"2026-03-14T07:02:58.844408Z","steps":["trace[846832411] 'agreement among raft nodes before linearized reading' (duration: 291.985068ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:58.844517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.896159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-14T07:02:58.844548Z","caller":"traceutil/trace.go:171","msg":"trace[979038710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:974; }","duration":"293.935239ms","start":"2026-03-14T07:02:58.550603Z","end":"2026-03-14T07:02:58.844539Z","steps":["trace[979038710] 'agreement among raft nodes before linearized reading' (duration: 293.853478ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:58.844657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.560341ms","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-14T07:02:58.844706Z","caller":"traceutil/trace.go:171","msg":"trace[1774516942] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:974; }","duration":"299.633623ms","start":"2026-03-14T07:02:58.545063Z","end":"2026-03-14T07:02:58.844697Z","steps":["trace[1774516942] 'agreement among raft nodes before linearized reading' (duration: 299.52844ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:59.013478Z","caller":"traceutil/trace.go:171","msg":"trace[412779365] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"163.6658ms","start":"2026-03-14T07:02:58.849609Z","end":"2026-03-14T07:02:59.013275Z","steps":["trace[412779365] 'process raft request' (duration: 163.177379ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:59.014098Z","caller":"traceutil/trace.go:171","msg":"trace[117461699] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"164.406586ms","start":"2026-03-14T07:02:58.849673Z","end":"2026-03-14T07:02:59.01408Z","steps":["trace[117461699] 'process raft request' (duration: 163.272582ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:59.014403Z","caller":"traceutil/trace.go:171","msg":"trace[1896138913] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"164.664431ms","start":"2026-03-14T07:02:58.8497Z","end":"2026-03-14T07:02:59.014364Z","steps":["trace[1896138913] 'process raft request' (duration: 163.308752ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:59.01461Z","caller":"traceutil/trace.go:171","msg":"trace[1494816218] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1015; }","duration":"115.93027ms","start":"2026-03-14T07:02:58.898659Z","end":"2026-03-14T07:02:59.014589Z","steps":["trace[1494816218] 'read index received' (duration: 114.0551ms)","trace[1494816218] 'applied index is now lower than readState.Index' (duration: 1.87362ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:02:59.014653Z","caller":"traceutil/trace.go:171","msg":"trace[742764943] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"163.753602ms","start":"2026-03-14T07:02:58.850881Z","end":"2026-03-14T07:02:59.014635Z","steps":["trace[742764943] 'process raft request' (duration: 163.586878ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:59.014712Z","caller":"traceutil/trace.go:171","msg":"trace[227965171] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"164.808104ms","start":"2026-03-14T07:02:58.849895Z","end":"2026-03-14T07:02:59.014703Z","steps":["trace[227965171] 'process raft request' (duration: 163.246711ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:59.014734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.046173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-03-14T07:02:59.016943Z","caller":"traceutil/trace.go:171","msg":"trace[226088618] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:980; }","duration":"118.313831ms","start":"2026-03-14T07:02:58.898609Z","end":"2026-03-14T07:02:59.016922Z","steps":["trace[226088618] 'agreement among raft nodes before linearized reading' (duration: 116.046182ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:59.477633Z","caller":"traceutil/trace.go:171","msg":"trace[891394690] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"225.997504ms","start":"2026-03-14T07:02:59.251615Z","end":"2026-03-14T07:02:59.477612Z","steps":["trace[891394690] 'process raft request' (duration: 225.80147ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:59.477548Z","caller":"traceutil/trace.go:171","msg":"trace[2128106315] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1020; }","duration":"221.794994ms","start":"2026-03-14T07:02:59.255735Z","end":"2026-03-14T07:02:59.47753Z","steps":["trace[2128106315] 'read index received' (duration: 221.62547ms)","trace[2128106315] 'applied index is now lower than readState.Index' (duration: 169.024µs)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:02:59.477859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.504823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-14T07:02:59.477884Z","caller":"traceutil/trace.go:171","msg":"trace[140251478] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:981; }","duration":"212.594735ms","start":"2026-03-14T07:02:59.265284Z","end":"2026-03-14T07:02:59.477878Z","steps":["trace[140251478] 'agreement among raft nodes before linearized reading' (duration: 212.533014ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:59.477995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.268513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-14T07:02:59.478014Z","caller":"traceutil/trace.go:171","msg":"trace[1192957677] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:981; }","duration":"222.335546ms","start":"2026-03-14T07:02:59.255673Z","end":"2026-03-14T07:02:59.478009Z","steps":["trace[1192957677] 'agreement among raft nodes before linearized reading' (duration: 222.116691ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:01.551303Z","caller":"traceutil/trace.go:171","msg":"trace[1631393882] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"527.046758ms","start":"2026-03-14T07:03:01.024223Z","end":"2026-03-14T07:03:01.55127Z","steps":["trace[1631393882] 'process raft request' (duration: 526.848074ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:01.551561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:01.024209Z","time spent":"527.200181ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:03:01.9858Z","caller":"traceutil/trace.go:171","msg":"trace[154678686] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"960.101149ms","start":"2026-03-14T07:03:01.025658Z","end":"2026-03-14T07:03:01.985759Z","steps":["trace[154678686] 'process raft request' (duration: 959.862934ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:01.986013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:01.02564Z","time spent":"960.257013ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:03:01.986306Z","caller":"traceutil/trace.go:171","msg":"trace[1094044777] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"960.470987ms","start":"2026-03-14T07:03:01.025807Z","end":"2026-03-14T07:03:01.986278Z","steps":["trace[1094044777] 'process raft request' (duration: 960.247012ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:01.986874Z","caller":"traceutil/trace.go:171","msg":"trace[491672958] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"960.450876ms","start":"2026-03-14T07:03:01.025761Z","end":"2026-03-14T07:03:01.986212Z","steps":["trace[491672958] 'process raft request' (duration: 959.945776ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:01.986963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:01.025801Z","time spent":"961.0973ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:03:01.987108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:01.025754Z","time spent":"961.217612ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:03:01.987147Z","caller":"traceutil/trace.go:171","msg":"trace[354441618] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1021; }","duration":"539.427265ms","start":"2026-03-14T07:03:01.447643Z","end":"2026-03-14T07:03:01.987071Z","steps":["trace[354441618] 'read index received' (duration: 103.480332ms)","trace[354441618] 'applied index is now lower than readState.Index' (duration: 435.944703ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:03:01.987383Z","caller":"traceutil/trace.go:171","msg":"trace[1088890183] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"961.163072ms","start":"2026-03-14T07:03:01.026174Z","end":"2026-03-14T07:03:01.987337Z","steps":["trace[1088890183] 'process raft request' (duration: 959.995266ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:01.987538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:01.026157Z","time spent":"961.292966ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:03:01.987966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.329415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:03:01.988046Z","caller":"traceutil/trace.go:171","msg":"trace[2042737851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:986; }","duration":"540.442677ms","start":"2026-03-14T07:03:01.447586Z","end":"2026-03-14T07:03:01.988029Z","steps":["trace[2042737851] 'agreement among raft nodes before linearized reading' (duration: 539.66914ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:01.988085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:01.447566Z","time spent":"540.510599ms","remote":"127.0.0.1:46568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-14T07:03:02.186112Z","caller":"traceutil/trace.go:171","msg":"trace[559494070] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"531.392922ms","start":"2026-03-14T07:03:01.654693Z","end":"2026-03-14T07:03:02.186086Z","steps":["trace[559494070] 'process raft request' (duration: 531.190077ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:02.18627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:01.654665Z","time spent":"531.523925ms","remote":"127.0.0.1:56194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5736,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:03:02.186888Z","caller":"traceutil/trace.go:171","msg":"trace[429517046] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1027; }","duration":"199.644806ms","start":"2026-03-14T07:03:01.987191Z","end":"2026-03-14T07:03:02.186836Z","steps":["trace[429517046] 'read index received' (duration: 199.622616ms)","trace[429517046] 'applied index is now lower than readState.Index' (duration: 16.74µs)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:03:02.188407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.16488ms","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-14T07:03:02.188464Z","caller":"traceutil/trace.go:171","msg":"trace[2005544312] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:987; }","duration":"323.273573ms","start":"2026-03-14T07:03:01.865176Z","end":"2026-03-14T07:03:02.18845Z","steps":["trace[2005544312] 'agreement among raft nodes before linearized reading' (duration: 323.13294ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:02.188504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:01.865157Z","time spent":"323.338804ms","remote":"127.0.0.1:56120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-03-14T07:03:02.188621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.901689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-14T07:03:02.188719Z","caller":"traceutil/trace.go:171","msg":"trace[1099510726] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:987; }","duration":"702.089983ms","start":"2026-03-14T07:03:01.486605Z","end":"2026-03-14T07:03:02.188695Z","steps":["trace[1099510726] 'agreement among raft nodes before linearized reading' (duration: 701.852228ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:02.188773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:01.486581Z","time spent":"702.180766ms","remote":"127.0.0.1:56562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-03-14T07:03:02.190899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.382532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:03:02.191022Z","caller":"traceutil/trace.go:171","msg":"trace[1278169256] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:987; }","duration":"200.541685ms","start":"2026-03-14T07:03:01.99045Z","end":"2026-03-14T07:03:02.190991Z","steps":["trace[1278169256] 'agreement among raft nodes before linearized reading' (duration: 196.594941ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:03.505423Z","caller":"traceutil/trace.go:171","msg":"trace[1721695277] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"358.860422ms","start":"2026-03-14T07:03:03.146542Z","end":"2026-03-14T07:03:03.505403Z","steps":["trace[1721695277] 'process raft request' (duration: 358.743698ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:03.505562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:03.146516Z","time spent":"358.985814ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:03:03.844985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.982958ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-14T07:03:03.845086Z","caller":"traceutil/trace.go:171","msg":"trace[1843801021] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1028; }","duration":"398.341862ms","start":"2026-03-14T07:03:03.446727Z","end":"2026-03-14T07:03:03.845069Z","steps":["trace[1843801021] 'read index received' (duration: 58.797918ms)","trace[1843801021] 'applied index is now lower than readState.Index' (duration: 339.542464ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:03:03.845212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.477795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:03:03.845268Z","caller":"traceutil/trace.go:171","msg":"trace[1449059128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:988; }","duration":"398.577997ms","start":"2026-03-14T07:03:03.446675Z","end":"2026-03-14T07:03:03.845253Z","steps":["trace[1449059128] 'agreement among raft nodes before linearized reading' (duration: 398.474395ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:03.845313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:03.446654Z","time spent":"398.650719ms","remote":"127.0.0.1:46568","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-14T07:03:03.845313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.584595ms","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-14T07:03:03.845517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.027067ms","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-14T07:03:03.845675Z","caller":"traceutil/trace.go:171","msg":"trace[139540797] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:988; }","duration":"201.292382ms","start":"2026-03-14T07:03:03.64435Z","end":"2026-03-14T07:03:03.845642Z","steps":["trace[139540797] 'agreement among raft nodes before linearized reading' (duration: 200.973195ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:03.845458Z","caller":"traceutil/trace.go:171","msg":"trace[1567694479] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:988; }","duration":"288.742928ms","start":"2026-03-14T07:03:03.556668Z","end":"2026-03-14T07:03:03.845411Z","steps":["trace[1567694479] 'agreement among raft nodes before linearized reading' (duration: 288.507703ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:03.845359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.933563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:03:03.845924Z","caller":"traceutil/trace.go:171","msg":"trace[133565273] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:988; }","duration":"276.537856ms","start":"2026-03-14T07:03:03.569364Z","end":"2026-03-14T07:03:03.845902Z","steps":["trace[133565273] 'agreement among raft nodes before linearized reading' (duration: 275.970854ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:04.042381Z","caller":"traceutil/trace.go:171","msg":"trace[275397631] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"191.929369ms","start":"2026-03-14T07:03:03.850429Z","end":"2026-03-14T07:03:04.042358Z","steps":["trace[275397631] 'process raft request' (duration: 191.309656ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:05.49079Z","caller":"traceutil/trace.go:171","msg":"trace[1483085820] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"182.605469ms","start":"2026-03-14T07:03:05.308149Z","end":"2026-03-14T07:03:05.490754Z","steps":["trace[1483085820] 'process raft request' (duration: 166.582153ms)","trace[1483085820] 'compare' (duration: 15.854023ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:03:05.490752Z","caller":"traceutil/trace.go:171","msg":"trace[1675346026] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1038; }","duration":"169.627489ms","start":"2026-03-14T07:03:05.321098Z","end":"2026-03-14T07:03:05.490726Z","steps":["trace[1675346026] 'read index received' (duration: 153.648253ms)","trace[1675346026] 'applied index is now lower than readState.Index' (duration: 15.977916ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:03:05.491336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.218211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-03-14T07:03:05.491408Z","caller":"traceutil/trace.go:171","msg":"trace[1068000764] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:998; }","duration":"170.350924ms","start":"2026-03-14T07:03:05.321044Z","end":"2026-03-14T07:03:05.491395Z","steps":["trace[1068000764] 'agreement among raft nodes before linearized reading' (duration: 169.728501ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:05.49136Z","caller":"traceutil/trace.go:171","msg":"trace[1245754096] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"168.386432ms","start":"2026-03-14T07:03:05.322961Z","end":"2026-03-14T07:03:05.491347Z","steps":["trace[1245754096] 'process raft request' (duration: 168.215118ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:07.392927Z","caller":"traceutil/trace.go:171","msg":"trace[1147164377] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"218.700337ms","start":"2026-03-14T07:03:07.1742Z","end":"2026-03-14T07:03:07.3929Z","steps":["trace[1147164377] 'process raft request' (duration: 150.494556ms)","trace[1147164377] 'compare' (duration: 68.090459ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:03:12.55514Z","caller":"traceutil/trace.go:171","msg":"trace[569678864] linearizableReadLoop","detail":"{readStateIndex:1077; appliedIndex:1076; }","duration":"423.174018ms","start":"2026-03-14T07:03:12.131942Z","end":"2026-03-14T07:03:12.555116Z","steps":["trace[569678864] 'read index received' (duration: 422.931602ms)","trace[569678864] 'applied index is now lower than readState.Index' (duration: 241.065µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:03:12.555342Z","caller":"traceutil/trace.go:171","msg":"trace[1917908208] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"884.05386ms","start":"2026-03-14T07:03:11.671261Z","end":"2026-03-14T07:03:12.555314Z","steps":["trace[1917908208] 'process raft request' (duration: 883.696042ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:12.555631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.675708ms","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-14T07:03:12.555688Z","caller":"traceutil/trace.go:171","msg":"trace[1701324326] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1035; }","duration":"423.7869ms","start":"2026-03-14T07:03:12.131887Z","end":"2026-03-14T07:03:12.555674Z","steps":["trace[1701324326] 'agreement among raft nodes before linearized reading' (duration: 423.510234ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:12.555737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:12.131866Z","time spent":"423.859701ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-14T07:03:12.555764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:11.671245Z","time spent":"884.340425ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-14T07:03:12.555979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.670075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:03:12.556022Z","caller":"traceutil/trace.go:171","msg":"trace[2003164500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1035; }","duration":"109.790387ms","start":"2026-03-14T07:03:12.446221Z","end":"2026-03-14T07:03:12.556011Z","steps":["trace[2003164500] 'agreement among raft nodes before linearized reading' (duration: 109.726196ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:12.556066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.743733ms","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-14T07:03:12.556121Z","caller":"traceutil/trace.go:171","msg":"trace[530533275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1035; }","duration":"406.858615ms","start":"2026-03-14T07:03:12.149245Z","end":"2026-03-14T07:03:12.556104Z","steps":["trace[530533275] 'agreement among raft nodes before linearized reading' (duration: 406.643581ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:12.556358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:12.149217Z","time spent":"407.126702ms","remote":"127.0.0.1:56294","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-14T07:03:12.556402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.877786ms","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:507"} {"level":"info","ts":"2026-03-14T07:03:12.556455Z","caller":"traceutil/trace.go:171","msg":"trace[1595302325] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1035; }","duration":"158.932258ms","start":"2026-03-14T07:03:12.397506Z","end":"2026-03-14T07:03:12.556438Z","steps":["trace[1595302325] 'agreement among raft nodes before linearized reading' (duration: 158.796695ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:12.556447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.09264ms","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-14T07:03:12.556511Z","caller":"traceutil/trace.go:171","msg":"trace[667678318] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1035; }","duration":"407.206013ms","start":"2026-03-14T07:03:12.149289Z","end":"2026-03-14T07:03:12.556495Z","steps":["trace[667678318] 'agreement among raft nodes before linearized reading' (duration: 406.403746ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:12.55656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:12.149266Z","time spent":"407.285145ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-14T07:03:12.556616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.95481ms","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-14T07:03:12.556762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.825543ms","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-14T07:03:12.556847Z","caller":"traceutil/trace.go:171","msg":"trace[1042631444] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1035; }","duration":"404.954755ms","start":"2026-03-14T07:03:12.151878Z","end":"2026-03-14T07:03:12.556832Z","steps":["trace[1042631444] 'agreement among raft nodes before linearized reading' (duration: 403.798189ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:12.556708Z","caller":"traceutil/trace.go:171","msg":"trace[292639547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1035; }","duration":"415.085824ms","start":"2026-03-14T07:03:12.141607Z","end":"2026-03-14T07:03:12.556693Z","steps":["trace[292639547] 'agreement among raft nodes before linearized reading' (duration: 414.057981ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:12.556925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:12.151856Z","time spent":"405.054678ms","remote":"127.0.0.1:56294","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-14T07:03:12.557073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:12.141588Z","time spent":"415.387521ms","remote":"127.0.0.1:56294","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-14T07:03:13.062857Z","caller":"traceutil/trace.go:171","msg":"trace[1181954500] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"498.706517ms","start":"2026-03-14T07:03:12.564126Z","end":"2026-03-14T07:03:13.062832Z","steps":["trace[1181954500] 'process raft request' (duration: 490.97605ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:13.063009Z","caller":"traceutil/trace.go:171","msg":"trace[1258010697] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"498.797489ms","start":"2026-03-14T07:03:12.564196Z","end":"2026-03-14T07:03:13.062993Z","steps":["trace[1258010697] 'process raft request' (duration: 498.582115ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:13.063095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:12.564188Z","time spent":"498.867691ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:03:13.063003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:12.564102Z","time spent":"498.83338ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:03:13.063649Z","caller":"traceutil/trace.go:171","msg":"trace[1672051304] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"499.200937ms","start":"2026-03-14T07:03:12.564412Z","end":"2026-03-14T07:03:13.063613Z","steps":["trace[1672051304] 'process raft request' (duration: 498.534663ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:13.063702Z","caller":"traceutil/trace.go:171","msg":"trace[430368573] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"497.999352ms","start":"2026-03-14T07:03:12.56568Z","end":"2026-03-14T07:03:13.063679Z","steps":["trace[430368573] 'process raft request' (duration: 497.808367ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:13.063733Z","caller":"traceutil/trace.go:171","msg":"trace[2144069016] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"496.385868ms","start":"2026-03-14T07:03:12.567315Z","end":"2026-03-14T07:03:13.063701Z","steps":["trace[2144069016] 'process raft request' (duration: 496.279855ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:13.063805Z","caller":"traceutil/trace.go:171","msg":"trace[636315156] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"493.447984ms","start":"2026-03-14T07:03:12.570345Z","end":"2026-03-14T07:03:13.063793Z","steps":["trace[636315156] 'process raft request' (duration: 493.333252ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:13.063908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:12.56567Z","time spent":"498.115895ms","remote":"127.0.0.1:56082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":911,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-14T07:03:13.063719Z","caller":"traceutil/trace.go:171","msg":"trace[2115255551] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"495.14194ms","start":"2026-03-14T07:03:12.568564Z","end":"2026-03-14T07:03:13.063706Z","steps":["trace[2115255551] 'process raft request' (duration: 495.083858ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:13.064101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:12.568542Z","time spent":"495.475858ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:03:13.064029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:12.570333Z","time spent":"493.515536ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:03:13.063956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:12.564397Z","time spent":"499.342112ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:03:13.06451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:12.567302Z","time spent":"496.571292ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:03:13.089438Z","caller":"traceutil/trace.go:171","msg":"trace[1392371480] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"487.425844ms","start":"2026-03-14T07:03:12.601996Z","end":"2026-03-14T07:03:13.089422Z","steps":["trace[1392371480] 'process raft request' (duration: 487.350032ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:13.089532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:12.601974Z","time spent":"487.516495ms","remote":"127.0.0.1:56082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":781,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-14T07:03:13.455645Z","caller":"traceutil/trace.go:171","msg":"trace[132208076] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"101.293075ms","start":"2026-03-14T07:03:13.354319Z","end":"2026-03-14T07:03:13.455612Z","steps":["trace[132208076] 'process raft request' (duration: 101.015069ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:13.548055Z","caller":"traceutil/trace.go:171","msg":"trace[288618302] linearizableReadLoop","detail":"{readStateIndex:1091; appliedIndex:1088; }","duration":"100.860255ms","start":"2026-03-14T07:03:13.447175Z","end":"2026-03-14T07:03:13.548035Z","steps":["trace[288618302] 'read index received' (duration: 8.194707ms)","trace[288618302] 'applied index is now lower than readState.Index' (duration: 92.662848ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:03:13.548275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.081591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:03:13.548356Z","caller":"traceutil/trace.go:171","msg":"trace[302695949] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1049; }","duration":"101.215994ms","start":"2026-03-14T07:03:13.447123Z","end":"2026-03-14T07:03:13.548339Z","steps":["trace[302695949] 'agreement among raft nodes before linearized reading' (duration: 101.019969ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:13.54871Z","caller":"traceutil/trace.go:171","msg":"trace[1843388973] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"155.3597ms","start":"2026-03-14T07:03:13.392614Z","end":"2026-03-14T07:03:13.547973Z","steps":["trace[1843388973] 'process raft request' (duration: 155.163546ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:59.566197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.673953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:03:59.566296Z","caller":"traceutil/trace.go:171","msg":"trace[510240510] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1245; }","duration":"119.822746ms","start":"2026-03-14T07:03:59.446451Z","end":"2026-03-14T07:03:59.566273Z","steps":["trace[510240510] 'range keys from in-memory index tree' (duration: 119.51646ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:04:45.70458Z","caller":"traceutil/trace.go:171","msg":"trace[1750759423] linearizableReadLoop","detail":"{readStateIndex:1491; appliedIndex:1490; }","duration":"134.617455ms","start":"2026-03-14T07:04:45.569944Z","end":"2026-03-14T07:04:45.704562Z","steps":["trace[1750759423] 'read index received' (duration: 134.529763ms)","trace[1750759423] 'applied index is now lower than readState.Index' (duration: 86.802µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:04:45.704696Z","caller":"traceutil/trace.go:171","msg":"trace[9919905] transaction","detail":"{read_only:false; response_revision:1430; number_of_response:1; }","duration":"167.892181ms","start":"2026-03-14T07:04:45.536771Z","end":"2026-03-14T07:04:45.704663Z","steps":["trace[9919905] 'process raft request' (duration: 167.653416ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:04:45.704811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.842209ms","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-14T07:04:45.704855Z","caller":"traceutil/trace.go:171","msg":"trace[476409180] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1430; }","duration":"134.944402ms","start":"2026-03-14T07:04:45.569899Z","end":"2026-03-14T07:04:45.704844Z","steps":["trace[476409180] 'agreement among raft nodes before linearized reading' (duration: 134.787418ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:04:45.704866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.345949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-14T07:04:45.704935Z","caller":"traceutil/trace.go:171","msg":"trace[756852955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1430; }","duration":"107.46304ms","start":"2026-03-14T07:04:45.597455Z","end":"2026-03-14T07:04:45.704918Z","steps":["trace[756852955] 'agreement among raft nodes before linearized reading' (duration: 107.270976ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:04:45.704916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.802756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-14T07:04:45.705017Z","caller":"traceutil/trace.go:171","msg":"trace[1603134425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1430; }","duration":"107.009552ms","start":"2026-03-14T07:04:45.597991Z","end":"2026-03-14T07:04:45.705Z","steps":["trace[1603134425] 'agreement among raft nodes before linearized reading' (duration: 106.744355ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:04:46.295488Z","caller":"traceutil/trace.go:171","msg":"trace[1644027618] transaction","detail":"{read_only:false; response_revision:1436; number_of_response:1; }","duration":"120.498171ms","start":"2026-03-14T07:04:46.174963Z","end":"2026-03-14T07:04:46.295461Z","steps":["trace[1644027618] 'process raft request' (duration: 120.349988ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:04:48.008982Z","caller":"traceutil/trace.go:171","msg":"trace[2039466079] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"146.04524ms","start":"2026-03-14T07:04:47.862885Z","end":"2026-03-14T07:04:48.00893Z","steps":["trace[2039466079] 'process raft request' (duration: 85.387006ms)","trace[2039466079] 'compare' (duration: 60.535652ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:00.165969Z","caller":"traceutil/trace.go:171","msg":"trace[1983556451] transaction","detail":"{read_only:false; response_revision:1736; number_of_response:1; }","duration":"114.717913ms","start":"2026-03-14T07:06:00.05122Z","end":"2026-03-14T07:06:00.165938Z","steps":["trace[1983556451] 'process raft request' (duration: 114.395687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:04.477066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.18266ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:06:04.477441Z","caller":"traceutil/trace.go:171","msg":"trace[1104297816] transaction","detail":"{read_only:false; response_revision:1761; number_of_response:1; }","duration":"165.661818ms","start":"2026-03-14T07:06:04.311734Z","end":"2026-03-14T07:06:04.477396Z","steps":["trace[1104297816] 'process raft request' (duration: 61.0508ms)","trace[1104297816] 'compare' (duration: 104.054847ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:04.477832Z","caller":"traceutil/trace.go:171","msg":"trace[356457115] transaction","detail":"{read_only:false; response_revision:1762; number_of_response:1; }","duration":"166.003624ms","start":"2026-03-14T07:06:04.311809Z","end":"2026-03-14T07:06:04.477813Z","steps":["trace[356457115] 'process raft request' (duration: 165.443834ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:04.47816Z","caller":"traceutil/trace.go:171","msg":"trace[539077890] transaction","detail":"{read_only:false; response_revision:1763; number_of_response:1; }","duration":"165.673088ms","start":"2026-03-14T07:06:04.312443Z","end":"2026-03-14T07:06:04.478116Z","steps":["trace[539077890] 'process raft request' (duration: 165.315141ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:05.031418Z","caller":"traceutil/trace.go:171","msg":"trace[1958324242] transaction","detail":"{read_only:false; response_revision:1764; number_of_response:1; }","duration":"278.398424ms","start":"2026-03-14T07:06:04.752983Z","end":"2026-03-14T07:06:05.031382Z","steps":["trace[1958324242] 'process raft request' (duration: 278.144658ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:06.6329Z","caller":"traceutil/trace.go:171","msg":"trace[1471177213] transaction","detail":"{read_only:false; response_revision:1771; number_of_response:1; }","duration":"144.886097ms","start":"2026-03-14T07:06:06.487985Z","end":"2026-03-14T07:06:06.632871Z","steps":["trace[1471177213] 'process raft request' (duration: 144.816537ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:06.632921Z","caller":"traceutil/trace.go:171","msg":"trace[742598829] transaction","detail":"{read_only:false; response_revision:1770; number_of_response:1; }","duration":"145.345112ms","start":"2026-03-14T07:06:06.487546Z","end":"2026-03-14T07:06:06.632891Z","steps":["trace[742598829] 'process raft request' (duration: 101.631017ms)","trace[742598829] 'compare' (duration: 43.481178ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:08.77116Z","caller":"traceutil/trace.go:171","msg":"trace[341135363] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"131.732652ms","start":"2026-03-14T07:06:08.639405Z","end":"2026-03-14T07:06:08.771138Z","steps":["trace[341135363] 'process raft request' (duration: 131.606783ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:08.77381Z","caller":"traceutil/trace.go:171","msg":"trace[953566129] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"133.920841ms","start":"2026-03-14T07:06:08.639866Z","end":"2026-03-14T07:06:08.773787Z","steps":["trace[953566129] 'process raft request' (duration: 133.843882ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:10.217079Z","caller":"traceutil/trace.go:171","msg":"trace[1139710543] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"132.150548ms","start":"2026-03-14T07:06:10.084889Z","end":"2026-03-14T07:06:10.21704Z","steps":["trace[1139710543] 'process raft request' (duration: 131.9934ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:10.922221Z","caller":"traceutil/trace.go:171","msg":"trace[1278673276] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"100.055312ms","start":"2026-03-14T07:06:10.822145Z","end":"2026-03-14T07:06:10.922201Z","steps":["trace[1278673276] 'process raft request' (duration: 61.069122ms)","trace[1278673276] 'compare' (duration: 38.652003ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:11.510936Z","caller":"traceutil/trace.go:171","msg":"trace[1477608447] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"102.052124ms","start":"2026-03-14T07:06:11.408851Z","end":"2026-03-14T07:06:11.510903Z","steps":["trace[1477608447] 'process raft request' (duration: 65.223942ms)","trace[1477608447] 'compare' (duration: 36.675993ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:13.364653Z","caller":"traceutil/trace.go:171","msg":"trace[1138104000] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"210.340269ms","start":"2026-03-14T07:06:13.154288Z","end":"2026-03-14T07:06:13.364628Z","steps":["trace[1138104000] 'process raft request' (duration: 210.21443ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:13.364839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.768468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-14T07:06:13.364907Z","caller":"traceutil/trace.go:171","msg":"trace[2060369251] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1809; }","duration":"254.922226ms","start":"2026-03-14T07:06:13.109971Z","end":"2026-03-14T07:06:13.364893Z","steps":["trace[2060369251] 'agreement among raft nodes before linearized reading' (duration: 254.763638ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:13.364682Z","caller":"traceutil/trace.go:171","msg":"trace[868853838] linearizableReadLoop","detail":"{readStateIndex:1888; appliedIndex:1887; }","duration":"254.57525ms","start":"2026-03-14T07:06:13.110049Z","end":"2026-03-14T07:06:13.364624Z","steps":["trace[868853838] 'read index received' (duration: 182.332555ms)","trace[868853838] 'applied index is now lower than readState.Index' (duration: 72.239595ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:06:13.365484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.568963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2723"} {"level":"info","ts":"2026-03-14T07:06:13.365524Z","caller":"traceutil/trace.go:171","msg":"trace[325975595] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1809; }","duration":"148.640493ms","start":"2026-03-14T07:06:13.216872Z","end":"2026-03-14T07:06:13.365512Z","steps":["trace[325975595] 'agreement among raft nodes before linearized reading' (duration: 148.495484ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:13.365864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.371319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-03-14T07:06:13.365902Z","caller":"traceutil/trace.go:171","msg":"trace[2126098955] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1809; }","duration":"213.448889ms","start":"2026-03-14T07:06:13.152444Z","end":"2026-03-14T07:06:13.365893Z","steps":["trace[2126098955] 'agreement among raft nodes before linearized reading' (duration: 213.32309ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:13.803066Z","caller":"traceutil/trace.go:171","msg":"trace[1656415539] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"146.492052ms","start":"2026-03-14T07:06:13.656527Z","end":"2026-03-14T07:06:13.803019Z","steps":["trace[1656415539] 'process raft request' (duration: 116.301177ms)","trace[1656415539] 'compare' (duration: 30.010947ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:19.050801Z","caller":"traceutil/trace.go:171","msg":"trace[1257533140] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"143.140215ms","start":"2026-03-14T07:06:18.907629Z","end":"2026-03-14T07:06:19.05077Z","steps":["trace[1257533140] 'process raft request' (duration: 60.058712ms)","trace[1257533140] 'compare' (duration: 82.908244ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:19.50427Z","caller":"traceutil/trace.go:171","msg":"trace[978683928] linearizableReadLoop","detail":"{readStateIndex:1920; appliedIndex:1919; }","duration":"171.316467ms","start":"2026-03-14T07:06:19.332929Z","end":"2026-03-14T07:06:19.504246Z","steps":["trace[978683928] 'read index received' (duration: 168.102018ms)","trace[978683928] 'applied index is now lower than readState.Index' (duration: 3.213299ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:19.504366Z","caller":"traceutil/trace.go:171","msg":"trace[382994384] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"449.847787ms","start":"2026-03-14T07:06:19.054474Z","end":"2026-03-14T07:06:19.504322Z","steps":["trace[382994384] 'process raft request' (duration: 446.578318ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:19.504432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.491025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T07:06:19.504673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:06:19.054455Z","time spent":"450.134284ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:06:19.504686Z","caller":"traceutil/trace.go:171","msg":"trace[853669766] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1839; }","duration":"171.760392ms","start":"2026-03-14T07:06:19.332877Z","end":"2026-03-14T07:06:19.504638Z","steps":["trace[853669766] 'agreement among raft nodes before linearized reading' (duration: 171.489875ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:24.005696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.839694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:06:24.005806Z","caller":"traceutil/trace.go:171","msg":"trace[597156182] linearizableReadLoop","detail":"{readStateIndex:1943; appliedIndex:1942; }","duration":"201.262133ms","start":"2026-03-14T07:06:23.804528Z","end":"2026-03-14T07:06:24.00579Z","steps":["trace[597156182] 'read index received' (duration: 64.265661ms)","trace[597156182] 'applied index is now lower than readState.Index' (duration: 136.995352ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:24.00585Z","caller":"traceutil/trace.go:171","msg":"trace[1546788426] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"267.791754ms","start":"2026-03-14T07:06:23.738021Z","end":"2026-03-14T07:06:24.005813Z","steps":["trace[1546788426] 'process raft request' (duration: 130.744572ms)","trace[1546788426] 'compare' (duration: 136.718955ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:06:24.005981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.454591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-14T07:06:24.006013Z","caller":"traceutil/trace.go:171","msg":"trace[1212184011] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1861; }","duration":"201.518391ms","start":"2026-03-14T07:06:23.804486Z","end":"2026-03-14T07:06:24.006005Z","steps":["trace[1212184011] 'agreement among raft nodes before linearized reading' (duration: 201.386552ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:24.006152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.461695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-14T07:06:24.006231Z","caller":"traceutil/trace.go:171","msg":"trace[431429140] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1861; }","duration":"151.613533ms","start":"2026-03-14T07:06:23.854596Z","end":"2026-03-14T07:06:24.00621Z","steps":["trace[431429140] 'agreement among raft nodes before linearized reading' (duration: 151.401055ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:24.006241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.807057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-14T07:06:24.006278Z","caller":"traceutil/trace.go:171","msg":"trace[527182658] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1861; }","duration":"143.846816ms","start":"2026-03-14T07:06:23.86242Z","end":"2026-03-14T07:06:24.006267Z","steps":["trace[527182658] 'agreement among raft nodes before linearized reading' (duration: 143.763577ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:24.79273Z","caller":"traceutil/trace.go:171","msg":"trace[1649538895] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"189.718773ms","start":"2026-03-14T07:06:24.602985Z","end":"2026-03-14T07:06:24.792704Z","steps":["trace[1649538895] 'process raft request' (duration: 189.565615ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:24.998745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.604691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:06:24.998819Z","caller":"traceutil/trace.go:171","msg":"trace[1317238067] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1865; }","duration":"165.74148ms","start":"2026-03-14T07:06:24.83306Z","end":"2026-03-14T07:06:24.998802Z","steps":["trace[1317238067] 'range keys from in-memory index tree' (duration: 165.514892ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:24.99907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.310121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-14T07:06:24.99914Z","caller":"traceutil/trace.go:171","msg":"trace[759915827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1865; }","duration":"138.423259ms","start":"2026-03-14T07:06:24.860698Z","end":"2026-03-14T07:06:24.999122Z","steps":["trace[759915827] 'range keys from in-memory index tree' (duration: 138.085443ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:25.55451Z","caller":"traceutil/trace.go:171","msg":"trace[751173216] linearizableReadLoop","detail":"{readStateIndex:1949; appliedIndex:1948; }","duration":"280.75208ms","start":"2026-03-14T07:06:25.273737Z","end":"2026-03-14T07:06:25.554489Z","steps":["trace[751173216] 'read index received' (duration: 280.557022ms)","trace[751173216] 'applied index is now lower than readState.Index' (duration: 194.238µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:25.554634Z","caller":"traceutil/trace.go:171","msg":"trace[1917271160] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"474.484395ms","start":"2026-03-14T07:06:25.080127Z","end":"2026-03-14T07:06:25.554611Z","steps":["trace[1917271160] 'process raft request' (duration: 474.240167ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:25.554679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.75485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:06:25.554712Z","caller":"traceutil/trace.go:171","msg":"trace[254522409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1867; }","duration":"108.835559ms","start":"2026-03-14T07:06:25.445867Z","end":"2026-03-14T07:06:25.554703Z","steps":["trace[254522409] 'agreement among raft nodes before linearized reading' (duration: 108.75233ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:25.554728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.978108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"warn","ts":"2026-03-14T07:06:25.554759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:06:25.080099Z","time spent":"474.576924ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:06:25.554803Z","caller":"traceutil/trace.go:171","msg":"trace[335356975] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1867; }","duration":"281.092847ms","start":"2026-03-14T07:06:25.273689Z","end":"2026-03-14T07:06:25.554782Z","steps":["trace[335356975] 'agreement among raft nodes before linearized reading' (duration: 280.916839ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:25.798147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.90541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:06:25.798374Z","caller":"traceutil/trace.go:171","msg":"trace[2085472201] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"239.186544ms","start":"2026-03-14T07:06:25.55917Z","end":"2026-03-14T07:06:25.798357Z","steps":["trace[2085472201] 'process raft request' (duration: 239.144234ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:25.798537Z","caller":"traceutil/trace.go:171","msg":"trace[1810024258] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"239.442171ms","start":"2026-03-14T07:06:25.559069Z","end":"2026-03-14T07:06:25.798511Z","steps":["trace[1810024258] 'process raft request' (duration: 72.284984ms)","trace[1810024258] 'compare' (duration: 166.585483ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:25.798582Z","caller":"traceutil/trace.go:171","msg":"trace[1897629546] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"239.464552ms","start":"2026-03-14T07:06:25.559096Z","end":"2026-03-14T07:06:25.79856Z","steps":["trace[1897629546] 'process raft request' (duration: 239.166545ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:26.150473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.403463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:06:26.150559Z","caller":"traceutil/trace.go:171","msg":"trace[2144782154] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1870; }","duration":"165.543331ms","start":"2026-03-14T07:06:25.984993Z","end":"2026-03-14T07:06:26.150536Z","steps":["trace[2144782154] 'count revisions from in-memory index tree' (duration: 165.306544ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:26.150683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.416926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-03-14T07:06:26.150767Z","caller":"traceutil/trace.go:171","msg":"trace[1516450787] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1870; }","duration":"115.545526ms","start":"2026-03-14T07:06:26.035204Z","end":"2026-03-14T07:06:26.150749Z","steps":["trace[1516450787] 'range keys from in-memory index tree' (duration: 115.236758ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:26.150832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.813973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-14T07:06:26.150907Z","caller":"traceutil/trace.go:171","msg":"trace[1386948062] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1870; }","duration":"114.916522ms","start":"2026-03-14T07:06:26.035974Z","end":"2026-03-14T07:06:26.15089Z","steps":["trace[1386948062] 'range keys from in-memory index tree' (duration: 114.680504ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:26.283272Z","caller":"traceutil/trace.go:171","msg":"trace[1353772893] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"128.223836ms","start":"2026-03-14T07:06:26.15502Z","end":"2026-03-14T07:06:26.283244Z","steps":["trace[1353772893] 'process raft request' (duration: 127.889509ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:26.87091Z","caller":"traceutil/trace.go:171","msg":"trace[483273168] linearizableReadLoop","detail":"{readStateIndex:1954; appliedIndex:1952; }","duration":"697.184085ms","start":"2026-03-14T07:06:26.173701Z","end":"2026-03-14T07:06:26.870885Z","steps":["trace[483273168] 'read index received' (duration: 109.114317ms)","trace[483273168] 'applied index is now lower than readState.Index' (duration: 588.068748ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:26.871134Z","caller":"traceutil/trace.go:171","msg":"trace[1249135642] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"714.637769ms","start":"2026-03-14T07:06:26.156459Z","end":"2026-03-14T07:06:26.871097Z","steps":["trace[1249135642] 'process raft request' (duration: 714.293033ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:26.871643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:06:26.156436Z","time spent":"715.130705ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:06:26.871261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.531191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:06:26.871841Z","caller":"traceutil/trace.go:171","msg":"trace[1183947757] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1872; }","duration":"698.150255ms","start":"2026-03-14T07:06:26.173656Z","end":"2026-03-14T07:06:26.871807Z","steps":["trace[1183947757] 'agreement among raft nodes before linearized reading' (duration: 697.527092ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:26.871847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.933744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T07:06:26.871941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:06:26.173643Z","time spent":"698.263034ms","remote":"127.0.0.1:56250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-03-14T07:06:26.871932Z","caller":"traceutil/trace.go:171","msg":"trace[1475645285] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1872; }","duration":"425.063673ms","start":"2026-03-14T07:06:26.446846Z","end":"2026-03-14T07:06:26.87191Z","steps":["trace[1475645285] 'agreement among raft nodes before linearized reading' (duration: 424.931775ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:26.872068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:06:26.446827Z","time spent":"425.222701ms","remote":"127.0.0.1:46568","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-14T07:06:27.097059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.357047ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:06:27.097262Z","caller":"traceutil/trace.go:171","msg":"trace[1227408633] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"220.192963ms","start":"2026-03-14T07:06:26.877011Z","end":"2026-03-14T07:06:27.097204Z","steps":["trace[1227408633] 'process raft request' (duration: 88.601001ms)","trace[1227408633] 'compare' (duration: 131.231134ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:27.99758Z","caller":"traceutil/trace.go:171","msg":"trace[557222214] linearizableReadLoop","detail":"{readStateIndex:1962; appliedIndex:1961; }","duration":"145.654618ms","start":"2026-03-14T07:06:27.851901Z","end":"2026-03-14T07:06:27.997556Z","steps":["trace[557222214] 'read index received' (duration: 145.526036ms)","trace[557222214] 'applied index is now lower than readState.Index' (duration: 127.462µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:27.997639Z","caller":"traceutil/trace.go:171","msg":"trace[979084856] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"155.785241ms","start":"2026-03-14T07:06:27.841814Z","end":"2026-03-14T07:06:27.997599Z","steps":["trace[979084856] 'process raft request' (duration: 155.576117ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:27.99794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.015626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2825"} {"level":"info","ts":"2026-03-14T07:06:27.997992Z","caller":"traceutil/trace.go:171","msg":"trace[576681230] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1879; }","duration":"146.118788ms","start":"2026-03-14T07:06:27.851863Z","end":"2026-03-14T07:06:27.997981Z","steps":["trace[576681230] 'agreement among raft nodes before linearized reading' (duration: 145.786851ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:36.167377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.750488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:attachdetach-controller\" ","response":"range_response_count:1 size:767"} {"level":"info","ts":"2026-03-14T07:06:36.167554Z","caller":"traceutil/trace.go:171","msg":"trace[692620541] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:attachdetach-controller; range_end:; response_count:1; response_revision:1915; }","duration":"142.973542ms","start":"2026-03-14T07:06:36.024564Z","end":"2026-03-14T07:06:36.167538Z","steps":["trace[692620541] 'range keys from in-memory index tree' (duration: 142.478622ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:36.167436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.563231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:service-controller\" ","response":"range_response_count:1 size:724"} {"level":"info","ts":"2026-03-14T07:06:36.167839Z","caller":"traceutil/trace.go:171","msg":"trace[705615457] range","detail":"{range_begin:/registry/clusterroles/system:controller:service-controller; range_end:; response_count:1; response_revision:1915; }","duration":"150.901648ms","start":"2026-03-14T07:06:36.016815Z","end":"2026-03-14T07:06:36.167716Z","steps":["trace[705615457] 'range keys from in-memory index tree' (duration: 150.471629ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:36.395817Z","caller":"traceutil/trace.go:171","msg":"trace[593045726] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"133.207108ms","start":"2026-03-14T07:06:36.262581Z","end":"2026-03-14T07:06:36.395788Z","steps":["trace[593045726] 'process raft request' (duration: 119.162763ms)","trace[593045726] 'compare' (duration: 13.582205ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:43.263012Z","caller":"traceutil/trace.go:171","msg":"trace[1368514674] linearizableReadLoop","detail":"{readStateIndex:2031; appliedIndex:2030; }","duration":"159.788505ms","start":"2026-03-14T07:06:43.103191Z","end":"2026-03-14T07:06:43.262979Z","steps":["trace[1368514674] 'read index received' (duration: 124.415953ms)","trace[1368514674] 'applied index is now lower than readState.Index' (duration: 35.369402ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:06:43.263224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.00905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/\" range_end:\"/registry/pods/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:06:43.26327Z","caller":"traceutil/trace.go:171","msg":"trace[1507568084] range","detail":"{range_begin:/registry/pods/default/; range_end:/registry/pods/default0; response_count:0; response_revision:1944; }","duration":"160.089822ms","start":"2026-03-14T07:06:43.103167Z","end":"2026-03-14T07:06:43.263257Z","steps":["trace[1507568084] 'agreement among raft nodes before linearized reading' (duration: 159.99236ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:43.263356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.961426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-node-lease/\" range_end:\"/registry/rolebindings/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:06:43.263445Z","caller":"traceutil/trace.go:171","msg":"trace[1035121338] range","detail":"{range_begin:/registry/rolebindings/kube-node-lease/; range_end:/registry/rolebindings/kube-node-lease0; response_count:0; response_revision:1944; }","duration":"107.11282ms","start":"2026-03-14T07:06:43.156311Z","end":"2026-03-14T07:06:43.263423Z","steps":["trace[1035121338] 'agreement among raft nodes before linearized reading' (duration: 106.928466ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:43.550164Z","caller":"traceutil/trace.go:171","msg":"trace[829616463] linearizableReadLoop","detail":"{readStateIndex:2033; appliedIndex:2032; }","duration":"104.978995ms","start":"2026-03-14T07:06:43.445164Z","end":"2026-03-14T07:06:43.550143Z","steps":["trace[829616463] 'read index received' (duration: 104.783841ms)","trace[829616463] 'applied index is now lower than readState.Index' (duration: 194.194µs)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:06:43.550304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.121158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:06:43.550337Z","caller":"traceutil/trace.go:171","msg":"trace[657782043] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1946; }","duration":"105.193799ms","start":"2026-03-14T07:06:43.445134Z","end":"2026-03-14T07:06:43.550328Z","steps":["trace[657782043] 'agreement among raft nodes before linearized reading' (duration: 105.127798ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:43.550284Z","caller":"traceutil/trace.go:171","msg":"trace[2109958945] transaction","detail":"{read_only:false; response_revision:1946; number_of_response:1; }","duration":"157.867135ms","start":"2026-03-14T07:06:43.392378Z","end":"2026-03-14T07:06:43.550245Z","steps":["trace[2109958945] 'process raft request' (duration: 157.62605ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:43.550483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.156538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/\" range_end:\"/registry/persistentvolumeclaims/default0\" limit:500 ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-03-14T07:06:43.550563Z","caller":"traceutil/trace.go:171","msg":"trace[542990958] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/; range_end:/registry/persistentvolumeclaims/default0; response_count:1; response_revision:1946; }","duration":"105.27051ms","start":"2026-03-14T07:06:43.445276Z","end":"2026-03-14T07:06:43.550546Z","steps":["trace[542990958] 'agreement among raft nodes before linearized reading' (duration: 105.037226ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:57.903886Z","caller":"traceutil/trace.go:171","msg":"trace[615924870] transaction","detail":"{read_only:false; response_revision:2004; number_of_response:1; }","duration":"187.827143ms","start":"2026-03-14T07:06:57.716039Z","end":"2026-03-14T07:06:57.903866Z","steps":["trace[615924870] 'process raft request' (duration: 187.70071ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:57.903917Z","caller":"traceutil/trace.go:171","msg":"trace[293252014] linearizableReadLoop","detail":"{readStateIndex:2093; appliedIndex:2093; }","duration":"184.472613ms","start":"2026-03-14T07:06:57.719394Z","end":"2026-03-14T07:06:57.903867Z","steps":["trace[293252014] 'read index received' (duration: 184.457172ms)","trace[293252014] 'applied index is now lower than readState.Index' (duration: 12.981µs)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:06:57.904189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.773509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-t59cl\" ","response":"range_response_count:1 size:13724"} {"level":"info","ts":"2026-03-14T07:06:57.904262Z","caller":"traceutil/trace.go:171","msg":"trace[1501766838] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-t59cl; range_end:; response_count:1; response_revision:2004; }","duration":"184.882811ms","start":"2026-03-14T07:06:57.71936Z","end":"2026-03-14T07:06:57.904243Z","steps":["trace[1501766838] 'agreement among raft nodes before linearized reading' (duration: 184.664837ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:58.581269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.54856ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-03-14T07:06:58.581422Z","caller":"traceutil/trace.go:171","msg":"trace[663581957] linearizableReadLoop","detail":"{readStateIndex:2095; appliedIndex:2093; }","duration":"605.782669ms","start":"2026-03-14T07:06:57.975627Z","end":"2026-03-14T07:06:58.58141Z","steps":["trace[663581957] 'read index received' (duration: 95.083066ms)","trace[663581957] 'applied index is now lower than readState.Index' (duration: 510.699063ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:06:58.581477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:06:57.905739Z","time spent":"675.735939ms","remote":"127.0.0.1:56026","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-14T07:06:58.581691Z","caller":"traceutil/trace.go:171","msg":"trace[528207149] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"671.110582ms","start":"2026-03-14T07:06:57.910574Z","end":"2026-03-14T07:06:58.581685Z","steps":["trace[528207149] 'process raft request' (duration: 670.766934ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:58.581751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:06:57.910555Z","time spent":"671.155892ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:06:58.581984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.363942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-t59cl\" ","response":"range_response_count:1 size:13724"} {"level":"info","ts":"2026-03-14T07:06:58.582004Z","caller":"traceutil/trace.go:171","msg":"trace[1801877613] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-t59cl; range_end:; response_count:1; response_revision:2005; }","duration":"606.468984ms","start":"2026-03-14T07:06:57.975529Z","end":"2026-03-14T07:06:58.581998Z","steps":["trace[1801877613] 'agreement among raft nodes before linearized reading' (duration: 606.291171ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:58.582269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:06:57.975493Z","time spent":"606.772781ms","remote":"127.0.0.1:56200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":13747,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-t59cl\" "} {"level":"warn","ts":"2026-03-14T07:06:58.582156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.791233ms","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-14T07:06:58.582177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.072346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:06:58.582386Z","caller":"traceutil/trace.go:171","msg":"trace[1389975612] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2005; }","duration":"379.018259ms","start":"2026-03-14T07:06:58.203301Z","end":"2026-03-14T07:06:58.582319Z","steps":["trace[1389975612] 'agreement among raft nodes before linearized reading' (duration: 378.819615ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:58.582492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:06:58.203279Z","time spent":"379.179123ms","remote":"127.0.0.1:56294","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-14T07:06:58.582432Z","caller":"traceutil/trace.go:171","msg":"trace[1349561522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2005; }","duration":"135.359872ms","start":"2026-03-14T07:06:58.447066Z","end":"2026-03-14T07:06:58.582426Z","steps":["trace[1349561522] 'agreement among raft nodes before linearized reading' (duration: 135.100747ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:58.582219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.961718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-03-14T07:06:58.582647Z","caller":"traceutil/trace.go:171","msg":"trace[287729933] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2005; }","duration":"370.428247ms","start":"2026-03-14T07:06:58.212213Z","end":"2026-03-14T07:06:58.582642Z","steps":["trace[287729933] 'agreement among raft nodes before linearized reading' (duration: 369.970218ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:58.582666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:06:58.212191Z","time spent":"370.469609ms","remote":"127.0.0.1:56294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-03-14T07:06:58.791457Z","caller":"traceutil/trace.go:171","msg":"trace[318181793] transaction","detail":"{read_only:false; response_revision:2007; number_of_response:1; }","duration":"204.816021ms","start":"2026-03-14T07:06:58.586615Z","end":"2026-03-14T07:06:58.791431Z","steps":["trace[318181793] 'process raft request' (duration: 140.188154ms)","trace[318181793] 'compare' (duration: 64.367101ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:58.791847Z","caller":"traceutil/trace.go:171","msg":"trace[638706240] transaction","detail":"{read_only:false; response_revision:2008; number_of_response:1; }","duration":"205.21188ms","start":"2026-03-14T07:06:58.586613Z","end":"2026-03-14T07:06:58.791825Z","steps":["trace[638706240] 'process raft request' (duration: 204.77314ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:58.791986Z","caller":"traceutil/trace.go:171","msg":"trace[1451116098] linearizableReadLoop","detail":"{readStateIndex:2099; appliedIndex:2095; }","duration":"199.697023ms","start":"2026-03-14T07:06:58.592274Z","end":"2026-03-14T07:06:58.791971Z","steps":["trace[1451116098] 'read index received' (duration: 75.578746ms)","trace[1451116098] 'applied index is now lower than readState.Index' (duration: 124.117107ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:58.792163Z","caller":"traceutil/trace.go:171","msg":"trace[1951160161] transaction","detail":"{read_only:false; response_revision:2009; number_of_response:1; }","duration":"204.77962ms","start":"2026-03-14T07:06:58.587346Z","end":"2026-03-14T07:06:58.792125Z","steps":["trace[1951160161] 'process raft request' (duration: 204.397152ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:58.792201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.119944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-03-14T07:06:58.792241Z","caller":"traceutil/trace.go:171","msg":"trace[966042016] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2009; }","duration":"122.194766ms","start":"2026-03-14T07:06:58.670034Z","end":"2026-03-14T07:06:58.792229Z","steps":["trace[966042016] 'agreement among raft nodes before linearized reading' (duration: 122.109114ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:58.792315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.02303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-t59cl\" ","response":"range_response_count:1 size:13724"} {"level":"info","ts":"2026-03-14T07:06:58.792394Z","caller":"traceutil/trace.go:171","msg":"trace[942924482] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-t59cl; range_end:; response_count:1; response_revision:2009; }","duration":"200.131392ms","start":"2026-03-14T07:06:58.592242Z","end":"2026-03-14T07:06:58.792373Z","steps":["trace[942924482] 'agreement among raft nodes before linearized reading' (duration: 199.920238ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:59.005842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.034617ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:06:59.006059Z","caller":"traceutil/trace.go:171","msg":"trace[1424214291] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"143.528983ms","start":"2026-03-14T07:06:58.862511Z","end":"2026-03-14T07:06:59.00604Z","steps":["trace[1424214291] 'process raft request' (duration: 143.456432ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:59.006102Z","caller":"traceutil/trace.go:171","msg":"trace[1507331167] transaction","detail":"{read_only:false; response_revision:2010; number_of_response:1; }","duration":"208.588439ms","start":"2026-03-14T07:06:58.79743Z","end":"2026-03-14T07:06:59.006019Z","steps":["trace[1507331167] 'process raft request' (duration: 101.224905ms)","trace[1507331167] 'compare' (duration: 106.863363ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:59.006156Z","caller":"traceutil/trace.go:171","msg":"trace[842658590] linearizableReadLoop","detail":"{readStateIndex:2100; appliedIndex:2099; }","duration":"144.058224ms","start":"2026-03-14T07:06:58.862039Z","end":"2026-03-14T07:06:59.006097Z","steps":["trace[842658590] 'read index received' (duration: 36.628199ms)","trace[842658590] 'applied index is now lower than readState.Index' (duration: 107.428395ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:06:59.0064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.34918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-t59cl\" ","response":"range_response_count:1 size:13724"} {"level":"info","ts":"2026-03-14T07:06:59.006438Z","caller":"traceutil/trace.go:171","msg":"trace[1258813950] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-t59cl; range_end:; response_count:1; response_revision:2011; }","duration":"144.423842ms","start":"2026-03-14T07:06:58.862003Z","end":"2026-03-14T07:06:59.006427Z","steps":["trace[1258813950] 'agreement among raft nodes before linearized reading' (duration: 144.249199ms)"],"step_count":1}