{"level":"warn","ts":"2026-02-21T21:16:41.893183Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-21T21:16:41.893329Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.177:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.177:2380","--initial-cluster=instance=https://199.19.213.177:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.177:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.177:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-21T21:16:41.893465Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-21T21:16:41.893496Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.177:2380"]} {"level":"info","ts":"2026-02-21T21:16:41.893527Z","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-02-21T21:16:41.895104Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.177:2379"]} {"level":"info","ts":"2026-02-21T21:16:41.895262Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.177:2380"],"listen-peer-urls":["https://199.19.213.177:2380"],"advertise-client-urls":["https://199.19.213.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.177:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-21T21:16:41.89975Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.112118ms"} {"level":"info","ts":"2026-02-21T21:16:41.908919Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f577bc5ae95d61e1","cluster-id":"735860b212d0f0b6"} {"level":"info","ts":"2026-02-21T21:16:41.909228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 switched to configuration voters=()"} {"level":"info","ts":"2026-02-21T21:16:41.909264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 became follower at term 0"} {"level":"info","ts":"2026-02-21T21:16:41.909303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f577bc5ae95d61e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-21T21:16:41.909338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 became follower at term 1"} {"level":"info","ts":"2026-02-21T21:16:41.909408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 switched to configuration voters=(17687813160169202145)"} {"level":"warn","ts":"2026-02-21T21:16:41.915971Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-21T21:16:41.917157Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-21T21:16:41.918127Z","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-02-21T21:16:41.921169Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f577bc5ae95d61e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-21T21:16:41.921678Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f577bc5ae95d61e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-21T21:16:41.921745Z","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-02-21T21:16:41.922252Z","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-02-21T21:16:41.922307Z","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-02-21T21:16:41.924283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 switched to configuration voters=(17687813160169202145)"} {"level":"info","ts":"2026-02-21T21:16:41.924451Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"735860b212d0f0b6","local-member-id":"f577bc5ae95d61e1","added-peer-id":"f577bc5ae95d61e1","added-peer-peer-urls":["https://199.19.213.177:2380"]} {"level":"info","ts":"2026-02-21T21:16:41.929213Z","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-02-21T21:16:41.929245Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.177:2380"} {"level":"info","ts":"2026-02-21T21:16:41.929307Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.177:2380"} {"level":"info","ts":"2026-02-21T21:16:41.929828Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-21T21:16:41.929768Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f577bc5ae95d61e1","initial-advertise-peer-urls":["https://199.19.213.177:2380"],"listen-peer-urls":["https://199.19.213.177:2380"],"advertise-client-urls":["https://199.19.213.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-21T21:16:42.111845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 is starting a new election at term 1"} {"level":"info","ts":"2026-02-21T21:16:42.111886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-21T21:16:42.111931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 received MsgPreVoteResp from f577bc5ae95d61e1 at term 1"} {"level":"info","ts":"2026-02-21T21:16:42.111952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 became candidate at term 2"} {"level":"info","ts":"2026-02-21T21:16:42.111959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 received MsgVoteResp from f577bc5ae95d61e1 at term 2"} {"level":"info","ts":"2026-02-21T21:16:42.111967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 became leader at term 2"} {"level":"info","ts":"2026-02-21T21:16:42.111975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f577bc5ae95d61e1 elected leader f577bc5ae95d61e1 at term 2"} {"level":"info","ts":"2026-02-21T21:16:42.113387Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f577bc5ae95d61e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.177:2379]}","request-path":"/0/members/f577bc5ae95d61e1/attributes","cluster-id":"735860b212d0f0b6","publish-timeout":"7s"} {"level":"info","ts":"2026-02-21T21:16:42.113164Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-21T21:16:42.113697Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-21T21:16:42.116142Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.177:2379"} {"level":"info","ts":"2026-02-21T21:16:42.116583Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-21T21:16:42.119183Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-21T21:16:42.119405Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-21T21:16:42.11946Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-21T21:16:42.119627Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"735860b212d0f0b6","local-member-id":"f577bc5ae95d61e1","cluster-version":"3.5"} {"level":"info","ts":"2026-02-21T21:16:42.124829Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-21T21:16:42.124888Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-21T21:17:05.309156Z","caller":"traceutil/trace.go:171","msg":"trace[1838097088] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"685.340193ms","start":"2026-02-21T21:17:04.623782Z","end":"2026-02-21T21:17:05.309123Z","steps":["trace[1838097088] 'process raft request' (duration: 685.145078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:17:05.309907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:17:04.623771Z","time spent":"685.517437ms","remote":"127.0.0.1:33836","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-02-21T21:17:05.647652Z","caller":"traceutil/trace.go:171","msg":"trace[798995989] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"1.022854661s","start":"2026-02-21T21:17:04.624778Z","end":"2026-02-21T21:17:05.647633Z","steps":["trace[798995989] 'process raft request' (duration: 1.022711417s)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:17:05.647785Z","caller":"traceutil/trace.go:171","msg":"trace[1596381508] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:476; }","duration":"367.493456ms","start":"2026-02-21T21:17:05.280281Z","end":"2026-02-21T21:17:05.647775Z","steps":["trace[1596381508] 'read index received' (duration: 28.649346ms)","trace[1596381508] 'applied index is now lower than readState.Index' (duration: 338.84314ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T21:17:05.647835Z","caller":"traceutil/trace.go:171","msg":"trace[550900212] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"987.027522ms","start":"2026-02-21T21:17:04.660802Z","end":"2026-02-21T21:17:05.647829Z","steps":["trace[550900212] 'process raft request' (duration: 986.784577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:17:05.647924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:17:04.66078Z","time spent":"987.090244ms","remote":"127.0.0.1:33836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T21:17:05.647997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.889873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.177\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-21T21:17:05.647992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.693861ms","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-02-21T21:17:05.648134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:17:04.624699Z","time spent":"1.023034365s","remote":"127.0.0.1:33836","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-02-21T21:17:05.648141Z","caller":"traceutil/trace.go:171","msg":"trace[1897190203] range","detail":"{range_begin:/registry/masterleases/199.19.213.177; range_end:; response_count:1; response_revision:464; }","duration":"140.083887ms","start":"2026-02-21T21:17:05.508037Z","end":"2026-02-21T21:17:05.648121Z","steps":["trace[1897190203] 'agreement among raft nodes before linearized reading' (duration: 139.863632ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:17:05.648142Z","caller":"traceutil/trace.go:171","msg":"trace[2115468338] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:464; }","duration":"367.884045ms","start":"2026-02-21T21:17:05.28024Z","end":"2026-02-21T21:17:05.648125Z","steps":["trace[2115468338] 'agreement among raft nodes before linearized reading' (duration: 367.690901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:17:05.648197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:17:05.280221Z","time spent":"367.961067ms","remote":"127.0.0.1:33836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-21T21:17:05.806349Z","caller":"traceutil/trace.go:171","msg":"trace[182699197] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"152.831582ms","start":"2026-02-21T21:17:05.653333Z","end":"2026-02-21T21:17:05.806164Z","steps":["trace[182699197] 'process raft request' (duration: 152.191817ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:17:12.045972Z","caller":"traceutil/trace.go:171","msg":"trace[1901798503] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:493; }","duration":"375.995885ms","start":"2026-02-21T21:17:11.669952Z","end":"2026-02-21T21:17:12.045948Z","steps":["trace[1901798503] 'read index received' (duration: 375.907043ms)","trace[1901798503] 'applied index is now lower than readState.Index' (duration: 87.742µs)"],"step_count":2} {"level":"warn","ts":"2026-02-21T21:17:12.046134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.165969ms","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-02-21T21:17:12.046105Z","caller":"traceutil/trace.go:171","msg":"trace[355568046] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"710.982338ms","start":"2026-02-21T21:17:11.335086Z","end":"2026-02-21T21:17:12.046069Z","steps":["trace[355568046] 'process raft request' (duration: 710.717402ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:17:12.046171Z","caller":"traceutil/trace.go:171","msg":"trace[1161160984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:477; }","duration":"376.243101ms","start":"2026-02-21T21:17:11.669916Z","end":"2026-02-21T21:17:12.046159Z","steps":["trace[1161160984] 'agreement among raft nodes before linearized reading' (duration: 376.16347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:17:12.046204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:17:11.669902Z","time spent":"376.295022ms","remote":"127.0.0.1:33836","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-02-21T21:17:12.046287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:17:11.335064Z","time spent":"711.126501ms","remote":"127.0.0.1:33836","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-02-21T21:17:12.046335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.986122ms","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-02-21T21:17:12.046362Z","caller":"traceutil/trace.go:171","msg":"trace[3362402] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:477; }","duration":"197.051744ms","start":"2026-02-21T21:17:11.8493Z","end":"2026-02-21T21:17:12.046352Z","steps":["trace[3362402] 'agreement among raft nodes before linearized reading' (duration: 197.005223ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:17:14.525973Z","caller":"traceutil/trace.go:171","msg":"trace[1772358173] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"468.306263ms","start":"2026-02-21T21:17:14.057632Z","end":"2026-02-21T21:17:14.525938Z","steps":["trace[1772358173] 'read index received' (duration: 468.113468ms)","trace[1772358173] 'applied index is now lower than readState.Index' (duration: 191.315µs)"],"step_count":2} {"level":"info","ts":"2026-02-21T21:17:14.526295Z","caller":"traceutil/trace.go:171","msg":"trace[1677447843] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"472.833421ms","start":"2026-02-21T21:17:14.053427Z","end":"2026-02-21T21:17:14.526261Z","steps":["trace[1677447843] 'process raft request' (duration: 472.35143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:17:14.526441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:17:14.05341Z","time spent":"472.959785ms","remote":"127.0.0.1:33836","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-02-21T21:17:14.526625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.534949ms","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-02-21T21:17:14.526737Z","caller":"traceutil/trace.go:171","msg":"trace[221405306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:516; }","duration":"468.687422ms","start":"2026-02-21T21:17:14.058027Z","end":"2026-02-21T21:17:14.526715Z","steps":["trace[221405306] 'agreement among raft nodes before linearized reading' (duration: 468.393425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:17:14.526791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:17:14.05802Z","time spent":"468.761214ms","remote":"127.0.0.1:33836","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-02-21T21:17:14.526764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.830155ms","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-02-21T21:17:14.526898Z","caller":"traceutil/trace.go:171","msg":"trace[1875060319] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:516; }","duration":"181.0276ms","start":"2026-02-21T21:17:14.345843Z","end":"2026-02-21T21:17:14.526871Z","steps":["trace[1875060319] 'agreement among raft nodes before linearized reading' (duration: 180.708112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:17:14.527154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.515491ms","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-02-21T21:17:14.527202Z","caller":"traceutil/trace.go:171","msg":"trace[1824245839] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:516; }","duration":"469.581363ms","start":"2026-02-21T21:17:14.057607Z","end":"2026-02-21T21:17:14.527188Z","steps":["trace[1824245839] 'agreement among raft nodes before linearized reading' (duration: 468.707483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:17:14.527246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:17:14.057595Z","time spent":"469.644165ms","remote":"127.0.0.1:33836","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-02-21T21:17:20.975112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.764172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T21:17:20.975247Z","caller":"traceutil/trace.go:171","msg":"trace[317272903] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:552; }","duration":"414.607068ms","start":"2026-02-21T21:17:20.560612Z","end":"2026-02-21T21:17:20.975219Z","steps":["trace[317272903] 'read index received' (duration: 11.514716ms)","trace[317272903] 'applied index is now lower than readState.Index' (duration: 403.090892ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T21:17:20.975368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.767972ms","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-02-21T21:17:20.975314Z","caller":"traceutil/trace.go:171","msg":"trace[219111810] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"417.468386ms","start":"2026-02-21T21:17:20.557789Z","end":"2026-02-21T21:17:20.975258Z","steps":["trace[219111810] 'process raft request' (duration: 76.212243ms)","trace[219111810] 'compare' (duration: 340.588047ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T21:17:20.975417Z","caller":"traceutil/trace.go:171","msg":"trace[413073295] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:536; }","duration":"414.868045ms","start":"2026-02-21T21:17:20.560536Z","end":"2026-02-21T21:17:20.975404Z","steps":["trace[413073295] 'agreement among raft nodes before linearized reading' (duration: 414.782623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:17:20.975459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:17:20.560507Z","time spent":"414.942536ms","remote":"127.0.0.1:33836","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-02-21T21:17:20.975584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:17:20.55777Z","time spent":"417.677202ms","remote":"127.0.0.1:33836","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-02-21T21:17:21.76805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.690445ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-21T21:17:21.768332Z","caller":"traceutil/trace.go:171","msg":"trace[2008295249] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:554; }","duration":"793.0208ms","start":"2026-02-21T21:17:20.975277Z","end":"2026-02-21T21:17:21.768298Z","steps":["trace[2008295249] 'read index received' (duration: 257.027258ms)","trace[2008295249] 'applied index is now lower than readState.Index' (duration: 535.988862ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T21:17:21.768627Z","caller":"traceutil/trace.go:171","msg":"trace[441659539] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"787.96391ms","start":"2026-02-21T21:17:20.980649Z","end":"2026-02-21T21:17:21.768613Z","steps":["trace[441659539] 'process raft request' (duration: 787.5451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:17:21.76862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203386037s","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-02-21T21:17:21.768665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.380663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T21:17:21.768732Z","caller":"traceutil/trace.go:171","msg":"trace[1735920031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:536; }","duration":"1.20350868s","start":"2026-02-21T21:17:20.565174Z","end":"2026-02-21T21:17:21.768682Z","steps":["trace[1735920031] 'agreement among raft nodes before linearized reading' (duration: 1.203213324s)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:17:21.768751Z","caller":"traceutil/trace.go:171","msg":"trace[720765863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"748.489496ms","start":"2026-02-21T21:17:21.020206Z","end":"2026-02-21T21:17:21.768695Z","steps":["trace[720765863] 'agreement among raft nodes before linearized reading' (duration: 748.343892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:17:21.768779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:17:20.565162Z","time spent":"1.203607553s","remote":"127.0.0.1:33836","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-02-21T21:17:21.768801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:17:21.020182Z","time spent":"748.609858ms","remote":"127.0.0.1:33588","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-02-21T21:17:21.768814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.65519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-f69c5\" ","response":"range_response_count:1 size:16656"} {"level":"warn","ts":"2026-02-21T21:17:21.76883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:17:20.980619Z","time spent":"788.054352ms","remote":"127.0.0.1:33836","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-02-21T21:17:21.768873Z","caller":"traceutil/trace.go:171","msg":"trace[1728017262] range","detail":"{range_begin:/registry/pods/kube-system/cilium-f69c5; range_end:; response_count:1; response_revision:537; }","duration":"585.843385ms","start":"2026-02-21T21:17:21.183015Z","end":"2026-02-21T21:17:21.768858Z","steps":["trace[1728017262] 'agreement among raft nodes before linearized reading' (duration: 585.612419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:17:21.768903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:17:21.182996Z","time spent":"585.900977ms","remote":"127.0.0.1:33772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-f69c5\" "} {"level":"info","ts":"2026-02-21T21:17:22.044425Z","caller":"traceutil/trace.go:171","msg":"trace[593398861] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"271.200478ms","start":"2026-02-21T21:17:21.7732Z","end":"2026-02-21T21:17:22.0444Z","steps":["trace[593398861] 'process raft request' (duration: 242.903341ms)","trace[593398861] 'compare' (duration: 28.161953ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T21:17:22.198202Z","caller":"traceutil/trace.go:171","msg":"trace[1157237255] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:557; }","duration":"177.137948ms","start":"2026-02-21T21:17:22.021031Z","end":"2026-02-21T21:17:22.198169Z","steps":["trace[1157237255] 'read index received' (duration: 176.906043ms)","trace[1157237255] 'applied index is now lower than readState.Index' (duration: 230.175µs)"],"step_count":2} {"level":"warn","ts":"2026-02-21T21:17:22.198329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.284451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T21:17:22.198364Z","caller":"traceutil/trace.go:171","msg":"trace[498733296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"177.381773ms","start":"2026-02-21T21:17:22.020969Z","end":"2026-02-21T21:17:22.198351Z","steps":["trace[498733296] 'agreement among raft nodes before linearized reading' (duration: 177.304792ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:17:22.19924Z","caller":"traceutil/trace.go:171","msg":"trace[1082603768] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"416.212357ms","start":"2026-02-21T21:17:21.782387Z","end":"2026-02-21T21:17:22.198599Z","steps":["trace[1082603768] 'process raft request' (duration: 415.619443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:17:22.199477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:17:21.782377Z","time spent":"416.974695ms","remote":"127.0.0.1:33772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T21:17:23.034551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.842431ms","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-02-21T21:17:23.034624Z","caller":"traceutil/trace.go:171","msg":"trace[2106649309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:539; }","duration":"449.968454ms","start":"2026-02-21T21:17:22.58464Z","end":"2026-02-21T21:17:23.034609Z","steps":["trace[2106649309] 'range keys from in-memory index tree' (duration: 449.676856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:17:23.034654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:17:22.584617Z","time spent":"450.031145ms","remote":"127.0.0.1:33836","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-02-21T21:17:23.638196Z","caller":"traceutil/trace.go:171","msg":"trace[940854427] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"599.130713ms","start":"2026-02-21T21:17:23.039038Z","end":"2026-02-21T21:17:23.638169Z","steps":["trace[940854427] 'process raft request' (duration: 598.961059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:17:23.638396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:17:23.039005Z","time spent":"599.288357ms","remote":"127.0.0.1:33836","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-02-21T21:17:23.723992Z","caller":"traceutil/trace.go:171","msg":"trace[962482537] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"684.110525ms","start":"2026-02-21T21:17:23.039859Z","end":"2026-02-21T21:17:23.723969Z","steps":["trace[962482537] 'process raft request' (duration: 683.996232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:17:23.724109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:17:23.039838Z","time spent":"684.213907ms","remote":"127.0.0.1:33836","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-02-21T21:17:25.780791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.891973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-02-21T21:17:25.780962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.51845ms","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-02-21T21:17:25.781061Z","caller":"traceutil/trace.go:171","msg":"trace[762402212] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:554; }","duration":"374.300114ms","start":"2026-02-21T21:17:25.406694Z","end":"2026-02-21T21:17:25.780994Z","steps":["trace[762402212] 'range keys from in-memory index tree' (duration: 373.779992ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:17:25.781081Z","caller":"traceutil/trace.go:171","msg":"trace[529170072] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:554; }","duration":"137.723586ms","start":"2026-02-21T21:17:25.643332Z","end":"2026-02-21T21:17:25.781055Z","steps":["trace[529170072] 'range keys from in-memory index tree' (duration: 137.269413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:17:25.781132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:17:25.406673Z","time spent":"374.429118ms","remote":"127.0.0.1:33694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-21T21:17:25.781122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.615248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.177\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-21T21:17:25.781202Z","caller":"traceutil/trace.go:171","msg":"trace[604627338] range","detail":"{range_begin:/registry/masterleases/199.19.213.177; range_end:; response_count:1; response_revision:554; }","duration":"271.732661ms","start":"2026-02-21T21:17:25.50945Z","end":"2026-02-21T21:17:25.781183Z","steps":["trace[604627338] 'range keys from in-memory index tree' (duration: 271.460524ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:17:34.457979Z","caller":"traceutil/trace.go:171","msg":"trace[2004233729] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"175.416177ms","start":"2026-02-21T21:17:34.282541Z","end":"2026-02-21T21:17:34.457957Z","steps":["trace[2004233729] 'process raft request' (duration: 175.371236ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:17:34.458183Z","caller":"traceutil/trace.go:171","msg":"trace[860083623] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"176.546524ms","start":"2026-02-21T21:17:34.281624Z","end":"2026-02-21T21:17:34.458171Z","steps":["trace[860083623] 'process raft request' (duration: 125.538104ms)","trace[860083623] 'compare' (duration: 50.60948ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T21:17:34.871614Z","caller":"traceutil/trace.go:171","msg":"trace[1152738301] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"203.414866ms","start":"2026-02-21T21:17:34.66817Z","end":"2026-02-21T21:17:34.871585Z","steps":["trace[1152738301] 'process raft request' (duration: 203.232382ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:17:34.995442Z","caller":"traceutil/trace.go:171","msg":"trace[276504293] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"114.237823ms","start":"2026-02-21T21:17:34.881178Z","end":"2026-02-21T21:17:34.995416Z","steps":["trace[276504293] 'process raft request' (duration: 114.062789ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:17:35.0638Z","caller":"traceutil/trace.go:171","msg":"trace[77190572] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"178.145251ms","start":"2026-02-21T21:17:34.88563Z","end":"2026-02-21T21:17:35.063775Z","steps":["trace[77190572] 'process raft request' (duration: 177.912645ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:17:35.205659Z","caller":"traceutil/trace.go:171","msg":"trace[1312715181] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"202.992927ms","start":"2026-02-21T21:17:35.002637Z","end":"2026-02-21T21:17:35.20563Z","steps":["trace[1312715181] 'process raft request' (duration: 186.697497ms)","trace[1312715181] 'compare' (duration: 16.179097ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T21:17:36.651607Z","caller":"traceutil/trace.go:171","msg":"trace[2139966149] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"112.140382ms","start":"2026-02-21T21:17:36.539435Z","end":"2026-02-21T21:17:36.651575Z","steps":["trace[2139966149] 'process raft request' (duration: 111.525738ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:17:42.379091Z","caller":"traceutil/trace.go:171","msg":"trace[510925849] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:750; }","duration":"420.848618ms","start":"2026-02-21T21:17:41.95822Z","end":"2026-02-21T21:17:42.379069Z","steps":["trace[510925849] 'read index received' (duration: 420.812747ms)","trace[510925849] 'applied index is now lower than readState.Index' (duration: 34.491µs)"],"step_count":2} {"level":"warn","ts":"2026-02-21T21:17:42.379239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.007382ms","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-02-21T21:17:42.379264Z","caller":"traceutil/trace.go:171","msg":"trace[2109917566] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:726; }","duration":"421.073413ms","start":"2026-02-21T21:17:41.958179Z","end":"2026-02-21T21:17:42.379252Z","steps":["trace[2109917566] 'agreement among raft nodes before linearized reading' (duration: 421.005532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:17:42.379287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:17:41.958161Z","time spent":"421.122365ms","remote":"127.0.0.1:33836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-21T21:17:42.379238Z","caller":"traceutil/trace.go:171","msg":"trace[1451880103] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"434.424882ms","start":"2026-02-21T21:17:41.944753Z","end":"2026-02-21T21:17:42.379178Z","steps":["trace[1451880103] 'process raft request' (duration: 434.175987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:17:42.379418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.654437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-21T21:17:42.379438Z","caller":"traceutil/trace.go:171","msg":"trace[587921878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:726; }","duration":"222.747349ms","start":"2026-02-21T21:17:42.156686Z","end":"2026-02-21T21:17:42.379433Z","steps":["trace[587921878] 'agreement among raft nodes before linearized reading' (duration: 222.706428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:17:42.37955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:17:41.944703Z","time spent":"434.727389ms","remote":"127.0.0.1:33836","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-02-21T21:17:42.379642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.961126ms","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-02-21T21:17:42.379782Z","caller":"traceutil/trace.go:171","msg":"trace[70854944] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:726; }","duration":"374.184512ms","start":"2026-02-21T21:17:42.005572Z","end":"2026-02-21T21:17:42.379756Z","steps":["trace[70854944] 'agreement among raft nodes before linearized reading' (duration: 373.810443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:17:42.379892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:17:42.005561Z","time spent":"374.316095ms","remote":"127.0.0.1:33836","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-02-21T21:17:42.947805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.248684ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T21:17:42.947986Z","caller":"traceutil/trace.go:171","msg":"trace[1808157100] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"564.404722ms","start":"2026-02-21T21:17:42.383569Z","end":"2026-02-21T21:17:42.947974Z","steps":["trace[1808157100] 'process raft request' (duration: 564.376871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:17:42.948025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:17:42.383554Z","time spent":"564.453393ms","remote":"127.0.0.1:33836","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-02-21T21:17:42.948014Z","caller":"traceutil/trace.go:171","msg":"trace[9558149] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"564.581537ms","start":"2026-02-21T21:17:42.383414Z","end":"2026-02-21T21:17:42.947995Z","steps":["trace[9558149] 'process raft request' (duration: 564.471284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:17:42.948119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:17:42.38338Z","time spent":"564.679688ms","remote":"127.0.0.1:33836","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-02-21T21:17:42.948104Z","caller":"traceutil/trace.go:171","msg":"trace[1907075917] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"564.697869ms","start":"2026-02-21T21:17:42.383376Z","end":"2026-02-21T21:17:42.948074Z","steps":["trace[1907075917] 'process raft request' (duration: 65.085327ms)","trace[1907075917] 'compare' (duration: 499.058538ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T21:17:42.948262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:17:42.383347Z","time spent":"564.829822ms","remote":"127.0.0.1:33836","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-02-21T21:18:07.360746Z","caller":"traceutil/trace.go:171","msg":"trace[447304493] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"127.423848ms","start":"2026-02-21T21:18:07.233275Z","end":"2026-02-21T21:18:07.360699Z","steps":["trace[447304493] 'process raft request' (duration: 79.373909ms)","trace[447304493] 'compare' (duration: 47.905225ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T21:18:15.784907Z","caller":"traceutil/trace.go:171","msg":"trace[222277945] linearizableReadLoop","detail":"{readStateIndex:853; appliedIndex:852; }","duration":"392.570927ms","start":"2026-02-21T21:18:15.392319Z","end":"2026-02-21T21:18:15.784889Z","steps":["trace[222277945] 'read index received' (duration: 392.411193ms)","trace[222277945] 'applied index is now lower than readState.Index' (duration: 159.254µs)"],"step_count":2} {"level":"info","ts":"2026-02-21T21:18:15.785006Z","caller":"traceutil/trace.go:171","msg":"trace[1255285126] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"540.917637ms","start":"2026-02-21T21:18:15.24407Z","end":"2026-02-21T21:18:15.784987Z","steps":["trace[1255285126] 'process raft request' (duration: 540.705812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:15.785079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.756401ms","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-02-21T21:18:15.785092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:15.244057Z","time spent":"540.975128ms","remote":"127.0.0.1:33836","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-02-21T21:18:15.785107Z","caller":"traceutil/trace.go:171","msg":"trace[595962906] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:822; }","duration":"392.822943ms","start":"2026-02-21T21:18:15.392276Z","end":"2026-02-21T21:18:15.785099Z","steps":["trace[595962906] 'agreement among raft nodes before linearized reading' (duration: 392.7011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:15.785124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:15.392251Z","time spent":"392.869194ms","remote":"127.0.0.1:33836","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-02-21T21:18:15.785221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.129627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.177\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-21T21:18:15.785241Z","caller":"traceutil/trace.go:171","msg":"trace[429799574] range","detail":"{range_begin:/registry/masterleases/199.19.213.177; range_end:; response_count:1; response_revision:822; }","duration":"273.166778ms","start":"2026-02-21T21:18:15.512069Z","end":"2026-02-21T21:18:15.785236Z","steps":["trace[429799574] 'agreement among raft nodes before linearized reading' (duration: 273.096596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:15.786231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.764981ms","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-02-21T21:18:15.786347Z","caller":"traceutil/trace.go:171","msg":"trace[15942297] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:822; }","duration":"284.94002ms","start":"2026-02-21T21:18:15.501379Z","end":"2026-02-21T21:18:15.786319Z","steps":["trace[15942297] 'agreement among raft nodes before linearized reading' (duration: 283.673019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:16.950967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.147302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T21:18:16.951042Z","caller":"traceutil/trace.go:171","msg":"trace[102564657] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:825; }","duration":"463.250104ms","start":"2026-02-21T21:18:16.487775Z","end":"2026-02-21T21:18:16.951025Z","steps":["trace[102564657] 'count revisions from in-memory index tree' (duration: 463.05256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:16.951075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:16.487718Z","time spent":"463.350347ms","remote":"127.0.0.1:33876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-02-21T21:18:16.95168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.096007ms","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-02-21T21:18:16.951811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.388502ms","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-02-21T21:18:16.951826Z","caller":"traceutil/trace.go:171","msg":"trace[1551361691] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:825; }","duration":"304.308762ms","start":"2026-02-21T21:18:16.647482Z","end":"2026-02-21T21:18:16.951791Z","steps":["trace[1551361691] 'range keys from in-memory index tree' (duration: 303.707048ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:18:16.951864Z","caller":"traceutil/trace.go:171","msg":"trace[1610594025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:825; }","duration":"757.488465ms","start":"2026-02-21T21:18:16.19436Z","end":"2026-02-21T21:18:16.951849Z","steps":["trace[1610594025] 'range keys from in-memory index tree' (duration: 757.119296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:16.951892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:16.647452Z","time spent":"304.423534ms","remote":"127.0.0.1:33836","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-02-21T21:18:16.951914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:16.194334Z","time spent":"757.567997ms","remote":"127.0.0.1:33836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-21T21:18:17.120606Z","caller":"traceutil/trace.go:171","msg":"trace[344438998] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"163.737077ms","start":"2026-02-21T21:18:16.956816Z","end":"2026-02-21T21:18:17.120553Z","steps":["trace[344438998] 'process raft request' (duration: 163.564093ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:18:17.141789Z","caller":"traceutil/trace.go:171","msg":"trace[2010692968] linearizableReadLoop","detail":"{readStateIndex:859; appliedIndex:857; }","duration":"121.682574ms","start":"2026-02-21T21:18:17.020069Z","end":"2026-02-21T21:18:17.141752Z","steps":["trace[2010692968] 'read index received' (duration: 100.278503ms)","trace[2010692968] 'applied index is now lower than readState.Index' (duration: 21.402221ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T21:18:17.141851Z","caller":"traceutil/trace.go:171","msg":"trace[1489364735] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"184.724718ms","start":"2026-02-21T21:18:16.957106Z","end":"2026-02-21T21:18:17.14183Z","steps":["trace[1489364735] 'process raft request' (duration: 184.440341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:17.142525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.418651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T21:18:17.142699Z","caller":"traceutil/trace.go:171","msg":"trace[922348755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:827; }","duration":"122.640126ms","start":"2026-02-21T21:18:17.020006Z","end":"2026-02-21T21:18:17.142646Z","steps":["trace[922348755] 'agreement among raft nodes before linearized reading' (duration: 121.873598ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:18:19.355872Z","caller":"traceutil/trace.go:171","msg":"trace[1328484460] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"226.869384ms","start":"2026-02-21T21:18:19.12899Z","end":"2026-02-21T21:18:19.355859Z","steps":["trace[1328484460] 'process raft request' (duration: 226.69529ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:18:19.355861Z","caller":"traceutil/trace.go:171","msg":"trace[271436704] linearizableReadLoop","detail":"{readStateIndex:864; appliedIndex:863; }","duration":"199.008789ms","start":"2026-02-21T21:18:19.156819Z","end":"2026-02-21T21:18:19.355827Z","steps":["trace[271436704] 'read index received' (duration: 198.811944ms)","trace[271436704] 'applied index is now lower than readState.Index' (duration: 195.945µs)"],"step_count":2} {"level":"warn","ts":"2026-02-21T21:18:19.356026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.202993ms","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-02-21T21:18:19.356067Z","caller":"traceutil/trace.go:171","msg":"trace[1647724424] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:832; }","duration":"199.299365ms","start":"2026-02-21T21:18:19.156755Z","end":"2026-02-21T21:18:19.356055Z","steps":["trace[1647724424] 'agreement among raft nodes before linearized reading' (duration: 199.156592ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:18:19.637872Z","caller":"traceutil/trace.go:171","msg":"trace[134900862] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"278.420044ms","start":"2026-02-21T21:18:19.359424Z","end":"2026-02-21T21:18:19.637844Z","steps":["trace[134900862] 'process raft request' (duration: 194.397279ms)","trace[134900862] 'compare' (duration: 83.891482ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T21:18:19.648063Z","caller":"traceutil/trace.go:171","msg":"trace[2134387590] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"140.324738ms","start":"2026-02-21T21:18:19.507705Z","end":"2026-02-21T21:18:19.648029Z","steps":["trace[2134387590] 'process raft request' (duration: 140.146004ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:18:20.056346Z","caller":"traceutil/trace.go:171","msg":"trace[907683256] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"131.631571ms","start":"2026-02-21T21:18:19.924668Z","end":"2026-02-21T21:18:20.0563Z","steps":["trace[907683256] 'process raft request' (duration: 91.322859ms)","trace[907683256] 'compare' (duration: 40.081396ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T21:18:20.216644Z","caller":"traceutil/trace.go:171","msg":"trace[1279927636] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"155.475049ms","start":"2026-02-21T21:18:20.061141Z","end":"2026-02-21T21:18:20.216616Z","steps":["trace[1279927636] 'process raft request' (duration: 107.980916ms)","trace[1279927636] 'compare' (duration: 47.35677ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T21:18:21.048451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.305944ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-21T21:18:22.155749Z","caller":"traceutil/trace.go:171","msg":"trace[466547382] linearizableReadLoop","detail":"{readStateIndex:872; appliedIndex:871; }","duration":"234.137937ms","start":"2026-02-21T21:18:21.921588Z","end":"2026-02-21T21:18:22.155726Z","steps":["trace[466547382] 'read index received' (duration: 233.838489ms)","trace[466547382] 'applied index is now lower than readState.Index' (duration: 298.498µs)"],"step_count":2} {"level":"info","ts":"2026-02-21T21:18:22.156254Z","caller":"traceutil/trace.go:171","msg":"trace[669747095] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"470.420935ms","start":"2026-02-21T21:18:21.685819Z","end":"2026-02-21T21:18:22.15624Z","steps":["trace[669747095] 'process raft request' (duration: 469.705037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:22.156457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:21.685804Z","time spent":"470.492287ms","remote":"127.0.0.1:33836","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-02-21T21:18:22.156604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.007798ms","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-02-21T21:18:22.156581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.774521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T21:18:22.15668Z","caller":"traceutil/trace.go:171","msg":"trace[736791920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:839; }","duration":"235.132291ms","start":"2026-02-21T21:18:21.921527Z","end":"2026-02-21T21:18:22.156659Z","steps":["trace[736791920] 'agreement among raft nodes before linearized reading' (duration: 234.876905ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:18:22.156728Z","caller":"traceutil/trace.go:171","msg":"trace[55341278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:839; }","duration":"136.040627ms","start":"2026-02-21T21:18:22.020639Z","end":"2026-02-21T21:18:22.156679Z","steps":["trace[55341278] 'agreement among raft nodes before linearized reading' (duration: 135.645257ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:18:22.288998Z","caller":"traceutil/trace.go:171","msg":"trace[1760832719] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"126.395345ms","start":"2026-02-21T21:18:22.162563Z","end":"2026-02-21T21:18:22.288958Z","steps":["trace[1760832719] 'process raft request' (duration: 125.994086ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:18:22.451333Z","caller":"traceutil/trace.go:171","msg":"trace[1790278831] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"156.171357ms","start":"2026-02-21T21:18:22.295139Z","end":"2026-02-21T21:18:22.45131Z","steps":["trace[1790278831] 'process raft request' (duration: 68.786612ms)","trace[1790278831] 'compare' (duration: 87.224581ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T21:18:28.479647Z","caller":"traceutil/trace.go:171","msg":"trace[2118672530] linearizableReadLoop","detail":"{readStateIndex:891; appliedIndex:890; }","duration":"166.76427ms","start":"2026-02-21T21:18:28.312854Z","end":"2026-02-21T21:18:28.479618Z","steps":["trace[2118672530] 'read index received' (duration: 136.347234ms)","trace[2118672530] 'applied index is now lower than readState.Index' (duration: 30.415356ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T21:18:28.479834Z","caller":"traceutil/trace.go:171","msg":"trace[153080306] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"187.368751ms","start":"2026-02-21T21:18:28.292429Z","end":"2026-02-21T21:18:28.479797Z","steps":["trace[153080306] 'process raft request' (duration: 156.835152ms)","trace[153080306] 'compare' (duration: 30.208402ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T21:18:28.479989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.117618ms","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-02-21T21:18:28.480042Z","caller":"traceutil/trace.go:171","msg":"trace[763152947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:857; }","duration":"167.239451ms","start":"2026-02-21T21:18:28.31279Z","end":"2026-02-21T21:18:28.48003Z","steps":["trace[763152947] 'agreement among raft nodes before linearized reading' (duration: 167.017386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:38.866643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.638515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T21:18:38.866747Z","caller":"traceutil/trace.go:171","msg":"trace[324597315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"844.8149ms","start":"2026-02-21T21:18:38.021913Z","end":"2026-02-21T21:18:38.866728Z","steps":["trace[324597315] 'range keys from in-memory index tree' (duration: 844.491552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:38.86679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:38.021875Z","time spent":"844.905801ms","remote":"127.0.0.1:33594","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-02-21T21:18:38.88697Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7053090573864287511,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-21T21:18:39.267446Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.054358569s","expected-duration":"1s"} {"level":"info","ts":"2026-02-21T21:18:39.267991Z","caller":"traceutil/trace.go:171","msg":"trace[2121111747] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"1.054963714s","start":"2026-02-21T21:18:38.212992Z","end":"2026-02-21T21:18:39.267956Z","steps":["trace[2121111747] 'process raft request' (duration: 1.054737688s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:39.268142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:38.212971Z","time spent":"1.055066567s","remote":"127.0.0.1:33836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T21:18:39.268303Z","caller":"traceutil/trace.go:171","msg":"trace[933628624] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:973; }","duration":"881.822622ms","start":"2026-02-21T21:18:38.386429Z","end":"2026-02-21T21:18:39.268252Z","steps":["trace[933628624] 'read index received' (duration: 881.802861ms)","trace[933628624] 'applied index is now lower than readState.Index' (duration: 14.75µs)"],"step_count":2} {"level":"warn","ts":"2026-02-21T21:18:39.268783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.285791ms","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-02-21T21:18:39.268834Z","caller":"traceutil/trace.go:171","msg":"trace[1642199660] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:935; }","duration":"721.410464ms","start":"2026-02-21T21:18:38.547408Z","end":"2026-02-21T21:18:39.268819Z","steps":["trace[1642199660] 'agreement among raft nodes before linearized reading' (duration: 721.048715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:39.268805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.002308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-21T21:18:39.26886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.428347ms","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-02-21T21:18:39.268881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:38.547365Z","time spent":"721.506616ms","remote":"127.0.0.1:33836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-21T21:18:39.26893Z","caller":"traceutil/trace.go:171","msg":"trace[23209822] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:935; }","duration":"882.566469ms","start":"2026-02-21T21:18:38.386346Z","end":"2026-02-21T21:18:39.268913Z","steps":["trace[23209822] 'agreement among raft nodes before linearized reading' (duration: 882.343774ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:18:39.268921Z","caller":"traceutil/trace.go:171","msg":"trace[674545379] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:935; }","duration":"401.225444ms","start":"2026-02-21T21:18:38.867666Z","end":"2026-02-21T21:18:39.268892Z","steps":["trace[674545379] 'agreement among raft nodes before linearized reading' (duration: 400.923677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:39.268974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:38.386317Z","time spent":"882.632691ms","remote":"127.0.0.1:33836","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-02-21T21:18:39.268981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:38.86765Z","time spent":"401.320676ms","remote":"127.0.0.1:33588","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-02-21T21:18:39.269083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.169809ms","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-02-21T21:18:39.269075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.512223ms","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-02-21T21:18:39.269128Z","caller":"traceutil/trace.go:171","msg":"trace[980340698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:935; }","duration":"703.259701ms","start":"2026-02-21T21:18:38.56584Z","end":"2026-02-21T21:18:39.2691Z","steps":["trace[980340698] 'agreement among raft nodes before linearized reading' (duration: 702.757299ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:18:39.269138Z","caller":"traceutil/trace.go:171","msg":"trace[1012529718] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:935; }","duration":"710.624596ms","start":"2026-02-21T21:18:38.558496Z","end":"2026-02-21T21:18:39.26912Z","steps":["trace[1012529718] 'agreement among raft nodes before linearized reading' (duration: 710.188076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:39.269156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:38.565799Z","time spent":"703.351433ms","remote":"127.0.0.1:33836","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-02-21T21:18:39.26917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:38.558474Z","time spent":"710.691468ms","remote":"127.0.0.1:33836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-21T21:18:53.842396Z","caller":"traceutil/trace.go:171","msg":"trace[531168559] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"365.972103ms","start":"2026-02-21T21:18:53.476388Z","end":"2026-02-21T21:18:53.84236Z","steps":["trace[531168559] 'process raft request' (duration: 365.769178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:53.842609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:53.476372Z","time spent":"366.123807ms","remote":"127.0.0.1:33836","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-02-21T21:18:54.05988Z","caller":"traceutil/trace.go:171","msg":"trace[1942731694] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1015; }","duration":"563.493586ms","start":"2026-02-21T21:18:53.496355Z","end":"2026-02-21T21:18:54.059849Z","steps":["trace[1942731694] 'read index received' (duration: 345.886884ms)","trace[1942731694] 'applied index is now lower than readState.Index' (duration: 217.605542ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T21:18:54.059931Z","caller":"traceutil/trace.go:171","msg":"trace[1987056888] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"583.324189ms","start":"2026-02-21T21:18:53.476552Z","end":"2026-02-21T21:18:54.059876Z","steps":["trace[1987056888] 'process raft request' (duration: 515.400578ms)","trace[1987056888] 'compare' (duration: 67.668145ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T21:18:54.060063Z","caller":"traceutil/trace.go:171","msg":"trace[1902683458] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"582.850588ms","start":"2026-02-21T21:18:53.47715Z","end":"2026-02-21T21:18:54.060001Z","steps":["trace[1902683458] 'process raft request' (duration: 582.651763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:54.06011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.734061ms","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":"warn","ts":"2026-02-21T21:18:54.060153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.041796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-21T21:18:54.06015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:53.476545Z","time spent":"583.490902ms","remote":"127.0.0.1:33836","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-02-21T21:18:54.060205Z","caller":"traceutil/trace.go:171","msg":"trace[1341851920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:978; }","duration":"403.16295ms","start":"2026-02-21T21:18:53.657028Z","end":"2026-02-21T21:18:54.060191Z","steps":["trace[1341851920] 'agreement among raft nodes before linearized reading' (duration: 402.984656ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:18:54.060206Z","caller":"traceutil/trace.go:171","msg":"trace[1882237520] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:978; }","duration":"563.880825ms","start":"2026-02-21T21:18:53.496307Z","end":"2026-02-21T21:18:54.060188Z","steps":["trace[1882237520] 'agreement among raft nodes before linearized reading' (duration: 563.689901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:54.060245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:53.657004Z","time spent":"403.232682ms","remote":"127.0.0.1:33836","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-02-21T21:18:54.060269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:53.496295Z","time spent":"563.956136ms","remote":"127.0.0.1:33870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-21T21:18:54.060327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:53.477125Z","time spent":"583.063153ms","remote":"127.0.0.1:33836","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-02-21T21:18:54.059912Z","caller":"traceutil/trace.go:171","msg":"trace[1745242230] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"582.884879ms","start":"2026-02-21T21:18:53.476989Z","end":"2026-02-21T21:18:54.059874Z","steps":["trace[1745242230] 'process raft request' (duration: 582.768446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:54.061727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:53.476979Z","time spent":"584.119938ms","remote":"127.0.0.1:33836","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-02-21T21:18:55.995645Z","caller":"traceutil/trace.go:171","msg":"trace[808014216] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1020; }","duration":"481.995491ms","start":"2026-02-21T21:18:55.51363Z","end":"2026-02-21T21:18:55.995626Z","steps":["trace[808014216] 'read index received' (duration: 481.811667ms)","trace[808014216] 'applied index is now lower than readState.Index' (duration: 182.764µs)"],"step_count":2} {"level":"info","ts":"2026-02-21T21:18:55.99589Z","caller":"traceutil/trace.go:171","msg":"trace[619813434] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"609.870212ms","start":"2026-02-21T21:18:55.385934Z","end":"2026-02-21T21:18:55.995804Z","steps":["trace[619813434] 'process raft request' (duration: 609.558404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:55.996058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.249067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.177\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-21T21:18:55.99609Z","caller":"traceutil/trace.go:171","msg":"trace[2031583608] range","detail":"{range_begin:/registry/masterleases/199.19.213.177; range_end:; response_count:1; response_revision:980; }","duration":"482.518124ms","start":"2026-02-21T21:18:55.513562Z","end":"2026-02-21T21:18:55.99608Z","steps":["trace[2031583608] 'agreement among raft nodes before linearized reading' (duration: 482.196627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:55.996083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.730079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T21:18:55.996177Z","caller":"traceutil/trace.go:171","msg":"trace[89710244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:980; }","duration":"200.884333ms","start":"2026-02-21T21:18:55.795272Z","end":"2026-02-21T21:18:55.996156Z","steps":["trace[89710244] 'agreement among raft nodes before linearized reading' (duration: 200.701479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:55.996216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:55.513517Z","time spent":"482.688188ms","remote":"127.0.0.1:33610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.177\" "} {"level":"warn","ts":"2026-02-21T21:18:55.996332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.947726ms","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-02-21T21:18:55.996371Z","caller":"traceutil/trace.go:171","msg":"trace[285952489] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:980; }","duration":"149.039178ms","start":"2026-02-21T21:18:55.847321Z","end":"2026-02-21T21:18:55.99636Z","steps":["trace[285952489] 'agreement among raft nodes before linearized reading' (duration: 148.833912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:55.99612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:55.38591Z","time spent":"610.102998ms","remote":"127.0.0.1:33652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-21T21:18:56.672059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.423509ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-21T21:18:56.672162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:55.998023Z","time spent":"674.134006ms","remote":"127.0.0.1:33610","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-21T21:18:56.726537Z","caller":"traceutil/trace.go:171","msg":"trace[667424269] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"720.288807ms","start":"2026-02-21T21:18:56.00621Z","end":"2026-02-21T21:18:56.726499Z","steps":["trace[667424269] 'process raft request' (duration: 720.052342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:56.726625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.618362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T21:18:56.726679Z","caller":"traceutil/trace.go:171","msg":"trace[49025511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:981; }","duration":"709.737735ms","start":"2026-02-21T21:18:56.016927Z","end":"2026-02-21T21:18:56.726665Z","steps":["trace[49025511] 'agreement among raft nodes before linearized reading' (duration: 709.637463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:56.726733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:56.016901Z","time spent":"709.821847ms","remote":"127.0.0.1:33588","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-02-21T21:18:56.72673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:56.006182Z","time spent":"720.442371ms","remote":"127.0.0.1:33836","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-02-21T21:18:56.726497Z","caller":"traceutil/trace.go:171","msg":"trace[177405565] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1021; }","duration":"709.451979ms","start":"2026-02-21T21:18:56.016982Z","end":"2026-02-21T21:18:56.726434Z","steps":["trace[177405565] 'read index received' (duration: 447.591621ms)","trace[177405565] 'applied index is now lower than readState.Index' (duration: 261.859148ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T21:18:56.7396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.564086ms","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-02-21T21:18:56.739786Z","caller":"traceutil/trace.go:171","msg":"trace[306832478] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:982; }","duration":"674.828973ms","start":"2026-02-21T21:18:56.064933Z","end":"2026-02-21T21:18:56.739762Z","steps":["trace[306832478] 'agreement among raft nodes before linearized reading' (duration: 674.406743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:56.739873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:56.064906Z","time spent":"674.953346ms","remote":"127.0.0.1:33836","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-02-21T21:18:56.739645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.680215ms","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-02-21T21:18:56.74Z","caller":"traceutil/trace.go:171","msg":"trace[149547234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:982; }","duration":"666.102855ms","start":"2026-02-21T21:18:56.073885Z","end":"2026-02-21T21:18:56.739988Z","steps":["trace[149547234] 'agreement among raft nodes before linearized reading' (duration: 665.48516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:56.740037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:56.073857Z","time spent":"666.170746ms","remote":"127.0.0.1:33836","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-02-21T21:18:56.739664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.599148ms","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-02-21T21:18:56.740099Z","caller":"traceutil/trace.go:171","msg":"trace[392983349] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:982; }","duration":"675.077859ms","start":"2026-02-21T21:18:56.065001Z","end":"2026-02-21T21:18:56.740079Z","steps":["trace[392983349] 'agreement among raft nodes before linearized reading' (duration: 674.443744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:56.740152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:56.064961Z","time spent":"675.180491ms","remote":"127.0.0.1:33836","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-02-21T21:18:56.739661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.388818ms","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-02-21T21:18:56.740242Z","caller":"traceutil/trace.go:171","msg":"trace[1198205292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:982; }","duration":"627.017592ms","start":"2026-02-21T21:18:56.113194Z","end":"2026-02-21T21:18:56.740212Z","steps":["trace[1198205292] 'agreement among raft nodes before linearized reading' (duration: 626.157992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:56.740355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:56.113175Z","time spent":"627.144115ms","remote":"127.0.0.1:33836","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-02-21T21:18:57.029586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.271929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T21:18:57.029812Z","caller":"traceutil/trace.go:171","msg":"trace[92503088] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1024; }","duration":"282.425298ms","start":"2026-02-21T21:18:56.74737Z","end":"2026-02-21T21:18:57.029795Z","steps":["trace[92503088] 'read index received' (duration: 47.886342ms)","trace[92503088] 'applied index is now lower than readState.Index' (duration: 234.537826ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T21:18:57.029958Z","caller":"traceutil/trace.go:171","msg":"trace[1935673141] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"282.563783ms","start":"2026-02-21T21:18:56.747363Z","end":"2026-02-21T21:18:57.029927Z","steps":["trace[1935673141] 'process raft request' (duration: 282.395698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:57.030061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.656995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-21T21:18:57.030131Z","caller":"traceutil/trace.go:171","msg":"trace[1866732215] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"284.719114ms","start":"2026-02-21T21:18:56.745403Z","end":"2026-02-21T21:18:57.030122Z","steps":["trace[1866732215] 'process raft request' (duration: 49.834749ms)","trace[1866732215] 'compare' (duration: 234.152347ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T21:18:57.030152Z","caller":"traceutil/trace.go:171","msg":"trace[1736145377] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:986; }","duration":"282.796108ms","start":"2026-02-21T21:18:56.747335Z","end":"2026-02-21T21:18:57.030131Z","steps":["trace[1736145377] 'agreement among raft nodes before linearized reading' (duration: 282.625864ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:18:57.03023Z","caller":"traceutil/trace.go:171","msg":"trace[711059573] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"284.878669ms","start":"2026-02-21T21:18:56.745345Z","end":"2026-02-21T21:18:57.030224Z","steps":["trace[711059573] 'process raft request' (duration: 284.329615ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:18:57.030286Z","caller":"traceutil/trace.go:171","msg":"trace[483769772] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"284.074829ms","start":"2026-02-21T21:18:56.746207Z","end":"2026-02-21T21:18:57.030282Z","steps":["trace[483769772] 'process raft request' (duration: 283.518415ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:18:57.941159Z","caller":"traceutil/trace.go:171","msg":"trace[187013914] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"188.311693ms","start":"2026-02-21T21:18:57.752802Z","end":"2026-02-21T21:18:57.941114Z","steps":["trace[187013914] 'process raft request' (duration: 188.009246ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:18:58.083446Z","caller":"traceutil/trace.go:171","msg":"trace[1686621393] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"128.083296ms","start":"2026-02-21T21:18:57.955332Z","end":"2026-02-21T21:18:58.083415Z","steps":["trace[1686621393] 'process raft request' (duration: 71.479345ms)","trace[1686621393] 'compare' (duration: 56.252062ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T21:18:58.8988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.001658ms","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-02-21T21:18:58.898902Z","caller":"traceutil/trace.go:171","msg":"trace[242103423] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:989; }","duration":"168.162993ms","start":"2026-02-21T21:18:58.730718Z","end":"2026-02-21T21:18:58.898881Z","steps":["trace[242103423] 'range keys from in-memory index tree' (duration: 167.731092ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:18:59.238914Z","caller":"traceutil/trace.go:171","msg":"trace[1928060415] linearizableReadLoop","detail":"{readStateIndex:1032; appliedIndex:1031; }","duration":"203.903545ms","start":"2026-02-21T21:18:59.034985Z","end":"2026-02-21T21:18:59.238888Z","steps":["trace[1928060415] 'read index received' (duration: 203.746522ms)","trace[1928060415] 'applied index is now lower than readState.Index' (duration: 156.163µs)"],"step_count":2} {"level":"warn","ts":"2026-02-21T21:18:59.239197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.184682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-21T21:18:59.239241Z","caller":"traceutil/trace.go:171","msg":"trace[1807341287] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:990; }","duration":"204.298785ms","start":"2026-02-21T21:18:59.034933Z","end":"2026-02-21T21:18:59.239232Z","steps":["trace[1807341287] 'agreement among raft nodes before linearized reading' (duration: 204.09907ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:18:59.238995Z","caller":"traceutil/trace.go:171","msg":"trace[674881043] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"334.86348ms","start":"2026-02-21T21:18:58.904118Z","end":"2026-02-21T21:18:59.238982Z","steps":["trace[674881043] 'process raft request' (duration: 334.621744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:59.239655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:58.904088Z","time spent":"335.495065ms","remote":"127.0.0.1:33836","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-02-21T21:18:59.240514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.115606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-21T21:18:59.240572Z","caller":"traceutil/trace.go:171","msg":"trace[807404590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:990; }","duration":"205.184537ms","start":"2026-02-21T21:18:59.035371Z","end":"2026-02-21T21:18:59.240556Z","steps":["trace[807404590] 'agreement among raft nodes before linearized reading' (duration: 205.046894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:59.240648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.202793ms","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-02-21T21:18:59.240768Z","caller":"traceutil/trace.go:171","msg":"trace[1471254457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:990; }","duration":"204.361346ms","start":"2026-02-21T21:18:59.036386Z","end":"2026-02-21T21:18:59.240747Z","steps":["trace[1471254457] 'agreement among raft nodes before linearized reading' (duration: 204.020048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:18:59.240984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.939595ms","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-02-21T21:18:59.24105Z","caller":"traceutil/trace.go:171","msg":"trace[1520824198] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:990; }","duration":"206.002926ms","start":"2026-02-21T21:18:59.035021Z","end":"2026-02-21T21:18:59.241024Z","steps":["trace[1520824198] 'agreement among raft nodes before linearized reading' (duration: 205.360391ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:19:00.401917Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7053090573864287667,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-21T21:19:00.546341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.287056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T21:19:00.546692Z","caller":"traceutil/trace.go:171","msg":"trace[1983487381] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"1.298326561s","start":"2026-02-21T21:18:59.248344Z","end":"2026-02-21T21:19:00.54667Z","steps":["trace[1983487381] 'process raft request' (duration: 1.298274799s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:19:00.546768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:59.248318Z","time spent":"1.298414793s","remote":"127.0.0.1:33836","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-02-21T21:19:00.546949Z","caller":"traceutil/trace.go:171","msg":"trace[665825169] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"1.301788363s","start":"2026-02-21T21:18:59.245105Z","end":"2026-02-21T21:19:00.546893Z","steps":["trace[665825169] 'process raft request' (duration: 451.812821ms)","trace[665825169] 'compare' (duration: 849.072811ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T21:19:00.547072Z","caller":"traceutil/trace.go:171","msg":"trace[1127246594] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"1.30039131s","start":"2026-02-21T21:18:59.246654Z","end":"2026-02-21T21:19:00.547045Z","steps":["trace[1127246594] 'process raft request' (duration: 1.299818406s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:19:00.547144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:59.246639Z","time spent":"1.300465911s","remote":"127.0.0.1:33836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T21:19:00.547111Z","caller":"traceutil/trace.go:171","msg":"trace[1028594114] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"1.298836313s","start":"2026-02-21T21:18:59.248233Z","end":"2026-02-21T21:19:00.547069Z","steps":["trace[1028594114] 'process raft request' (duration: 1.298343161s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:19:00.547193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:59.245069Z","time spent":"1.302001178s","remote":"127.0.0.1:33836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T21:19:00.547318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:59.248224Z","time spent":"1.298974466s","remote":"127.0.0.1:33836","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-02-21T21:19:00.573776Z","caller":"traceutil/trace.go:171","msg":"trace[1128979310] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1032; }","duration":"672.914898ms","start":"2026-02-21T21:18:59.900844Z","end":"2026-02-21T21:19:00.573758Z","steps":["trace[1128979310] 'read index received' (duration: 545.984088ms)","trace[1128979310] 'applied index is now lower than readState.Index' (duration: 126.92956ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T21:19:00.574147Z","caller":"traceutil/trace.go:171","msg":"trace[1603913813] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"759.063343ms","start":"2026-02-21T21:18:59.815063Z","end":"2026-02-21T21:19:00.574126Z","steps":["trace[1603913813] 'process raft request' (duration: 758.53232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:19:00.574262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:59.815015Z","time spent":"759.182866ms","remote":"127.0.0.1:33836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T21:19:00.574574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.762573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T21:19:00.574677Z","caller":"traceutil/trace.go:171","msg":"trace[2070621806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:996; }","duration":"555.922066ms","start":"2026-02-21T21:19:00.018737Z","end":"2026-02-21T21:19:00.57466Z","steps":["trace[2070621806] 'agreement among raft nodes before linearized reading' (duration: 555.732361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:19:00.574718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:19:00.018718Z","time spent":"555.994528ms","remote":"127.0.0.1:33594","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-02-21T21:19:00.574706Z","caller":"traceutil/trace.go:171","msg":"trace[960627528] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"132.912582ms","start":"2026-02-21T21:19:00.441777Z","end":"2026-02-21T21:19:00.574689Z","steps":["trace[960627528] 'process raft request' (duration: 131.9392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:19:00.575006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.170408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-21T21:19:00.575032Z","caller":"traceutil/trace.go:171","msg":"trace[1565368284] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:996; }","duration":"674.230098ms","start":"2026-02-21T21:18:59.900793Z","end":"2026-02-21T21:19:00.575024Z","steps":["trace[1565368284] 'agreement among raft nodes before linearized reading' (duration: 674.183877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:19:00.575055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:18:59.900771Z","time spent":"674.27357ms","remote":"127.0.0.1:33978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-02-21T21:19:01.176437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.030764ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-21T21:19:01.176515Z","caller":"traceutil/trace.go:171","msg":"trace[1586382524] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1038; }","duration":"156.056524ms","start":"2026-02-21T21:19:01.020445Z","end":"2026-02-21T21:19:01.176502Z","steps":["trace[1586382524] 'read index received' (duration: 27.892536ms)","trace[1586382524] 'applied index is now lower than readState.Index' (duration: 128.162578ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T21:19:01.176567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.140336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T21:19:01.176597Z","caller":"traceutil/trace.go:171","msg":"trace[762652900] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:996; }","duration":"156.209218ms","start":"2026-02-21T21:19:01.020383Z","end":"2026-02-21T21:19:01.176593Z","steps":["trace[762652900] 'agreement among raft nodes before linearized reading' (duration: 156.149596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:19:01.776896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.715847ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-21T21:19:01.777023Z","caller":"traceutil/trace.go:171","msg":"trace[303972957] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"504.266392ms","start":"2026-02-21T21:19:01.272736Z","end":"2026-02-21T21:19:01.777002Z","steps":["trace[303972957] 'process raft request' (duration: 252.365501ms)","trace[303972957] 'compare' (duration: 251.509362ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T21:19:01.777085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:19:01.272725Z","time spent":"504.331855ms","remote":"127.0.0.1:33652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":851,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-21T21:19:02.5285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.089461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T21:19:02.528607Z","caller":"traceutil/trace.go:171","msg":"trace[2005620675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:998; }","duration":"510.203584ms","start":"2026-02-21T21:19:02.018373Z","end":"2026-02-21T21:19:02.528577Z","steps":["trace[2005620675] 'range keys from in-memory index tree' (duration: 509.428286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:19:02.528654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:19:02.01833Z","time spent":"510.314596ms","remote":"127.0.0.1:33588","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-02-21T21:19:02.674364Z","caller":"traceutil/trace.go:171","msg":"trace[1325587224] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"119.944822ms","start":"2026-02-21T21:19:02.554386Z","end":"2026-02-21T21:19:02.674331Z","steps":["trace[1325587224] 'process raft request' (duration: 119.733607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:19:03.148459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.023251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T21:19:03.148815Z","caller":"traceutil/trace.go:171","msg":"trace[982011928] linearizableReadLoop","detail":"{readStateIndex:1045; appliedIndex:1042; }","duration":"129.06618ms","start":"2026-02-21T21:19:03.019727Z","end":"2026-02-21T21:19:03.148793Z","steps":["trace[982011928] 'read index received' (duration: 45.721µs)","trace[982011928] 'applied index is now lower than readState.Index' (duration: 129.019089ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T21:19:03.14893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.199523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T21:19:03.148957Z","caller":"traceutil/trace.go:171","msg":"trace[1308304732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1002; }","duration":"129.261745ms","start":"2026-02-21T21:19:03.019685Z","end":"2026-02-21T21:19:03.148947Z","steps":["trace[1308304732] 'agreement among raft nodes before linearized reading' (duration: 129.181083ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:19:03.14922Z","caller":"traceutil/trace.go:171","msg":"trace[1525119632] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"587.598022ms","start":"2026-02-21T21:19:02.56161Z","end":"2026-02-21T21:19:03.149208Z","steps":["trace[1525119632] 'process raft request' (duration: 586.978437ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:19:03.152176Z","caller":"traceutil/trace.go:171","msg":"trace[293664504] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"590.519431ms","start":"2026-02-21T21:19:02.561635Z","end":"2026-02-21T21:19:03.152154Z","steps":["trace[293664504] 'process raft request' (duration: 587.10285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:19:03.152288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:19:02.561608Z","time spent":"590.633064ms","remote":"127.0.0.1:33836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T21:19:03.152696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:19:02.561593Z","time spent":"587.651803ms","remote":"127.0.0.1:33836","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-02-21T21:19:03.155178Z","caller":"traceutil/trace.go:171","msg":"trace[910158662] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"591.098935ms","start":"2026-02-21T21:19:02.561601Z","end":"2026-02-21T21:19:03.1527Z","steps":["trace[910158662] 'process raft request' (duration: 226.667319ms)","trace[910158662] 'compare' (duration: 359.5635ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T21:19:03.155393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:19:02.561576Z","time spent":"593.682486ms","remote":"127.0.0.1:33836","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-02-21T21:19:03.528896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.9484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-21T21:19:03.52909Z","caller":"traceutil/trace.go:171","msg":"trace[270886701] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1002; }","duration":"231.310449ms","start":"2026-02-21T21:19:03.297747Z","end":"2026-02-21T21:19:03.529058Z","steps":["trace[270886701] 'range keys from in-memory index tree' (duration: 230.541301ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:19:09.291684Z","caller":"traceutil/trace.go:171","msg":"trace[1387255686] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"112.740551ms","start":"2026-02-21T21:19:09.178914Z","end":"2026-02-21T21:19:09.291654Z","steps":["trace[1387255686] 'process raft request' (duration: 112.593867ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:19:09.293329Z","caller":"traceutil/trace.go:171","msg":"trace[65871786] linearizableReadLoop","detail":"{readStateIndex:1083; appliedIndex:1082; }","duration":"106.036331ms","start":"2026-02-21T21:19:09.187276Z","end":"2026-02-21T21:19:09.293312Z","steps":["trace[65871786] 'read index received' (duration: 104.908574ms)","trace[65871786] 'applied index is now lower than readState.Index' (duration: 1.126937ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T21:19:09.293572Z","caller":"traceutil/trace.go:171","msg":"trace[11552003] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"113.770544ms","start":"2026-02-21T21:19:09.179767Z","end":"2026-02-21T21:19:09.293538Z","steps":["trace[11552003] 'process raft request' (duration: 113.482917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:19:09.293655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.355888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-21T21:19:09.293773Z","caller":"traceutil/trace.go:171","msg":"trace[313331965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1039; }","duration":"106.516051ms","start":"2026-02-21T21:19:09.187243Z","end":"2026-02-21T21:19:09.293759Z","steps":["trace[313331965] 'agreement among raft nodes before linearized reading' (duration: 106.219684ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:19:09.918018Z","caller":"traceutil/trace.go:171","msg":"trace[2012199283] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"351.299453ms","start":"2026-02-21T21:19:09.566683Z","end":"2026-02-21T21:19:09.917982Z","steps":["trace[2012199283] 'process raft request' (duration: 350.929834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:19:09.918205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:19:09.566657Z","time spent":"351.441846ms","remote":"127.0.0.1:33836","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-02-21T21:19:13.419173Z","caller":"traceutil/trace.go:171","msg":"trace[719385010] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"103.303776ms","start":"2026-02-21T21:19:13.315843Z","end":"2026-02-21T21:19:13.419147Z","steps":["trace[719385010] 'process raft request' (duration: 103.119961ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:19:13.419545Z","caller":"traceutil/trace.go:171","msg":"trace[344819501] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"101.244566ms","start":"2026-02-21T21:19:13.318265Z","end":"2026-02-21T21:19:13.41951Z","steps":["trace[344819501] 'process raft request' (duration: 100.837637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:19:14.231486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.069935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T21:19:14.231651Z","caller":"traceutil/trace.go:171","msg":"trace[1336184520] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"164.874975ms","start":"2026-02-21T21:19:14.066744Z","end":"2026-02-21T21:19:14.231618Z","steps":["trace[1336184520] 'process raft request' (duration: 49.541653ms)","trace[1336184520] 'compare' (duration: 114.902482ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T21:19:16.961273Z","caller":"traceutil/trace.go:171","msg":"trace[94943412] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"195.153634ms","start":"2026-02-21T21:19:16.766085Z","end":"2026-02-21T21:19:16.961239Z","steps":["trace[94943412] 'process raft request' (duration: 194.904768ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:19:18.182244Z","caller":"traceutil/trace.go:171","msg":"trace[1738081922] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"139.832824ms","start":"2026-02-21T21:19:18.042389Z","end":"2026-02-21T21:19:18.182222Z","steps":["trace[1738081922] 'process raft request' (duration: 139.676831ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:19:51.123258Z","caller":"traceutil/trace.go:171","msg":"trace[71024723] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1283; }","duration":"115.225108ms","start":"2026-02-21T21:19:51.008008Z","end":"2026-02-21T21:19:51.123233Z","steps":["trace[71024723] 'read index received' (duration: 86.435891ms)","trace[71024723] 'applied index is now lower than readState.Index' (duration: 28.788117ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T21:19:51.123421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.507589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T21:19:51.123452Z","caller":"traceutil/trace.go:171","msg":"trace[2018914730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1231; }","duration":"103.600851ms","start":"2026-02-21T21:19:51.019844Z","end":"2026-02-21T21:19:51.123444Z","steps":["trace[2018914730] 'agreement among raft nodes before linearized reading' (duration: 103.527099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:19:51.123707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.589436ms","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:510"} {"level":"info","ts":"2026-02-21T21:19:51.123822Z","caller":"traceutil/trace.go:171","msg":"trace[2080558120] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1231; }","duration":"115.842092ms","start":"2026-02-21T21:19:51.007954Z","end":"2026-02-21T21:19:51.123796Z","steps":["trace[2080558120] 'agreement among raft nodes before linearized reading' (duration: 115.401772ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:22:16.8774Z","caller":"traceutil/trace.go:171","msg":"trace[442665284] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"184.858982ms","start":"2026-02-21T21:22:16.692509Z","end":"2026-02-21T21:22:16.877368Z","steps":["trace[442665284] 'process raft request' (duration: 184.150195ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:22:24.696773Z","caller":"traceutil/trace.go:171","msg":"trace[842971114] linearizableReadLoop","detail":"{readStateIndex:1971; appliedIndex:1970; }","duration":"228.507913ms","start":"2026-02-21T21:22:24.468234Z","end":"2026-02-21T21:22:24.696742Z","steps":["trace[842971114] 'read index received' (duration: 228.268837ms)","trace[842971114] 'applied index is now lower than readState.Index' (duration: 237.596µs)"],"step_count":2} {"level":"info","ts":"2026-02-21T21:22:24.697165Z","caller":"traceutil/trace.go:171","msg":"trace[1134966317] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"529.9326ms","start":"2026-02-21T21:22:24.167219Z","end":"2026-02-21T21:22:24.697152Z","steps":["trace[1134966317] 'process raft request' (duration: 529.308226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T21:22:24.697287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T21:22:24.167191Z","time spent":"530.007862ms","remote":"127.0.0.1:33836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T21:22:24.697722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.498816ms","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-02-21T21:22:24.697726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.267065ms","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:533"} {"level":"info","ts":"2026-02-21T21:22:24.697764Z","caller":"traceutil/trace.go:171","msg":"trace[1413393961] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1887; }","duration":"229.598938ms","start":"2026-02-21T21:22:24.468152Z","end":"2026-02-21T21:22:24.697751Z","steps":["trace[1413393961] 'agreement among raft nodes before linearized reading' (duration: 229.523677ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T21:22:24.697822Z","caller":"traceutil/trace.go:171","msg":"trace[1193948336] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1887; }","duration":"125.430838ms","start":"2026-02-21T21:22:24.572365Z","end":"2026-02-21T21:22:24.697796Z","steps":["trace[1193948336] 'agreement among raft nodes before linearized reading' (duration: 125.143682ms)"],"step_count":1}