{"level":"warn","ts":"2026-02-12T02:12:20.787615Z","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-12T02:12:20.787698Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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-12T02:12:20.787768Z","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-12T02:12:20.787776Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-02-12T02:12:20.787796Z","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-12T02:12:20.788339Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-02-12T02:12:20.788411Z","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":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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-12T02:12:20.791988Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.401602ms"} {"level":"info","ts":"2026-02-12T02:12:20.810763Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-02-12T02:12:20.810823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-02-12T02:12:20.810838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-02-12T02:12:20.810846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-12T02:12:20.810852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-02-12T02:12:20.81089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-02-12T02:12:20.820663Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-12T02:12:20.822112Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-12T02:12:20.831196Z","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-12T02:12:20.833633Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-12T02:12:20.834031Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-12T02:12:20.834157Z","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-12T02:12:20.834205Z","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-12T02:12:20.834222Z","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-12T02:12:20.834823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-02-12T02:12:20.834914Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-02-12T02:12:20.843831Z","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-12T02:12:20.843939Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-02-12T02:12:20.843956Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-02-12T02:12:20.859825Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-12T02:12:20.859857Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-12T02:12:21.111188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-02-12T02:12:21.111238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-12T02:12:21.11126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-02-12T02:12:21.111271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-02-12T02:12:21.11132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-02-12T02:12:21.111331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-02-12T02:12:21.111355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-02-12T02:12:21.116608Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-02-12T02:12:21.116796Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T02:12:21.116871Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T02:12:21.117186Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-12T02:12:21.117243Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-12T02:12:21.117284Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T02:12:21.119428Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-02-12T02:12:21.127669Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-12T02:12:21.13322Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T02:12:21.133337Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T02:12:21.133392Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T02:12:39.364756Z","caller":"traceutil/trace.go:171","msg":"trace[721508221] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:428; }","duration":"223.794063ms","start":"2026-02-12T02:12:39.140941Z","end":"2026-02-12T02:12:39.364735Z","steps":["trace[721508221] 'read index received' (duration: 223.590778ms)","trace[721508221] 'applied index is now lower than readState.Index' (duration: 202.395µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:12:39.364936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.970316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:12:39.364974Z","caller":"traceutil/trace.go:171","msg":"trace[60737448] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:414; }","duration":"224.028307ms","start":"2026-02-12T02:12:39.140937Z","end":"2026-02-12T02:12:39.364965Z","steps":["trace[60737448] 'agreement among raft nodes before linearized reading' (duration: 223.915155ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:12:39.365349Z","caller":"traceutil/trace.go:171","msg":"trace[1738163702] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"333.973762ms","start":"2026-02-12T02:12:39.031364Z","end":"2026-02-12T02:12:39.365338Z","steps":["trace[1738163702] 'process raft request' (duration: 333.236947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:12:39.365807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:12:39.031352Z","time spent":"334.033623ms","remote":"127.0.0.1:33832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5255,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:12:57.982857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.566685ms","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-12T02:12:57.982862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.535918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-12T02:12:57.982914Z","caller":"traceutil/trace.go:171","msg":"trace[1054267376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:492; }","duration":"141.633146ms","start":"2026-02-12T02:12:57.841269Z","end":"2026-02-12T02:12:57.982902Z","steps":["trace[1054267376] 'range keys from in-memory index tree' (duration: 141.403931ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:12:57.982936Z","caller":"traceutil/trace.go:171","msg":"trace[246173789] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:492; }","duration":"258.638051ms","start":"2026-02-12T02:12:57.724279Z","end":"2026-02-12T02:12:57.982917Z","steps":["trace[246173789] 'range keys from in-memory index tree' (duration: 258.381645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:12:57.983048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.286393ms","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-12T02:12:57.983063Z","caller":"traceutil/trace.go:171","msg":"trace[139572853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:492; }","duration":"136.326384ms","start":"2026-02-12T02:12:57.846732Z","end":"2026-02-12T02:12:57.983059Z","steps":["trace[139572853] 'range keys from in-memory index tree' (duration: 136.215222ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:13:02.248155Z","caller":"traceutil/trace.go:171","msg":"trace[1889942843] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"156.033251ms","start":"2026-02-12T02:13:02.092099Z","end":"2026-02-12T02:13:02.248133Z","steps":["trace[1889942843] 'process raft request' (duration: 105.12049ms)","trace[1889942843] 'compare' (duration: 50.816459ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:13:02.248842Z","caller":"traceutil/trace.go:171","msg":"trace[484598425] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:521; }","duration":"109.036184ms","start":"2026-02-12T02:13:02.139789Z","end":"2026-02-12T02:13:02.248825Z","steps":["trace[484598425] 'read index received' (duration: 57.476049ms)","trace[484598425] 'applied index is now lower than readState.Index' (duration: 51.559005ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:13:02.249288Z","caller":"traceutil/trace.go:171","msg":"trace[1331541615] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"157.147226ms","start":"2026-02-12T02:13:02.092126Z","end":"2026-02-12T02:13:02.249274Z","steps":["trace[1331541615] 'process raft request' (duration: 156.635765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:02.249419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.619557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:13:02.251057Z","caller":"traceutil/trace.go:171","msg":"trace[1800085454] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:503; }","duration":"111.302622ms","start":"2026-02-12T02:13:02.139741Z","end":"2026-02-12T02:13:02.251044Z","steps":["trace[1800085454] 'agreement among raft nodes before linearized reading' (duration: 109.215698ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:13:03.356012Z","caller":"traceutil/trace.go:171","msg":"trace[1476271175] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"460.085575ms","start":"2026-02-12T02:13:02.895903Z","end":"2026-02-12T02:13:03.355988Z","steps":["trace[1476271175] 'process raft request' (duration: 459.974672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:03.356154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:02.895876Z","time spent":"460.198767ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:13:06.241634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.32812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fbvfc\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-12T02:13:06.241696Z","caller":"traceutil/trace.go:171","msg":"trace[1414317797] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fbvfc; range_end:; response_count:1; response_revision:513; }","duration":"337.422313ms","start":"2026-02-12T02:13:05.904261Z","end":"2026-02-12T02:13:06.241683Z","steps":["trace[1414317797] 'range keys from in-memory index tree' (duration: 337.215888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:06.241724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:05.904246Z","time spent":"337.472633ms","remote":"127.0.0.1:33832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16678,"request content":"key:\"/registry/pods/kube-system/cilium-fbvfc\" "} {"level":"warn","ts":"2026-02-12T02:13:06.242516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.156021ms","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-12T02:13:06.242535Z","caller":"traceutil/trace.go:171","msg":"trace[1619012149] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:513; }","duration":"145.215532ms","start":"2026-02-12T02:13:06.097313Z","end":"2026-02-12T02:13:06.242529Z","steps":["trace[1619012149] 'range keys from in-memory index tree' (duration: 145.072639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:06.242617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.542965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:13:06.242632Z","caller":"traceutil/trace.go:171","msg":"trace[292777531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"101.584036ms","start":"2026-02-12T02:13:06.141043Z","end":"2026-02-12T02:13:06.242627Z","steps":["trace[292777531] 'range keys from in-memory index tree' (duration: 101.495444ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:13:06.472514Z","caller":"traceutil/trace.go:171","msg":"trace[2077561284] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"226.040397ms","start":"2026-02-12T02:13:06.246452Z","end":"2026-02-12T02:13:06.472492Z","steps":["trace[2077561284] 'process raft request' (duration: 225.915535ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:13:06.757434Z","caller":"traceutil/trace.go:171","msg":"trace[1336684220] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"493.020184ms","start":"2026-02-12T02:13:06.264396Z","end":"2026-02-12T02:13:06.757416Z","steps":["trace[1336684220] 'read index received' (duration: 208.627417ms)","trace[1336684220] 'applied index is now lower than readState.Index' (duration: 284.391977ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:13:06.757515Z","caller":"traceutil/trace.go:171","msg":"trace[240518772] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"499.083903ms","start":"2026-02-12T02:13:06.258425Z","end":"2026-02-12T02:13:06.757508Z","steps":["trace[240518772] 'process raft request' (duration: 498.870669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:06.757597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:06.25841Z","time spent":"499.124264ms","remote":"127.0.0.1:33832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:13:06.757765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.365701ms","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-12T02:13:06.757786Z","caller":"traceutil/trace.go:171","msg":"trace[620335975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:515; }","duration":"493.406153ms","start":"2026-02-12T02:13:06.264373Z","end":"2026-02-12T02:13:06.757779Z","steps":["trace[620335975] 'agreement among raft nodes before linearized reading' (duration: 493.314091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:06.757811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:06.264366Z","time spent":"493.439243ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T02:13:06.758014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.581045ms","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-12T02:13:06.758034Z","caller":"traceutil/trace.go:171","msg":"trace[1691616113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:515; }","duration":"492.612656ms","start":"2026-02-12T02:13:06.265415Z","end":"2026-02-12T02:13:06.758028Z","steps":["trace[1691616113] 'agreement among raft nodes before linearized reading' (duration: 492.557795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:06.758051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:06.26541Z","time spent":"492.637136ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T02:13:10.374044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.332894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:13:10.37414Z","caller":"traceutil/trace.go:171","msg":"trace[258897582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"234.485967ms","start":"2026-02-12T02:13:10.139638Z","end":"2026-02-12T02:13:10.374124Z","steps":["trace[258897582] 'range keys from in-memory index tree' (duration: 234.267063ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:13:11.372075Z","caller":"traceutil/trace.go:171","msg":"trace[797013228] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"143.740841ms","start":"2026-02-12T02:13:11.228316Z","end":"2026-02-12T02:13:11.372057Z","steps":["trace[797013228] 'process raft request' (duration: 143.009836ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:13:16.758655Z","caller":"traceutil/trace.go:171","msg":"trace[222324311] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"203.892348ms","start":"2026-02-12T02:13:16.554747Z","end":"2026-02-12T02:13:16.758639Z","steps":["trace[222324311] 'process raft request' (duration: 203.779126ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:13:17.214771Z","caller":"traceutil/trace.go:171","msg":"trace[1894324868] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"374.186323ms","start":"2026-02-12T02:13:16.840578Z","end":"2026-02-12T02:13:17.214764Z","steps":["trace[1894324868] 'process raft request' (duration: 357.156272ms)","trace[1894324868] 'compare' (duration: 16.51599ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:13:17.21486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:16.840567Z","time spent":"374.242124ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:13:17.214677Z","caller":"traceutil/trace.go:171","msg":"trace[891355707] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"370.860532ms","start":"2026-02-12T02:13:16.843801Z","end":"2026-02-12T02:13:17.214661Z","steps":["trace[891355707] 'read index received' (duration: 353.82379ms)","trace[891355707] 'applied index is now lower than readState.Index' (duration: 17.036222ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:13:17.221721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.908831ms","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-12T02:13:17.221754Z","caller":"traceutil/trace.go:171","msg":"trace[1994931442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:630; }","duration":"377.958072ms","start":"2026-02-12T02:13:16.843789Z","end":"2026-02-12T02:13:17.221747Z","steps":["trace[1994931442] 'agreement among raft nodes before linearized reading' (duration: 371.254971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:17.22177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:16.843784Z","time spent":"377.982063ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-12T02:13:23.26561Z","caller":"traceutil/trace.go:171","msg":"trace[8028815] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:716; }","duration":"428.332402ms","start":"2026-02-12T02:13:22.837264Z","end":"2026-02-12T02:13:23.265596Z","steps":["trace[8028815] 'read index received' (duration: 428.21986ms)","trace[8028815] 'applied index is now lower than readState.Index' (duration: 111.832µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:13:23.265787Z","caller":"traceutil/trace.go:171","msg":"trace[738949389] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"433.031792ms","start":"2026-02-12T02:13:22.832748Z","end":"2026-02-12T02:13:23.265779Z","steps":["trace[738949389] 'process raft request' (duration: 432.775446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:23.265852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:22.832731Z","time spent":"433.073562ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:13:23.265992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.72629ms","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-12T02:13:23.266012Z","caller":"traceutil/trace.go:171","msg":"trace[247354084] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:692; }","duration":"428.75775ms","start":"2026-02-12T02:13:22.837248Z","end":"2026-02-12T02:13:23.266006Z","steps":["trace[247354084] 'agreement among raft nodes before linearized reading' (duration: 428.693079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:23.266029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:22.837242Z","time spent":"428.783321ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-12T02:13:23.26611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.775292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:13:23.266126Z","caller":"traceutil/trace.go:171","msg":"trace[1117754936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:692; }","duration":"127.815603ms","start":"2026-02-12T02:13:23.138305Z","end":"2026-02-12T02:13:23.266121Z","steps":["trace[1117754936] 'agreement among raft nodes before linearized reading' (duration: 127.792252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:24.280212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.226562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T02:13:24.280317Z","caller":"traceutil/trace.go:171","msg":"trace[649737056] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"1.008261971s","start":"2026-02-12T02:13:23.272023Z","end":"2026-02-12T02:13:24.280285Z","steps":["trace[649737056] 'process raft request' (duration: 675.805944ms)","trace[649737056] 'compare' (duration: 331.923176ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:13:24.280367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:23.272011Z","time spent":"1.008333462s","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:13:24.282183Z","caller":"traceutil/trace.go:171","msg":"trace[1305258717] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:717; }","duration":"754.931104ms","start":"2026-02-12T02:13:23.527241Z","end":"2026-02-12T02:13:24.282172Z","steps":["trace[1305258717] 'read index received' (duration: 420.75948ms)","trace[1305258717] 'applied index is now lower than readState.Index' (duration: 334.171014ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:13:24.282323Z","caller":"traceutil/trace.go:171","msg":"trace[1894182057] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"1.010180721s","start":"2026-02-12T02:13:23.272134Z","end":"2026-02-12T02:13:24.282315Z","steps":["trace[1894182057] 'process raft request' (duration: 1.009934216s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:24.282372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:23.272129Z","time spent":"1.010209882s","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:13:24.282465Z","caller":"traceutil/trace.go:171","msg":"trace[515569517] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"1.008162848s","start":"2026-02-12T02:13:23.274296Z","end":"2026-02-12T02:13:24.282459Z","steps":["trace[515569517] 'process raft request' (duration: 1.007818771s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:24.282499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:23.274287Z","time spent":"1.008194149s","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:13:24.282571Z","caller":"traceutil/trace.go:171","msg":"trace[2118767033] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"927.847773ms","start":"2026-02-12T02:13:23.354718Z","end":"2026-02-12T02:13:24.282566Z","steps":["trace[2118767033] 'process raft request' (duration: 927.419564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:24.282599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:23.354693Z","time spent":"927.891324ms","remote":"127.0.0.1:33740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-12T02:13:24.282736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.493606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T02:13:24.282756Z","caller":"traceutil/trace.go:171","msg":"trace[1970834382] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:696; }","duration":"755.539037ms","start":"2026-02-12T02:13:23.527211Z","end":"2026-02-12T02:13:24.282751Z","steps":["trace[1970834382] 'agreement among raft nodes before linearized reading' (duration: 755.465266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:24.282778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:23.527196Z","time spent":"755.576228ms","remote":"127.0.0.1:33916","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-12T02:13:24.282898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.883996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:13:24.282918Z","caller":"traceutil/trace.go:171","msg":"trace[840382154] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:696; }","duration":"561.923187ms","start":"2026-02-12T02:13:23.720989Z","end":"2026-02-12T02:13:24.282912Z","steps":["trace[840382154] 'agreement among raft nodes before linearized reading' (duration: 561.886087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:24.282936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:23.720963Z","time spent":"561.969048ms","remote":"127.0.0.1:34144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-12T02:13:24.283112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.462942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:13:24.283131Z","caller":"traceutil/trace.go:171","msg":"trace[79704723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:696; }","duration":"141.524293ms","start":"2026-02-12T02:13:24.141599Z","end":"2026-02-12T02:13:24.283124Z","steps":["trace[79704723] 'agreement among raft nodes before linearized reading' (duration: 141.496822ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:13:28.670161Z","caller":"traceutil/trace.go:171","msg":"trace[1694279356] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"309.421897ms","start":"2026-02-12T02:13:28.360715Z","end":"2026-02-12T02:13:28.670137Z","steps":["trace[1694279356] 'process raft request' (duration: 277.156913ms)","trace[1694279356] 'compare' (duration: 32.112241ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:13:28.670294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:28.360693Z","time spent":"309.540179ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:13:28.672034Z","caller":"traceutil/trace.go:171","msg":"trace[1071015925] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"310.879189ms","start":"2026-02-12T02:13:28.361143Z","end":"2026-02-12T02:13:28.672022Z","steps":["trace[1071015925] 'process raft request' (duration: 310.767097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:28.67209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:28.361126Z","time spent":"310.93524ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:13:28.67216Z","caller":"traceutil/trace.go:171","msg":"trace[983653072] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"308.806695ms","start":"2026-02-12T02:13:28.363347Z","end":"2026-02-12T02:13:28.672154Z","steps":["trace[983653072] 'process raft request' (duration: 308.619801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:28.672193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:28.363333Z","time spent":"308.839745ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:13:28.672259Z","caller":"traceutil/trace.go:171","msg":"trace[527241187] linearizableReadLoop","detail":"{readStateIndex:736; appliedIndex:733; }","duration":"307.013487ms","start":"2026-02-12T02:13:28.365231Z","end":"2026-02-12T02:13:28.672245Z","steps":["trace[527241187] 'read index received' (duration: 272.668888ms)","trace[527241187] 'applied index is now lower than readState.Index' (duration: 34.343609ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:13:28.672382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.13924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T02:13:28.672433Z","caller":"traceutil/trace.go:171","msg":"trace[1834624800] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:711; }","duration":"307.220151ms","start":"2026-02-12T02:13:28.365204Z","end":"2026-02-12T02:13:28.672424Z","steps":["trace[1834624800] 'agreement among raft nodes before linearized reading' (duration: 307.087879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:28.672488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:28.365194Z","time spent":"307.264712ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":40,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-02-12T02:13:28.672527Z","caller":"traceutil/trace.go:171","msg":"trace[1512066231] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"300.243803ms","start":"2026-02-12T02:13:28.372276Z","end":"2026-02-12T02:13:28.67252Z","steps":["trace[1512066231] 'process raft request' (duration: 299.717342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:28.672568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:28.372261Z","time spent":"300.284984ms","remote":"127.0.0.1:33826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4769,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:13:33.677542Z","caller":"traceutil/trace.go:171","msg":"trace[234633890] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"347.104817ms","start":"2026-02-12T02:13:33.330413Z","end":"2026-02-12T02:13:33.677517Z","steps":["trace[234633890] 'process raft request' (duration: 346.951944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:33.677664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:33.330388Z","time spent":"347.21577ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:13:34.502968Z","caller":"traceutil/trace.go:171","msg":"trace[1484556321] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"120.477087ms","start":"2026-02-12T02:13:34.382469Z","end":"2026-02-12T02:13:34.502946Z","steps":["trace[1484556321] 'process raft request' (duration: 120.375045ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:47.529167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.56641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T02:13:47.529227Z","caller":"traceutil/trace.go:171","msg":"trace[319661717] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:766; }","duration":"325.675353ms","start":"2026-02-12T02:13:47.203538Z","end":"2026-02-12T02:13:47.529214Z","steps":["trace[319661717] 'count revisions from in-memory index tree' (duration: 325.474749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:47.529256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:47.203526Z","time spent":"325.723643ms","remote":"127.0.0.1:33740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":80,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-12T02:13:49.151201Z","caller":"traceutil/trace.go:171","msg":"trace[1636452221] linearizableReadLoop","detail":"{readStateIndex:799; appliedIndex:798; }","duration":"358.739894ms","start":"2026-02-12T02:13:48.792443Z","end":"2026-02-12T02:13:49.151183Z","steps":["trace[1636452221] 'read index received' (duration: 358.56606ms)","trace[1636452221] 'applied index is now lower than readState.Index' (duration: 173.114µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:13:49.151407Z","caller":"traceutil/trace.go:171","msg":"trace[1099077008] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"595.59067ms","start":"2026-02-12T02:13:48.555809Z","end":"2026-02-12T02:13:49.151399Z","steps":["trace[1099077008] 'process raft request' (duration: 595.249463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:49.151485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:48.555798Z","time spent":"595.629992ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:13:49.151656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.220484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T02:13:49.151678Z","caller":"traceutil/trace.go:171","msg":"trace[1176808259] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:769; }","duration":"359.267455ms","start":"2026-02-12T02:13:48.792403Z","end":"2026-02-12T02:13:49.15167Z","steps":["trace[1176808259] 'agreement among raft nodes before linearized reading' (duration: 359.190543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:49.151696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:48.792359Z","time spent":"359.333106ms","remote":"127.0.0.1:33916","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-12T02:13:49.151805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.712169ms","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-12T02:13:49.151823Z","caller":"traceutil/trace.go:171","msg":"trace[2042230807] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:769; }","duration":"305.755089ms","start":"2026-02-12T02:13:48.846062Z","end":"2026-02-12T02:13:49.151817Z","steps":["trace[2042230807] 'agreement among raft nodes before linearized reading' (duration: 305.703368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:49.151852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:48.846047Z","time spent":"305.78737ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-12T02:13:49.152012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.519295ms","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-12T02:13:49.152035Z","caller":"traceutil/trace.go:171","msg":"trace[128984490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:769; }","duration":"273.566576ms","start":"2026-02-12T02:13:48.878463Z","end":"2026-02-12T02:13:49.152029Z","steps":["trace[128984490] 'agreement among raft nodes before linearized reading' (duration: 273.504294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:50.590827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.872135ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-02-12T02:13:51.509107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.531931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T02:13:51.509244Z","caller":"traceutil/trace.go:171","msg":"trace[932419161] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"307.688501ms","start":"2026-02-12T02:13:51.201543Z","end":"2026-02-12T02:13:51.509231Z","steps":["trace[932419161] 'process raft request' (duration: 307.66351ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:13:51.50924Z","caller":"traceutil/trace.go:171","msg":"trace[815330526] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"310.833138ms","start":"2026-02-12T02:13:51.198387Z","end":"2026-02-12T02:13:51.509221Z","steps":["trace[815330526] 'process raft request' (duration: 106.133533ms)","trace[815330526] 'compare' (duration: 204.447189ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:13:51.509285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:51.201536Z","time spent":"307.731172ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:13:51.509349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:51.198376Z","time spent":"310.913099ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:13:51.509463Z","caller":"traceutil/trace.go:171","msg":"trace[288263311] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"308.341715ms","start":"2026-02-12T02:13:51.201115Z","end":"2026-02-12T02:13:51.509456Z","steps":["trace[288263311] 'process raft request' (duration: 308.061739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:51.509498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:51.201097Z","time spent":"308.383215ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:13:51.950888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.467856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-12T02:13:51.950935Z","caller":"traceutil/trace.go:171","msg":"trace[2023519869] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:777; }","duration":"217.521957ms","start":"2026-02-12T02:13:51.733402Z","end":"2026-02-12T02:13:51.950924Z","steps":["trace[2023519869] 'range keys from in-memory index tree' (duration: 217.318503ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:13:52.48635Z","caller":"traceutil/trace.go:171","msg":"trace[693156283] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:808; }","duration":"273.71859ms","start":"2026-02-12T02:13:52.212614Z","end":"2026-02-12T02:13:52.486332Z","steps":["trace[693156283] 'read index received' (duration: 273.592878ms)","trace[693156283] 'applied index is now lower than readState.Index' (duration: 125.192µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:13:52.486431Z","caller":"traceutil/trace.go:171","msg":"trace[78549073] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"533.260149ms","start":"2026-02-12T02:13:51.953162Z","end":"2026-02-12T02:13:52.486422Z","steps":["trace[78549073] 'process raft request' (duration: 533.058905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:52.486503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:51.953144Z","time spent":"533.30444ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:13:52.486599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.077168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:13:52.486627Z","caller":"traceutil/trace.go:171","msg":"trace[478104606] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:778; }","duration":"152.140719ms","start":"2026-02-12T02:13:52.334479Z","end":"2026-02-12T02:13:52.48662Z","steps":["trace[478104606] 'agreement among raft nodes before linearized reading' (duration: 152.092198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:52.486716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.098868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T02:13:52.486737Z","caller":"traceutil/trace.go:171","msg":"trace[1026916049] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:778; }","duration":"274.156589ms","start":"2026-02-12T02:13:52.212575Z","end":"2026-02-12T02:13:52.486732Z","steps":["trace[1026916049] 'agreement among raft nodes before linearized reading' (duration: 274.114018ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:13:57.210122Z","caller":"traceutil/trace.go:171","msg":"trace[19107340] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"710.318507ms","start":"2026-02-12T02:13:56.499785Z","end":"2026-02-12T02:13:57.210104Z","steps":["trace[19107340] 'process raft request' (duration: 709.998101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:57.210224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:56.499769Z","time spent":"710.404589ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:13:57.698822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.407443ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:13:57.699465Z","caller":"traceutil/trace.go:171","msg":"trace[610355004] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:791; }","duration":"258.051197ms","start":"2026-02-12T02:13:57.441391Z","end":"2026-02-12T02:13:57.699442Z","steps":["trace[610355004] 'range keys from in-memory index tree' (duration: 257.393822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:57.700184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.863798ms","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-12T02:13:57.700257Z","caller":"traceutil/trace.go:171","msg":"trace[1196645965] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:791; }","duration":"163.960541ms","start":"2026-02-12T02:13:57.53628Z","end":"2026-02-12T02:13:57.700241Z","steps":["trace[1196645965] 'range keys from in-memory index tree' (duration: 163.367797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:57.700234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.204175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T02:13:57.700978Z","caller":"traceutil/trace.go:171","msg":"trace[1210872634] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:791; }","duration":"164.983372ms","start":"2026-02-12T02:13:57.535982Z","end":"2026-02-12T02:13:57.700965Z","steps":["trace[1210872634] 'range keys from in-memory index tree' (duration: 163.695265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:57.700539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.286187ms","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-12T02:13:57.70147Z","caller":"traceutil/trace.go:171","msg":"trace[15213935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:791; }","duration":"165.233227ms","start":"2026-02-12T02:13:57.536218Z","end":"2026-02-12T02:13:57.701451Z","steps":["trace[15213935] 'range keys from in-memory index tree' (duration: 163.825887ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:13:57.876047Z","caller":"traceutil/trace.go:171","msg":"trace[1133914667] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"167.790291ms","start":"2026-02-12T02:13:57.708239Z","end":"2026-02-12T02:13:57.87603Z","steps":["trace[1133914667] 'process raft request' (duration: 167.75358ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:13:57.876541Z","caller":"traceutil/trace.go:171","msg":"trace[729929368] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"169.550719ms","start":"2026-02-12T02:13:57.706962Z","end":"2026-02-12T02:13:57.876513Z","steps":["trace[729929368] 'process raft request' (duration: 148.90949ms)","trace[729929368] 'compare' (duration: 20.034126ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:13:58.580576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.864035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:13:58.580647Z","caller":"traceutil/trace.go:171","msg":"trace[2032802401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"439.007568ms","start":"2026-02-12T02:13:58.141622Z","end":"2026-02-12T02:13:58.58063Z","steps":["trace[2032802401] 'range keys from in-memory index tree' (duration: 438.779573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:58.580685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:58.141604Z","time spent":"439.07356ms","remote":"127.0.0.1:33668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T02:13:58.580972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.345349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T02:13:58.581002Z","caller":"traceutil/trace.go:171","msg":"trace[390409043] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:794; }","duration":"279.41977ms","start":"2026-02-12T02:13:58.301573Z","end":"2026-02-12T02:13:58.580993Z","steps":["trace[390409043] 'count revisions from in-memory index tree' (duration: 279.221586ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:13:59.107639Z","caller":"traceutil/trace.go:171","msg":"trace[120928485] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"355.221469ms","start":"2026-02-12T02:13:58.752398Z","end":"2026-02-12T02:13:59.107619Z","steps":["trace[120928485] 'process raft request' (duration: 355.107097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:59.107965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:58.752368Z","time spent":"355.541585ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:13:59.547486Z","caller":"traceutil/trace.go:171","msg":"trace[1687546048] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"298.304402ms","start":"2026-02-12T02:13:59.249153Z","end":"2026-02-12T02:13:59.547457Z","steps":["trace[1687546048] 'read index received' (duration: 298.182889ms)","trace[1687546048] 'applied index is now lower than readState.Index' (duration: 120.263µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:13:59.547706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.533047ms","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-12T02:13:59.547749Z","caller":"traceutil/trace.go:171","msg":"trace[1595616757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:796; }","duration":"298.608048ms","start":"2026-02-12T02:13:59.249129Z","end":"2026-02-12T02:13:59.547737Z","steps":["trace[1595616757] 'agreement among raft nodes before linearized reading' (duration: 298.423724ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:13:59.547868Z","caller":"traceutil/trace.go:171","msg":"trace[1140197683] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"308.156751ms","start":"2026-02-12T02:13:59.239701Z","end":"2026-02-12T02:13:59.547858Z","steps":["trace[1140197683] 'process raft request' (duration: 307.60688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:13:59.54795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:13:59.239685Z","time spent":"308.210362ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:14:15.97599Z","caller":"traceutil/trace.go:171","msg":"trace[2103827062] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"127.522268ms","start":"2026-02-12T02:14:15.848451Z","end":"2026-02-12T02:14:15.975974Z","steps":["trace[2103827062] 'process raft request' (duration: 45.691081ms)","trace[2103827062] 'compare' (duration: 81.710655ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:14:16.252018Z","caller":"traceutil/trace.go:171","msg":"trace[1804919332] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"270.593083ms","start":"2026-02-12T02:14:15.981404Z","end":"2026-02-12T02:14:16.251997Z","steps":["trace[1804919332] 'process raft request' (duration: 192.737482ms)","trace[1804919332] 'compare' (duration: 77.78492ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:14:16.383132Z","caller":"traceutil/trace.go:171","msg":"trace[418434173] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"394.82752ms","start":"2026-02-12T02:14:15.988285Z","end":"2026-02-12T02:14:16.383112Z","steps":["trace[418434173] 'process raft request' (duration: 392.968411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:16.383285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:15.988269Z","time spent":"394.964783ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:14:18.211612Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338667357327852,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T02:14:18.238713Z","caller":"traceutil/trace.go:171","msg":"trace[730270518] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:934; }","duration":"528.175701ms","start":"2026-02-12T02:14:17.710515Z","end":"2026-02-12T02:14:18.238691Z","steps":["trace[730270518] 'read index received' (duration: 528.065318ms)","trace[730270518] 'applied index is now lower than readState.Index' (duration: 109.713µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:14:18.238789Z","caller":"traceutil/trace.go:171","msg":"trace[344013760] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"558.483705ms","start":"2026-02-12T02:14:17.680297Z","end":"2026-02-12T02:14:18.238781Z","steps":["trace[344013760] 'process raft request' (duration: 558.28996ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:18.238869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:17.680288Z","time spent":"558.523005ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:14:18.238938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.671133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T02:14:18.238993Z","caller":"traceutil/trace.go:171","msg":"trace[252996722] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:897; }","duration":"463.758224ms","start":"2026-02-12T02:14:17.775224Z","end":"2026-02-12T02:14:18.238982Z","steps":["trace[252996722] 'agreement among raft nodes before linearized reading' (duration: 463.648442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:18.239014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:17.775211Z","time spent":"463.798784ms","remote":"127.0.0.1:33998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-02-12T02:14:18.239185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.673032ms","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-12T02:14:18.2392Z","caller":"traceutil/trace.go:171","msg":"trace[824294758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:897; }","duration":"528.712042ms","start":"2026-02-12T02:14:17.710484Z","end":"2026-02-12T02:14:18.239196Z","steps":["trace[824294758] 'agreement among raft nodes before linearized reading' (duration: 528.630261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:18.239214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:17.710468Z","time spent":"528.741493ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T02:14:18.239279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.087288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:14:18.239293Z","caller":"traceutil/trace.go:171","msg":"trace[1454031458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:897; }","duration":"102.124458ms","start":"2026-02-12T02:14:18.137164Z","end":"2026-02-12T02:14:18.239289Z","steps":["trace[1454031458] 'agreement among raft nodes before linearized reading' (duration: 102.102568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:18.239381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.163071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T02:14:18.239392Z","caller":"traceutil/trace.go:171","msg":"trace[247732984] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:897; }","duration":"259.195642ms","start":"2026-02-12T02:14:17.980193Z","end":"2026-02-12T02:14:18.239389Z","steps":["trace[247732984] 'agreement among raft nodes before linearized reading' (duration: 259.158932ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:14:19.852318Z","caller":"traceutil/trace.go:171","msg":"trace[2094631606] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"350.704625ms","start":"2026-02-12T02:14:19.501591Z","end":"2026-02-12T02:14:19.852296Z","steps":["trace[2094631606] 'read index received' (duration: 350.49214ms)","trace[2094631606] 'applied index is now lower than readState.Index' (duration: 211.685µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:14:19.852426Z","caller":"traceutil/trace.go:171","msg":"trace[741941727] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"361.471853ms","start":"2026-02-12T02:14:19.490944Z","end":"2026-02-12T02:14:19.852416Z","steps":["trace[741941727] 'process raft request' (duration: 361.186756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:19.852522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:19.490926Z","time spent":"361.526453ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:14:19.852586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.579605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:14:19.852657Z","caller":"traceutil/trace.go:171","msg":"trace[1342603126] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:902; }","duration":"215.707338ms","start":"2026-02-12T02:14:19.636931Z","end":"2026-02-12T02:14:19.852638Z","steps":["trace[1342603126] 'agreement among raft nodes before linearized reading' (duration: 215.592506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:19.852866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.268487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:14:19.852895Z","caller":"traceutil/trace.go:171","msg":"trace[512149592] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:902; }","duration":"351.315737ms","start":"2026-02-12T02:14:19.50157Z","end":"2026-02-12T02:14:19.852886Z","steps":["trace[512149592] 'agreement among raft nodes before linearized reading' (duration: 351.263296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:19.852923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:19.501563Z","time spent":"351.348878ms","remote":"127.0.0.1:33890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-02-12T02:14:20.443328Z","caller":"traceutil/trace.go:171","msg":"trace[897265449] linearizableReadLoop","detail":"{readStateIndex:943; appliedIndex:942; }","duration":"108.484351ms","start":"2026-02-12T02:14:20.334828Z","end":"2026-02-12T02:14:20.443312Z","steps":["trace[897265449] 'read index received' (duration: 37.404983ms)","trace[897265449] 'applied index is now lower than readState.Index' (duration: 71.078808ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:14:20.443524Z","caller":"traceutil/trace.go:171","msg":"trace[575094045] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"128.19486ms","start":"2026-02-12T02:14:20.315323Z","end":"2026-02-12T02:14:20.443517Z","steps":["trace[575094045] 'process raft request' (duration: 56.947758ms)","trace[575094045] 'compare' (duration: 70.972046ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:14:20.443728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.899102ms","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-12T02:14:20.44375Z","caller":"traceutil/trace.go:171","msg":"trace[1513279909] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:905; }","duration":"108.955262ms","start":"2026-02-12T02:14:20.334788Z","end":"2026-02-12T02:14:20.443743Z","steps":["trace[1513279909] 'agreement among raft nodes before linearized reading' (duration: 108.86634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:26.634135Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338667357327918,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T02:14:26.661168Z","caller":"traceutil/trace.go:171","msg":"trace[1695462880] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:958; }","duration":"527.566567ms","start":"2026-02-12T02:14:26.13358Z","end":"2026-02-12T02:14:26.661146Z","steps":["trace[1695462880] 'read index received' (duration: 527.398984ms)","trace[1695462880] 'applied index is now lower than readState.Index' (duration: 166.653µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:14:26.661594Z","caller":"traceutil/trace.go:171","msg":"trace[83398912] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"558.137367ms","start":"2026-02-12T02:14:26.103444Z","end":"2026-02-12T02:14:26.661581Z","steps":["trace[83398912] 'process raft request' (duration: 557.590015ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:26.662313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:26.103417Z","time spent":"558.846381ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:14:26.661682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.086308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T02:14:26.663731Z","caller":"traceutil/trace.go:171","msg":"trace[907149450] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:919; }","duration":"530.157422ms","start":"2026-02-12T02:14:26.133541Z","end":"2026-02-12T02:14:26.663699Z","steps":["trace[907149450] 'agreement among raft nodes before linearized reading' (duration: 528.091418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:26.663871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:26.133523Z","time spent":"530.326416ms","remote":"127.0.0.1:54290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-12T02:14:26.661867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.943668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-12T02:14:26.66199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.169672ms","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-12T02:14:26.662051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.706464ms","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-12T02:14:26.662104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.108563ms","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-12T02:14:26.662157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.56537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-12T02:14:26.662209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.648491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-12T02:14:26.66561Z","caller":"traceutil/trace.go:171","msg":"trace[1088980341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:919; }","duration":"526.708649ms","start":"2026-02-12T02:14:26.138891Z","end":"2026-02-12T02:14:26.665599Z","steps":["trace[1088980341] 'agreement among raft nodes before linearized reading' (duration: 522.949979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:26.666363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:26.138879Z","time spent":"527.473635ms","remote":"127.0.0.1:33668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-12T02:14:26.665674Z","caller":"traceutil/trace.go:171","msg":"trace[1750381118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:919; }","duration":"149.873002ms","start":"2026-02-12T02:14:26.515795Z","end":"2026-02-12T02:14:26.665668Z","steps":["trace[1750381118] 'agreement among raft nodes before linearized reading' (duration: 146.090981ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:14:26.665933Z","caller":"traceutil/trace.go:171","msg":"trace[2058283676] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:919; }","duration":"151.614778ms","start":"2026-02-12T02:14:26.51431Z","end":"2026-02-12T02:14:26.665924Z","steps":["trace[2058283676] 'agreement among raft nodes before linearized reading' (duration: 147.695814ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:14:26.666014Z","caller":"traceutil/trace.go:171","msg":"trace[747920856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:919; }","duration":"201.054628ms","start":"2026-02-12T02:14:26.464952Z","end":"2026-02-12T02:14:26.666007Z","steps":["trace[747920856] 'agreement among raft nodes before linearized reading' (duration: 197.112014ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:14:26.666072Z","caller":"traceutil/trace.go:171","msg":"trace[1100952136] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:919; }","duration":"338.504225ms","start":"2026-02-12T02:14:26.327562Z","end":"2026-02-12T02:14:26.666066Z","steps":["trace[1100952136] 'agreement among raft nodes before linearized reading' (duration: 334.554541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:26.667556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:26.327543Z","time spent":"339.897614ms","remote":"127.0.0.1:33916","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-12T02:14:26.666193Z","caller":"traceutil/trace.go:171","msg":"trace[1622421279] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:919; }","duration":"369.667466ms","start":"2026-02-12T02:14:26.296519Z","end":"2026-02-12T02:14:26.666187Z","steps":["trace[1622421279] 'agreement among raft nodes before linearized reading' (duration: 365.65157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:26.668118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:26.296499Z","time spent":"371.605838ms","remote":"127.0.0.1:33916","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-12T02:14:27.205209Z","caller":"traceutil/trace.go:171","msg":"trace[1218842443] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"529.824355ms","start":"2026-02-12T02:14:26.675367Z","end":"2026-02-12T02:14:27.205191Z","steps":["trace[1218842443] 'process raft request' (duration: 529.661121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:27.206316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:26.675351Z","time spent":"530.074201ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:14:27.20652Z","caller":"traceutil/trace.go:171","msg":"trace[254708528] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"528.892625ms","start":"2026-02-12T02:14:26.677619Z","end":"2026-02-12T02:14:27.206511Z","steps":["trace[254708528] 'process raft request' (duration: 528.66567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:27.207245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:26.677612Z","time spent":"528.925247ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:14:27.20732Z","caller":"traceutil/trace.go:171","msg":"trace[868586446] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"531.888179ms","start":"2026-02-12T02:14:26.675424Z","end":"2026-02-12T02:14:27.207312Z","steps":["trace[868586446] 'process raft request' (duration: 530.722565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:27.207362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:26.675303Z","time spent":"532.034262ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:14:27.207417Z","caller":"traceutil/trace.go:171","msg":"trace[554784094] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"530.844646ms","start":"2026-02-12T02:14:26.676566Z","end":"2026-02-12T02:14:27.207411Z","steps":["trace[554784094] 'process raft request' (duration: 529.61949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:27.207448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:26.676547Z","time spent":"530.882837ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:14:27.207493Z","caller":"traceutil/trace.go:171","msg":"trace[1881065675] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"530.854557ms","start":"2026-02-12T02:14:26.676632Z","end":"2026-02-12T02:14:27.207486Z","steps":["trace[1881065675] 'process raft request' (duration: 529.635011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:27.207523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:26.676627Z","time spent":"530.881367ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:14:35.734367Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338667357327976,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T02:14:35.809128Z","caller":"traceutil/trace.go:171","msg":"trace[241392973] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:981; }","duration":"575.71947ms","start":"2026-02-12T02:14:35.233391Z","end":"2026-02-12T02:14:35.809111Z","steps":["trace[241392973] 'read index received' (duration: 575.606067ms)","trace[241392973] 'applied index is now lower than readState.Index' (duration: 112.603µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:14:35.80921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:35.019051Z","time spent":"790.154981ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-12T02:14:35.809467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.068498ms","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-12T02:14:35.809496Z","caller":"traceutil/trace.go:171","msg":"trace[426564681] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:940; }","duration":"576.132759ms","start":"2026-02-12T02:14:35.233356Z","end":"2026-02-12T02:14:35.809489Z","steps":["trace[426564681] 'agreement among raft nodes before linearized reading' (duration: 576.024876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:35.80952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:35.23333Z","time spent":"576.18582ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T02:14:35.809745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.532688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T02:14:35.809772Z","caller":"traceutil/trace.go:171","msg":"trace[1305581544] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:940; }","duration":"511.581499ms","start":"2026-02-12T02:14:35.298183Z","end":"2026-02-12T02:14:35.809765Z","steps":["trace[1305581544] 'agreement among raft nodes before linearized reading' (duration: 511.540028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:35.80979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:35.29817Z","time spent":"511.61699ms","remote":"127.0.0.1:33916","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-12T02:14:35.809991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.266752ms","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-12T02:14:35.810015Z","caller":"traceutil/trace.go:171","msg":"trace[110118251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:940; }","duration":"479.300393ms","start":"2026-02-12T02:14:35.330709Z","end":"2026-02-12T02:14:35.810009Z","steps":["trace[110118251] 'agreement among raft nodes before linearized reading' (duration: 479.238491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:35.810032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:35.330704Z","time spent":"479.324443ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T02:14:35.810143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.006329ms","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-12T02:14:35.810172Z","caller":"traceutil/trace.go:171","msg":"trace[292488000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:940; }","duration":"481.0365ms","start":"2026-02-12T02:14:35.329121Z","end":"2026-02-12T02:14:35.810157Z","steps":["trace[292488000] 'agreement among raft nodes before linearized reading' (duration: 480.982139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:35.810195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:35.329115Z","time spent":"481.076211ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T02:14:35.810329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.057951ms","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-12T02:14:35.810352Z","caller":"traceutil/trace.go:171","msg":"trace[1495056741] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:940; }","duration":"489.101472ms","start":"2026-02-12T02:14:35.321245Z","end":"2026-02-12T02:14:35.810347Z","steps":["trace[1495056741] 'agreement among raft nodes before linearized reading' (duration: 489.02744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:35.81037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:35.321232Z","time spent":"489.134683ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-12T02:14:35.955455Z","caller":"traceutil/trace.go:171","msg":"trace[1043080666] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"138.013169ms","start":"2026-02-12T02:14:35.817417Z","end":"2026-02-12T02:14:35.95543Z","steps":["trace[1043080666] 'process raft request' (duration: 137.880737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:35.955543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.110754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-12T02:14:35.955566Z","caller":"traceutil/trace.go:171","msg":"trace[862412901] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:946; }","duration":"124.162085ms","start":"2026-02-12T02:14:35.831399Z","end":"2026-02-12T02:14:35.955561Z","steps":["trace[862412901] 'agreement among raft nodes before linearized reading' (duration: 124.095214ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:14:35.955741Z","caller":"traceutil/trace.go:171","msg":"trace[1622507341] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"137.748134ms","start":"2026-02-12T02:14:35.817987Z","end":"2026-02-12T02:14:35.955735Z","steps":["trace[1622507341] 'process raft request' (duration: 137.396616ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:14:35.955463Z","caller":"traceutil/trace.go:171","msg":"trace[39507243] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:983; }","duration":"124.032873ms","start":"2026-02-12T02:14:35.831418Z","end":"2026-02-12T02:14:35.955451Z","steps":["trace[39507243] 'read index received' (duration: 123.838119ms)","trace[39507243] 'applied index is now lower than readState.Index' (duration: 194.434µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:14:35.955863Z","caller":"traceutil/trace.go:171","msg":"trace[593878662] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"137.200822ms","start":"2026-02-12T02:14:35.818658Z","end":"2026-02-12T02:14:35.955859Z","steps":["trace[593878662] 'process raft request' (duration: 136.742243ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:14:35.95592Z","caller":"traceutil/trace.go:171","msg":"trace[1818113245] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"132.681686ms","start":"2026-02-12T02:14:35.823234Z","end":"2026-02-12T02:14:35.955916Z","steps":["trace[1818113245] 'process raft request' (duration: 132.181755ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:14:35.955971Z","caller":"traceutil/trace.go:171","msg":"trace[1557482063] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"131.803358ms","start":"2026-02-12T02:14:35.824164Z","end":"2026-02-12T02:14:35.955967Z","steps":["trace[1557482063] 'process raft request' (duration: 131.272077ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:14:36.900576Z","caller":"traceutil/trace.go:171","msg":"trace[671259732] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"228.408547ms","start":"2026-02-12T02:14:36.67215Z","end":"2026-02-12T02:14:36.900559Z","steps":["trace[671259732] 'process raft request' (duration: 228.321525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:37.288515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.436093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:14:37.288585Z","caller":"traceutil/trace.go:171","msg":"trace[579266051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:947; }","duration":"150.559185ms","start":"2026-02-12T02:14:37.138008Z","end":"2026-02-12T02:14:37.288567Z","steps":["trace[579266051] 'range keys from in-memory index tree' (duration: 150.343581ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:14:38.223319Z","caller":"traceutil/trace.go:171","msg":"trace[968459598] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"207.78414ms","start":"2026-02-12T02:14:38.015507Z","end":"2026-02-12T02:14:38.223291Z","steps":["trace[968459598] 'process raft request' (duration: 207.672698ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:14:38.246899Z","caller":"traceutil/trace.go:171","msg":"trace[117307498] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"175.214299ms","start":"2026-02-12T02:14:38.071648Z","end":"2026-02-12T02:14:38.246862Z","steps":["trace[117307498] 'process raft request' (duration: 175.112027ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:14:38.247096Z","caller":"traceutil/trace.go:171","msg":"trace[156148608] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:991; }","duration":"108.417011ms","start":"2026-02-12T02:14:38.138644Z","end":"2026-02-12T02:14:38.247061Z","steps":["trace[156148608] 'read index received' (duration: 84.479702ms)","trace[156148608] 'applied index is now lower than readState.Index' (duration: 23.936039ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:14:38.247246Z","caller":"traceutil/trace.go:171","msg":"trace[579618228] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"175.531526ms","start":"2026-02-12T02:14:38.071707Z","end":"2026-02-12T02:14:38.247239Z","steps":["trace[579618228] 'process raft request' (duration: 175.135358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:38.24726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.599566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:14:38.247298Z","caller":"traceutil/trace.go:171","msg":"trace[1354502935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:952; }","duration":"108.673017ms","start":"2026-02-12T02:14:38.138612Z","end":"2026-02-12T02:14:38.247285Z","steps":["trace[1354502935] 'agreement among raft nodes before linearized reading' (duration: 108.562035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:38.998929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:38.334877Z","time spent":"664.047324ms","remote":"127.0.0.1:56194","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-12T02:14:40.136974Z","caller":"traceutil/trace.go:171","msg":"trace[306318233] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"115.903151ms","start":"2026-02-12T02:14:40.021055Z","end":"2026-02-12T02:14:40.136959Z","steps":["trace[306318233] 'process raft request' (duration: 115.816688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:41.437348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.016712ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-12T02:14:41.43758Z","caller":"traceutil/trace.go:171","msg":"trace[1337836329] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:995; }","duration":"1.369383346s","start":"2026-02-12T02:14:40.068176Z","end":"2026-02-12T02:14:41.43756Z","steps":["trace[1337836329] 'read index received' (duration: 69.100107ms)","trace[1337836329] 'applied index is now lower than readState.Index' (duration: 1.300281939s)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:14:41.437792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.3696041s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-12T02:14:41.437834Z","caller":"traceutil/trace.go:171","msg":"trace[1766736823] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:953; }","duration":"1.369679741s","start":"2026-02-12T02:14:40.068139Z","end":"2026-02-12T02:14:41.437819Z","steps":["trace[1766736823] 'agreement among raft nodes before linearized reading' (duration: 1.369538158s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:41.437876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:40.068106Z","time spent":"1.369758943s","remote":"127.0.0.1:33916","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-12T02:14:41.438464Z","caller":"traceutil/trace.go:171","msg":"trace[1351021373] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"1.237754741s","start":"2026-02-12T02:14:40.200691Z","end":"2026-02-12T02:14:41.438446Z","steps":["trace[1351021373] 'process raft request' (duration: 1.23676634s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:41.438551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:40.200668Z","time spent":"1.237830713s","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:14:41.723027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.580551438s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:14:41.723176Z","caller":"traceutil/trace.go:171","msg":"trace[2106544489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:954; }","duration":"1.58068521s","start":"2026-02-12T02:14:40.142424Z","end":"2026-02-12T02:14:41.72311Z","steps":["trace[2106544489] 'agreement among raft nodes before linearized reading' (duration: 1.295984927s)","trace[2106544489] 'range keys from in-memory index tree' (duration: 284.560071ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:14:41.723224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:40.142414Z","time spent":"1.580800813s","remote":"127.0.0.1:33668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T02:14:41.724364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.472953993s","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-12T02:14:41.724429Z","caller":"traceutil/trace.go:171","msg":"trace[1102519286] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:954; }","duration":"1.473034124s","start":"2026-02-12T02:14:40.251374Z","end":"2026-02-12T02:14:41.724408Z","steps":["trace[1102519286] 'agreement among raft nodes before linearized reading' (duration: 1.187334841s)","trace[1102519286] 'range keys from in-memory index tree' (duration: 284.780135ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:14:41.724461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:40.25137Z","time spent":"1.473084316s","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-12T02:14:41.724567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.497609107s","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-12T02:14:41.72461Z","caller":"traceutil/trace.go:171","msg":"trace[926143707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:954; }","duration":"1.497669477s","start":"2026-02-12T02:14:40.226927Z","end":"2026-02-12T02:14:41.724596Z","steps":["trace[926143707] 'agreement among raft nodes before linearized reading' (duration: 1.21182734s)","trace[926143707] 'range keys from in-memory index tree' (duration: 285.711664ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:14:41.724643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:40.226916Z","time spent":"1.497719998s","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-12T02:14:41.724648Z","caller":"traceutil/trace.go:171","msg":"trace[397468469] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"281.478664ms","start":"2026-02-12T02:14:41.443158Z","end":"2026-02-12T02:14:41.724637Z","steps":["trace[397468469] 'process raft request' (duration: 184.894285ms)","trace[397468469] 'compare' (duration: 95.811413ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:14:41.724983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.474154588s","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-12T02:14:41.725003Z","caller":"traceutil/trace.go:171","msg":"trace[1708602978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:954; }","duration":"1.474196679s","start":"2026-02-12T02:14:40.2508Z","end":"2026-02-12T02:14:41.724996Z","steps":["trace[1708602978] 'agreement among raft nodes before linearized reading' (duration: 1.187945583s)","trace[1708602978] 'range keys from in-memory index tree' (duration: 284.215083ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:14:41.725021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:40.250787Z","time spent":"1.47422903s","remote":"127.0.0.1:33916","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-12T02:14:42.020549Z","caller":"traceutil/trace.go:171","msg":"trace[1934545379] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"289.799892ms","start":"2026-02-12T02:14:41.730728Z","end":"2026-02-12T02:14:42.020527Z","steps":["trace[1934545379] 'process raft request' (duration: 289.316772ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:14:42.099492Z","caller":"traceutil/trace.go:171","msg":"trace[75233848] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:998; }","duration":"363.106607ms","start":"2026-02-12T02:14:41.736364Z","end":"2026-02-12T02:14:42.09947Z","steps":["trace[75233848] 'read index received' (duration: 283.630941ms)","trace[75233848] 'applied index is now lower than readState.Index' (duration: 79.474786ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:14:42.099642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.23855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:14:42.099674Z","caller":"traceutil/trace.go:171","msg":"trace[917198332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"363.303751ms","start":"2026-02-12T02:14:41.73636Z","end":"2026-02-12T02:14:42.099664Z","steps":["trace[917198332] 'agreement among raft nodes before linearized reading' (duration: 363.195649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:42.099708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:41.736333Z","time spent":"363.367123ms","remote":"127.0.0.1:33668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-12T02:14:42.100045Z","caller":"traceutil/trace.go:171","msg":"trace[1811325507] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"363.749561ms","start":"2026-02-12T02:14:41.736282Z","end":"2026-02-12T02:14:42.100031Z","steps":["trace[1811325507] 'process raft request' (duration: 363.038766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:42.100117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:41.736259Z","time spent":"363.810632ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:14:42.100161Z","caller":"traceutil/trace.go:171","msg":"trace[447482414] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"363.133177ms","start":"2026-02-12T02:14:41.737001Z","end":"2026-02-12T02:14:42.100135Z","steps":["trace[447482414] 'process raft request' (duration: 362.430992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:42.100291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:41.736993Z","time spent":"363.225509ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:14:42.361743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.63068ms","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-12T02:14:42.361802Z","caller":"traceutil/trace.go:171","msg":"trace[1405584652] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:958; }","duration":"217.738862ms","start":"2026-02-12T02:14:42.144048Z","end":"2026-02-12T02:14:42.361787Z","steps":["trace[1405584652] 'range keys from in-memory index tree' (duration: 217.449136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:43.59234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.334412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:14:43.592432Z","caller":"traceutil/trace.go:171","msg":"trace[1462813988] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:959; }","duration":"633.478365ms","start":"2026-02-12T02:14:42.95893Z","end":"2026-02-12T02:14:43.592408Z","steps":["trace[1462813988] 'count revisions from in-memory index tree' (duration: 633.249141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:43.592523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:42.958908Z","time spent":"633.598218ms","remote":"127.0.0.1:34144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-12T02:14:43.593475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.987974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:14:43.593813Z","caller":"traceutil/trace.go:171","msg":"trace[1056237819] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:959; }","duration":"453.377822ms","start":"2026-02-12T02:14:43.140411Z","end":"2026-02-12T02:14:43.593789Z","steps":["trace[1056237819] 'range keys from in-memory index tree' (duration: 452.874032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:43.594001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:43.140391Z","time spent":"453.594857ms","remote":"127.0.0.1:33676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T02:14:43.594867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.775241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:14:43.595291Z","caller":"traceutil/trace.go:171","msg":"trace[383024516] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:959; }","duration":"657.23756ms","start":"2026-02-12T02:14:42.938044Z","end":"2026-02-12T02:14:43.595281Z","steps":["trace[383024516] 'range keys from in-memory index tree' (duration: 656.599316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:43.595392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:42.938022Z","time spent":"657.360043ms","remote":"127.0.0.1:33668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-12T02:14:44.485455Z","caller":"traceutil/trace.go:171","msg":"trace[1795912654] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1002; }","duration":"461.022876ms","start":"2026-02-12T02:14:44.024411Z","end":"2026-02-12T02:14:44.485434Z","steps":["trace[1795912654] 'read index received' (duration: 460.859152ms)","trace[1795912654] 'applied index is now lower than readState.Index' (duration: 163.024µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:14:44.485792Z","caller":"traceutil/trace.go:171","msg":"trace[383968157] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"751.617012ms","start":"2026-02-12T02:14:43.734165Z","end":"2026-02-12T02:14:44.485782Z","steps":["trace[383968157] 'process raft request' (duration: 751.163523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:44.485889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:43.73415Z","time spent":"751.668284ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:14:44.486174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.71966ms","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-12T02:14:44.486207Z","caller":"traceutil/trace.go:171","msg":"trace[962965723] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:960; }","duration":"461.837663ms","start":"2026-02-12T02:14:44.024359Z","end":"2026-02-12T02:14:44.486197Z","steps":["trace[962965723] 'agreement among raft nodes before linearized reading' (duration: 461.682679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:44.486235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:44.024337Z","time spent":"461.890353ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-12T02:14:44.486404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.828924ms","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-12T02:14:44.486429Z","caller":"traceutil/trace.go:171","msg":"trace[972866066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:960; }","duration":"381.888705ms","start":"2026-02-12T02:14:44.104532Z","end":"2026-02-12T02:14:44.486421Z","steps":["trace[972866066] 'agreement among raft nodes before linearized reading' (duration: 381.816884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:44.486451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:44.104512Z","time spent":"381.934246ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T02:14:44.486793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.143616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:14:44.486825Z","caller":"traceutil/trace.go:171","msg":"trace[1587659238] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"346.203898ms","start":"2026-02-12T02:14:44.140612Z","end":"2026-02-12T02:14:44.486816Z","steps":["trace[1587659238] 'agreement among raft nodes before linearized reading' (duration: 346.156937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:44.486847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:44.140598Z","time spent":"346.243068ms","remote":"127.0.0.1:33668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T02:14:44.487006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.397965ms","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-12T02:14:44.48703Z","caller":"traceutil/trace.go:171","msg":"trace[1816891713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:960; }","duration":"382.434907ms","start":"2026-02-12T02:14:44.104587Z","end":"2026-02-12T02:14:44.487022Z","steps":["trace[1816891713] 'agreement among raft nodes before linearized reading' (duration: 382.367245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:44.487051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:44.104582Z","time spent":"382.463997ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T02:14:45.230299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.645159ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T02:14:45.230463Z","caller":"traceutil/trace.go:171","msg":"trace[948087991] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1003; }","duration":"731.713031ms","start":"2026-02-12T02:14:44.498698Z","end":"2026-02-12T02:14:45.230411Z","steps":["trace[948087991] 'read index received' (duration: 318.852728ms)","trace[948087991] 'applied index is now lower than readState.Index' (duration: 412.858933ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:14:45.23077Z","caller":"traceutil/trace.go:171","msg":"trace[1559318562] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"734.612972ms","start":"2026-02-12T02:14:44.496147Z","end":"2026-02-12T02:14:45.23076Z","steps":["trace[1559318562] 'process raft request' (duration: 321.453793ms)","trace[1559318562] 'compare' (duration: 412.175318ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:14:45.231051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:44.496124Z","time spent":"734.900218ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:14:45.231277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.576909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:14:45.231375Z","caller":"traceutil/trace.go:171","msg":"trace[1821685300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"732.703252ms","start":"2026-02-12T02:14:44.498663Z","end":"2026-02-12T02:14:45.231366Z","steps":["trace[1821685300] 'agreement among raft nodes before linearized reading' (duration: 732.58548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:45.231472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:44.498645Z","time spent":"732.818665ms","remote":"127.0.0.1:33676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-12T02:14:45.245482Z","caller":"traceutil/trace.go:171","msg":"trace[1175118173] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"746.77627ms","start":"2026-02-12T02:14:44.498693Z","end":"2026-02-12T02:14:45.245469Z","steps":["trace[1175118173] 'process raft request' (duration: 745.912572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:45.245614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:44.498679Z","time spent":"746.884332ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:14:45.24576Z","caller":"traceutil/trace.go:171","msg":"trace[308926366] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"746.976264ms","start":"2026-02-12T02:14:44.498777Z","end":"2026-02-12T02:14:45.245753Z","steps":["trace[308926366] 'process raft request' (duration: 745.946233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:45.24584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:44.49877Z","time spent":"747.051566ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:14:45.245954Z","caller":"traceutil/trace.go:171","msg":"trace[1518745599] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"745.975083ms","start":"2026-02-12T02:14:44.499961Z","end":"2026-02-12T02:14:45.245936Z","steps":["trace[1518745599] 'process raft request' (duration: 744.786868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:45.246207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:44.49995Z","time spent":"746.047074ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:14:45.246353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.527249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-12T02:14:45.246404Z","caller":"traceutil/trace.go:171","msg":"trace[1878331616] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:964; }","duration":"227.620001ms","start":"2026-02-12T02:14:45.018776Z","end":"2026-02-12T02:14:45.246396Z","steps":["trace[1878331616] 'agreement among raft nodes before linearized reading' (duration: 227.518148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:45.453533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.987438ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-02-12T02:14:46.733537Z","caller":"traceutil/trace.go:171","msg":"trace[1791866227] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"236.975479ms","start":"2026-02-12T02:14:46.496545Z","end":"2026-02-12T02:14:46.733521Z","steps":["trace[1791866227] 'process raft request' (duration: 236.861867ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:14:48.959896Z","caller":"traceutil/trace.go:171","msg":"trace[131744549] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"221.150314ms","start":"2026-02-12T02:14:48.738713Z","end":"2026-02-12T02:14:48.959863Z","steps":["trace[131744549] 'process raft request' (duration: 221.034541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:50.346289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.068879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:14:50.346332Z","caller":"traceutil/trace.go:171","msg":"trace[1475958146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:991; }","duration":"144.145761ms","start":"2026-02-12T02:14:50.202176Z","end":"2026-02-12T02:14:50.346322Z","steps":["trace[1475958146] 'range keys from in-memory index tree' (duration: 143.998747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:50.346442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.962058ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T02:14:50.346604Z","caller":"traceutil/trace.go:171","msg":"trace[1103926157] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"117.491354ms","start":"2026-02-12T02:14:50.229105Z","end":"2026-02-12T02:14:50.346596Z","steps":["trace[1103926157] 'process raft request' (duration: 12.327261ms)","trace[1103926157] 'compare' (duration: 104.751574ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:14:55.167635Z","caller":"traceutil/trace.go:171","msg":"trace[711621961] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"132.336079ms","start":"2026-02-12T02:14:55.03528Z","end":"2026-02-12T02:14:55.167616Z","steps":["trace[711621961] 'process raft request' (duration: 131.319688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:57.630685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.222465ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:14:57.630938Z","caller":"traceutil/trace.go:171","msg":"trace[700100381] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1029; }","duration":"189.482731ms","start":"2026-02-12T02:14:57.441438Z","end":"2026-02-12T02:14:57.630921Z","steps":["trace[700100381] 'range keys from in-memory index tree' (duration: 189.211105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:57.631303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.463185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T02:14:57.631394Z","caller":"traceutil/trace.go:171","msg":"trace[208632891] linearizableReadLoop","detail":"{readStateIndex:1076; appliedIndex:1075; }","duration":"306.059196ms","start":"2026-02-12T02:14:57.32532Z","end":"2026-02-12T02:14:57.631379Z","steps":["trace[208632891] 'read index received' (duration: 64.397507ms)","trace[208632891] 'applied index is now lower than readState.Index' (duration: 241.660439ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:14:57.631544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.213791ms","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-12T02:14:57.631572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.125855ms","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-12T02:14:57.631599Z","caller":"traceutil/trace.go:171","msg":"trace[601803247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1030; }","duration":"303.164956ms","start":"2026-02-12T02:14:57.328425Z","end":"2026-02-12T02:14:57.63159Z","steps":["trace[601803247] 'agreement among raft nodes before linearized reading' (duration: 303.037452ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:14:57.631584Z","caller":"traceutil/trace.go:171","msg":"trace[1298095402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1030; }","duration":"306.270691ms","start":"2026-02-12T02:14:57.325301Z","end":"2026-02-12T02:14:57.631572Z","steps":["trace[1298095402] 'agreement among raft nodes before linearized reading' (duration: 306.150318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:14:57.631664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:57.325293Z","time spent":"306.360543ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T02:14:57.631679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.984747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:14:57.631705Z","caller":"traceutil/trace.go:171","msg":"trace[181119623] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1030; }","duration":"242.097659ms","start":"2026-02-12T02:14:57.3896Z","end":"2026-02-12T02:14:57.631698Z","steps":["trace[181119623] 'agreement among raft nodes before linearized reading' (duration: 242.053158ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:14:57.631926Z","caller":"traceutil/trace.go:171","msg":"trace[403881876] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"312.385981ms","start":"2026-02-12T02:14:57.31942Z","end":"2026-02-12T02:14:57.631806Z","steps":["trace[403881876] 'process raft request' (duration: 70.331883ms)","trace[403881876] 'compare' (duration: 240.784791ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:14:57.631628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:57.328419Z","time spent":"303.202696ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T02:14:57.63202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:57.31941Z","time spent":"312.555405ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:15:00.157755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.823834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T02:15:00.157839Z","caller":"traceutil/trace.go:171","msg":"trace[932001303] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"510.980675ms","start":"2026-02-12T02:14:59.646845Z","end":"2026-02-12T02:15:00.157825Z","steps":["trace[932001303] 'process raft request' (duration: 390.024548ms)","trace[932001303] 'compare' (duration: 120.575269ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:15:00.157877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:59.646835Z","time spent":"511.024536ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:15:00.200125Z","caller":"traceutil/trace.go:171","msg":"trace[1056183582] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"491.241086ms","start":"2026-02-12T02:14:59.708839Z","end":"2026-02-12T02:15:00.20008Z","steps":["trace[1056183582] 'process raft request' (duration: 491.087363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:15:00.200247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:14:59.708814Z","time spent":"491.371689ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:15:06.36419Z","caller":"traceutil/trace.go:171","msg":"trace[415668439] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"177.882474ms","start":"2026-02-12T02:15:06.186262Z","end":"2026-02-12T02:15:06.364145Z","steps":["trace[415668439] 'process raft request' (duration: 177.348603ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:15:06.363754Z","caller":"traceutil/trace.go:171","msg":"trace[997935709] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1134; }","duration":"146.123591ms","start":"2026-02-12T02:15:06.217609Z","end":"2026-02-12T02:15:06.363733Z","steps":["trace[997935709] 'read index received' (duration: 145.940927ms)","trace[997935709] 'applied index is now lower than readState.Index' (duration: 181.824µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:15:06.366265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.635054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-02-12T02:15:06.366723Z","caller":"traceutil/trace.go:171","msg":"trace[639191415] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1087; }","duration":"149.131703ms","start":"2026-02-12T02:15:06.217577Z","end":"2026-02-12T02:15:06.366708Z","steps":["trace[639191415] 'agreement among raft nodes before linearized reading' (duration: 147.719123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:15:06.366813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.910194ms","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:508"} {"level":"info","ts":"2026-02-12T02:15:06.36726Z","caller":"traceutil/trace.go:171","msg":"trace[658860726] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1087; }","duration":"129.388284ms","start":"2026-02-12T02:15:06.237848Z","end":"2026-02-12T02:15:06.367237Z","steps":["trace[658860726] 'agreement among raft nodes before linearized reading' (duration: 128.758661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:15:06.366666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.958064ms","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-12T02:15:06.369218Z","caller":"traceutil/trace.go:171","msg":"trace[951319518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1087; }","duration":"147.530569ms","start":"2026-02-12T02:15:06.221672Z","end":"2026-02-12T02:15:06.369203Z","steps":["trace[951319518] 'agreement among raft nodes before linearized reading' (duration: 144.876003ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:17:57.60407Z","caller":"traceutil/trace.go:171","msg":"trace[972603388] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"361.477604ms","start":"2026-02-12T02:17:57.242574Z","end":"2026-02-12T02:17:57.604052Z","steps":["trace[972603388] 'process raft request' (duration: 360.230528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:17:57.604319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:17:57.24256Z","time spent":"361.647688ms","remote":"127.0.0.1:33916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:17:57.601994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.382333ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:17:57.606523Z","caller":"traceutil/trace.go:171","msg":"trace[865284720] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1813; }","duration":"164.920549ms","start":"2026-02-12T02:17:57.441581Z","end":"2026-02-12T02:17:57.606501Z","steps":["trace[865284720] 'range keys from in-memory index tree' (duration: 160.365983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:18:06.853071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.456557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-12T02:18:06.853128Z","caller":"traceutil/trace.go:171","msg":"trace[2018517820] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1856; }","duration":"327.532229ms","start":"2026-02-12T02:18:06.525584Z","end":"2026-02-12T02:18:06.853116Z","steps":["trace[2018517820] 'range keys from in-memory index tree' (duration: 327.324534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:18:06.853159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:18:06.525526Z","time spent":"327.62691ms","remote":"127.0.0.1:33916","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-12T02:18:07.003649Z","caller":"traceutil/trace.go:171","msg":"trace[2037507795] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"143.981167ms","start":"2026-02-12T02:18:06.85965Z","end":"2026-02-12T02:18:07.003631Z","steps":["trace[2037507795] 'process raft request' (duration: 143.939346ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:18:07.003896Z","caller":"traceutil/trace.go:171","msg":"trace[1759430655] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"145.811025ms","start":"2026-02-12T02:18:06.858078Z","end":"2026-02-12T02:18:07.003889Z","steps":["trace[1759430655] 'process raft request' (duration: 142.480255ms)"],"step_count":1}