{"level":"warn","ts":"2026-02-07T03:58:50.51546Z","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-07T03:58:50.51564Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.62: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://162.253.55.62:2380","--initial-cluster=instance=https://162.253.55.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.62: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-07T03:58:50.515733Z","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-07T03:58:50.515808Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-02-07T03:58:50.515858Z","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-07T03:58:50.516395Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"]} {"level":"info","ts":"2026-02-07T03:58:50.519179Z","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://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.62: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-07T03:58:50.527594Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.345637ms"} {"level":"info","ts":"2026-02-07T03:58:50.545835Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"791a7e25b533cd26","cluster-id":"be484d2748da72cd"} {"level":"info","ts":"2026-02-07T03:58:50.54601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=()"} {"level":"info","ts":"2026-02-07T03:58:50.546066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 0"} {"level":"info","ts":"2026-02-07T03:58:50.546105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 791a7e25b533cd26 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-07T03:58:50.546149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 1"} {"level":"info","ts":"2026-02-07T03:58:50.546216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"warn","ts":"2026-02-07T03:58:50.56317Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-07T03:58:50.585658Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-07T03:58:50.589397Z","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-07T03:58:50.594359Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"791a7e25b533cd26","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-07T03:58:50.598012Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"791a7e25b533cd26","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-07T03:58:50.598724Z","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-07T03:58:50.598853Z","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-07T03:58:50.599048Z","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-07T03:58:50.601111Z","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-07T03:58:50.601464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"info","ts":"2026-02-07T03:58:50.601572Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","added-peer-id":"791a7e25b533cd26","added-peer-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-02-07T03:58:50.602421Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"791a7e25b533cd26","initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-07T03:58:50.602969Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-02-07T03:58:50.609734Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-02-07T03:58:50.602484Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-07T03:58:50.747198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 is starting a new election at term 1"} {"level":"info","ts":"2026-02-07T03:58:50.747254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-07T03:58:50.747296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgPreVoteResp from 791a7e25b533cd26 at term 1"} {"level":"info","ts":"2026-02-07T03:58:50.747368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became candidate at term 2"} {"level":"info","ts":"2026-02-07T03:58:50.747414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgVoteResp from 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-02-07T03:58:50.747428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became leader at term 2"} {"level":"info","ts":"2026-02-07T03:58:50.747435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 791a7e25b533cd26 elected leader 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-02-07T03:58:50.748397Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T03:58:50.749184Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"791a7e25b533cd26","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.62:2379]}","request-path":"/0/members/791a7e25b533cd26/attributes","cluster-id":"be484d2748da72cd","publish-timeout":"7s"} {"level":"info","ts":"2026-02-07T03:58:50.749415Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T03:58:50.749612Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T03:58:50.749839Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T03:58:50.749851Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T03:58:50.750031Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T03:58:50.749998Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-07T03:58:50.750121Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-07T03:58:50.752021Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.62:2379"} {"level":"info","ts":"2026-02-07T03:58:50.755949Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-07T03:58:54.036783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.440865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-07T03:58:54.036973Z","caller":"traceutil/trace.go:171","msg":"trace[1801286648] transaction","detail":"{read_only:false; response_revision:93; number_of_response:1; }","duration":"120.160249ms","start":"2026-02-07T03:58:53.916732Z","end":"2026-02-07T03:58:54.036892Z","steps":["trace[1801286648] 'process raft request' (duration: 14.038885ms)","trace[1801286648] 'compare' (duration: 105.294257ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T03:59:01.436019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.558551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-07T03:59:01.43608Z","caller":"traceutil/trace.go:171","msg":"trace[1819437155] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:375; }","duration":"258.65431ms","start":"2026-02-07T03:59:01.177414Z","end":"2026-02-07T03:59:01.436068Z","steps":["trace[1819437155] 'range keys from in-memory index tree' (duration: 258.486204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:01.436254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.133677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-07T03:59:01.436281Z","caller":"traceutil/trace.go:171","msg":"trace[1041903774] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:375; }","duration":"213.1865ms","start":"2026-02-07T03:59:01.223087Z","end":"2026-02-07T03:59:01.436274Z","steps":["trace[1041903774] 'range keys from in-memory index tree' (duration: 213.081374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:01.436367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.539112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-07T03:59:01.436389Z","caller":"traceutil/trace.go:171","msg":"trace[531618314] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:375; }","duration":"162.586546ms","start":"2026-02-07T03:59:01.273797Z","end":"2026-02-07T03:59:01.436384Z","steps":["trace[531618314] 'range keys from in-memory index tree' (duration: 162.481101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:03.800311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.716096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5260"} {"level":"warn","ts":"2026-02-07T03:59:03.800517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.083785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T03:59:03.800597Z","caller":"traceutil/trace.go:171","msg":"trace[1806660250] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:403; }","duration":"234.190559ms","start":"2026-02-07T03:59:03.566397Z","end":"2026-02-07T03:59:03.800587Z","steps":["trace[1806660250] 'range keys from in-memory index tree' (duration: 234.032092ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:59:03.801211Z","caller":"traceutil/trace.go:171","msg":"trace[5024666] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:403; }","duration":"233.864137ms","start":"2026-02-07T03:59:03.566552Z","end":"2026-02-07T03:59:03.800416Z","steps":["trace[5024666] 'range keys from in-memory index tree' (duration: 233.660574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:07.386037Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674583716541847,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-07T03:59:07.765485Z","caller":"traceutil/trace.go:171","msg":"trace[2073583921] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"997.914625ms","start":"2026-02-07T03:59:06.76755Z","end":"2026-02-07T03:59:07.765464Z","steps":["trace[2073583921] 'process raft request' (duration: 997.796965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:07.765895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:06.767529Z","time spent":"998.150815ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T03:59:08.230511Z","caller":"traceutil/trace.go:171","msg":"trace[484504557] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"1.344638929s","start":"2026-02-07T03:59:06.885854Z","end":"2026-02-07T03:59:08.230493Z","steps":["trace[484504557] 'read index received' (duration: 881.054644ms)","trace[484504557] 'applied index is now lower than readState.Index' (duration: 463.583516ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T03:59:08.230685Z","caller":"traceutil/trace.go:171","msg":"trace[1596821730] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"1.283765337s","start":"2026-02-07T03:59:06.946913Z","end":"2026-02-07T03:59:08.230679Z","steps":["trace[1596821730] 'process raft request' (duration: 1.280698322s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:08.230753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:06.946898Z","time spent":"1.283804944s","remote":"127.0.0.1:33814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4041,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T03:59:08.230897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.345041122s","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-07T03:59:08.230923Z","caller":"traceutil/trace.go:171","msg":"trace[365913205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:416; }","duration":"1.345089006s","start":"2026-02-07T03:59:06.885827Z","end":"2026-02-07T03:59:08.230916Z","steps":["trace[365913205] 'agreement among raft nodes before linearized reading' (duration: 1.345043382s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:08.230944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:06.885817Z","time spent":"1.345122215s","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-07T03:59:08.231061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.33886416s","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-07T03:59:08.231084Z","caller":"traceutil/trace.go:171","msg":"trace[1770300815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:416; }","duration":"1.338901348s","start":"2026-02-07T03:59:06.892178Z","end":"2026-02-07T03:59:08.231079Z","steps":["trace[1770300815] 'agreement among raft nodes before linearized reading' (duration: 1.33886477s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:08.2311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:06.892171Z","time spent":"1.338925609s","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-07T03:59:08.231242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.021211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T03:59:08.231265Z","caller":"traceutil/trace.go:171","msg":"trace[905919429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"166.077562ms","start":"2026-02-07T03:59:08.065182Z","end":"2026-02-07T03:59:08.231259Z","steps":["trace[905919429] 'agreement among raft nodes before linearized reading' (duration: 166.045373ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:08.231362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.092141ms","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-07T03:59:08.231385Z","caller":"traceutil/trace.go:171","msg":"trace[39664265] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:416; }","duration":"695.115704ms","start":"2026-02-07T03:59:07.536263Z","end":"2026-02-07T03:59:08.231378Z","steps":["trace[39664265] 'agreement among raft nodes before linearized reading' (duration: 695.078886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:08.231402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:07.536209Z","time spent":"695.189169ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-07T03:59:12.436455Z","caller":"traceutil/trace.go:171","msg":"trace[1119454891] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"174.884911ms","start":"2026-02-07T03:59:12.261553Z","end":"2026-02-07T03:59:12.436438Z","steps":["trace[1119454891] 'process raft request' (duration: 174.805189ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:59:12.443553Z","caller":"traceutil/trace.go:171","msg":"trace[1636031696] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:438; }","duration":"178.79764ms","start":"2026-02-07T03:59:12.264741Z","end":"2026-02-07T03:59:12.443539Z","steps":["trace[1636031696] 'read index received' (duration: 172.07102ms)","trace[1636031696] 'applied index is now lower than readState.Index' (duration: 6.72573ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T03:59:12.443709Z","caller":"traceutil/trace.go:171","msg":"trace[1080321404] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"179.735114ms","start":"2026-02-07T03:59:12.263961Z","end":"2026-02-07T03:59:12.443696Z","steps":["trace[1080321404] 'process raft request' (duration: 179.476594ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:59:12.443798Z","caller":"traceutil/trace.go:171","msg":"trace[1095226813] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"175.964006ms","start":"2026-02-07T03:59:12.267821Z","end":"2026-02-07T03:59:12.443785Z","steps":["trace[1095226813] 'process raft request' (duration: 175.679675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:12.443869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.109831ms","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-07T03:59:12.445644Z","caller":"traceutil/trace.go:171","msg":"trace[1011315522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:428; }","duration":"180.891291ms","start":"2026-02-07T03:59:12.264738Z","end":"2026-02-07T03:59:12.445629Z","steps":["trace[1011315522] 'agreement among raft nodes before linearized reading' (duration: 179.087289ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:59:12.608973Z","caller":"traceutil/trace.go:171","msg":"trace[2058519791] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"135.525116ms","start":"2026-02-07T03:59:12.47343Z","end":"2026-02-07T03:59:12.608955Z","steps":["trace[2058519791] 'process raft request' (duration: 129.091595ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:59:12.609183Z","caller":"traceutil/trace.go:171","msg":"trace[1835392217] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"135.505693ms","start":"2026-02-07T03:59:12.473667Z","end":"2026-02-07T03:59:12.609173Z","steps":["trace[1835392217] 'process raft request' (duration: 135.0235ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:59:12.609367Z","caller":"traceutil/trace.go:171","msg":"trace[560664074] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"132.082304ms","start":"2026-02-07T03:59:12.477276Z","end":"2026-02-07T03:59:12.609358Z","steps":["trace[560664074] 'process raft request' (duration: 131.457142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:13.495324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.011723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T03:59:13.49556Z","caller":"traceutil/trace.go:171","msg":"trace[502857594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:459; }","duration":"433.246781ms","start":"2026-02-07T03:59:13.062255Z","end":"2026-02-07T03:59:13.495501Z","steps":["trace[502857594] 'range keys from in-memory index tree' (duration: 432.952763ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:59:13.495408Z","caller":"traceutil/trace.go:171","msg":"trace[1425948842] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"290.305662ms","start":"2026-02-07T03:59:13.205079Z","end":"2026-02-07T03:59:13.495384Z","steps":["trace[1425948842] 'process raft request' (duration: 266.570576ms)","trace[1425948842] 'compare' (duration: 23.169044ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T03:59:13.495784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:13.062239Z","time spent":"433.526863ms","remote":"127.0.0.1:33650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-07T03:59:26.686488Z","caller":"traceutil/trace.go:171","msg":"trace[754031480] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:538; }","duration":"146.258982ms","start":"2026-02-07T03:59:26.539904Z","end":"2026-02-07T03:59:26.686163Z","steps":["trace[754031480] 'read index received' (duration: 146.065092ms)","trace[754031480] 'applied index is now lower than readState.Index' (duration: 193.22µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T03:59:26.686915Z","caller":"traceutil/trace.go:171","msg":"trace[380936978] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"402.488408ms","start":"2026-02-07T03:59:26.283955Z","end":"2026-02-07T03:59:26.686443Z","steps":["trace[380936978] 'process raft request' (duration: 402.073597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:26.687204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.801839ms","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-07T03:59:26.687309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:26.283889Z","time spent":"403.059525ms","remote":"127.0.0.1:33814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4253,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T03:59:26.687362Z","caller":"traceutil/trace.go:171","msg":"trace[765965207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:523; }","duration":"147.402327ms","start":"2026-02-07T03:59:26.539854Z","end":"2026-02-07T03:59:26.687257Z","steps":["trace[765965207] 'agreement among raft nodes before linearized reading' (duration: 146.817975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:26.689356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.371941ms","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-07T03:59:26.68938Z","caller":"traceutil/trace.go:171","msg":"trace[1274628707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:523; }","duration":"149.414306ms","start":"2026-02-07T03:59:26.539959Z","end":"2026-02-07T03:59:26.689373Z","steps":["trace[1274628707] 'agreement among raft nodes before linearized reading' (duration: 149.260791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:26.690799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.836881ms","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-07T03:59:26.690826Z","caller":"traceutil/trace.go:171","msg":"trace[338412016] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:523; }","duration":"142.883388ms","start":"2026-02-07T03:59:26.547933Z","end":"2026-02-07T03:59:26.690816Z","steps":["trace[338412016] 'agreement among raft nodes before linearized reading' (duration: 142.773668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:27.261776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.917843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T03:59:27.261916Z","caller":"traceutil/trace.go:171","msg":"trace[179354205] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:539; }","duration":"198.155913ms","start":"2026-02-07T03:59:27.063749Z","end":"2026-02-07T03:59:27.261905Z","steps":["trace[179354205] 'read index received' (duration: 31.071µs)","trace[179354205] 'applied index is now lower than readState.Index' (duration: 198.124082ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T03:59:27.261965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.214953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T03:59:27.261978Z","caller":"traceutil/trace.go:171","msg":"trace[1481908998] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:527; }","duration":"198.2637ms","start":"2026-02-07T03:59:27.063709Z","end":"2026-02-07T03:59:27.261973Z","steps":["trace[1481908998] 'agreement among raft nodes before linearized reading' (duration: 198.223566ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:59:27.262152Z","caller":"traceutil/trace.go:171","msg":"trace[1601934661] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"559.998592ms","start":"2026-02-07T03:59:26.702128Z","end":"2026-02-07T03:59:27.262127Z","steps":["trace[1601934661] 'process raft request' (duration: 37.654903ms)","trace[1601934661] 'compare' (duration: 521.67378ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T03:59:27.262182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:26.702091Z","time spent":"560.073228ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T03:59:27.262239Z","caller":"traceutil/trace.go:171","msg":"trace[1159485742] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"559.983995ms","start":"2026-02-07T03:59:26.70225Z","end":"2026-02-07T03:59:27.262234Z","steps":["trace[1159485742] 'process raft request' (duration: 559.591471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:27.262273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:26.702241Z","time spent":"560.017447ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T03:59:27.26268Z","caller":"traceutil/trace.go:171","msg":"trace[352460358] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"554.016223ms","start":"2026-02-07T03:59:26.708635Z","end":"2026-02-07T03:59:27.262651Z","steps":["trace[352460358] 'process raft request' (duration: 553.233015ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:27.262874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:26.708614Z","time spent":"554.176198ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T03:59:27.263049Z","caller":"traceutil/trace.go:171","msg":"trace[667956228] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"550.065233ms","start":"2026-02-07T03:59:26.712969Z","end":"2026-02-07T03:59:27.263035Z","steps":["trace[667956228] 'process raft request' (duration: 548.916669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:27.263109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:26.712952Z","time spent":"550.128354ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T03:59:29.68736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.549106ms","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-07T03:59:29.687467Z","caller":"traceutil/trace.go:171","msg":"trace[1781300018] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:529; }","duration":"101.666072ms","start":"2026-02-07T03:59:29.585787Z","end":"2026-02-07T03:59:29.687453Z","steps":["trace[1781300018] 'range keys from in-memory index tree' (duration: 101.536723ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:59:29.688577Z","caller":"traceutil/trace.go:171","msg":"trace[1605406116] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"415.38543ms","start":"2026-02-07T03:59:29.273182Z","end":"2026-02-07T03:59:29.688567Z","steps":["trace[1605406116] 'read index received' (duration: 93.967323ms)","trace[1605406116] 'applied index is now lower than readState.Index' (duration: 321.417577ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T03:59:29.688767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.857133ms","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-07T03:59:29.688784Z","caller":"traceutil/trace.go:171","msg":"trace[662189751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:530; }","duration":"413.890443ms","start":"2026-02-07T03:59:29.274887Z","end":"2026-02-07T03:59:29.688778Z","steps":["trace[662189751] 'agreement among raft nodes before linearized reading' (duration: 413.812959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:29.688802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:29.274877Z","time spent":"413.920572ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-07T03:59:29.688898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.056203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T03:59:29.688913Z","caller":"traceutil/trace.go:171","msg":"trace[194482146] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:530; }","duration":"118.094755ms","start":"2026-02-07T03:59:29.570812Z","end":"2026-02-07T03:59:29.688907Z","steps":["trace[194482146] 'agreement among raft nodes before linearized reading' (duration: 118.070918ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:59:29.689073Z","caller":"traceutil/trace.go:171","msg":"trace[725067816] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"417.210076ms","start":"2026-02-07T03:59:29.271857Z","end":"2026-02-07T03:59:29.689067Z","steps":["trace[725067816] 'process raft request' (duration: 416.630119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:29.689119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:29.271851Z","time spent":"417.235024ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T03:59:29.690565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.363693ms","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-07T03:59:29.690629Z","caller":"traceutil/trace.go:171","msg":"trace[99315351] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:530; }","duration":"417.491032ms","start":"2026-02-07T03:59:29.273125Z","end":"2026-02-07T03:59:29.690616Z","steps":["trace[99315351] 'agreement among raft nodes before linearized reading' (duration: 415.536666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:29.690659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:29.273113Z","time spent":"417.539057ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-07T03:59:35.262415Z","caller":"traceutil/trace.go:171","msg":"trace[1889574632] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"597.477478ms","start":"2026-02-07T03:59:34.664907Z","end":"2026-02-07T03:59:35.262384Z","steps":["trace[1889574632] 'process raft request' (duration: 515.706972ms)","trace[1889574632] 'compare' (duration: 81.391428ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T03:59:35.262615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:34.664897Z","time spent":"597.647403ms","remote":"127.0.0.1:33822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T03:59:35.277738Z","caller":"traceutil/trace.go:171","msg":"trace[990460756] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"596.127724ms","start":"2026-02-07T03:59:34.681601Z","end":"2026-02-07T03:59:35.277729Z","steps":["trace[990460756] 'process raft request' (duration: 595.967752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:35.277839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:34.681581Z","time spent":"596.20541ms","remote":"127.0.0.1:33676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T03:59:35.278212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.541678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T03:59:35.278233Z","caller":"traceutil/trace.go:171","msg":"trace[348555505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"216.585647ms","start":"2026-02-07T03:59:35.061641Z","end":"2026-02-07T03:59:35.278227Z","steps":["trace[348555505] 'agreement among raft nodes before linearized reading' (duration: 216.527655ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:59:35.278383Z","caller":"traceutil/trace.go:171","msg":"trace[605987152] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:584; }","duration":"216.022902ms","start":"2026-02-07T03:59:35.061674Z","end":"2026-02-07T03:59:35.277697Z","steps":["trace[605987152] 'read index received' (duration: 118.950313ms)","trace[605987152] 'applied index is now lower than readState.Index' (duration: 97.071939ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T03:59:35.637379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.766237ms","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-07T03:59:35.637469Z","caller":"traceutil/trace.go:171","msg":"trace[1427209406] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:568; }","duration":"246.894903ms","start":"2026-02-07T03:59:35.390553Z","end":"2026-02-07T03:59:35.637448Z","steps":["trace[1427209406] 'range keys from in-memory index tree' (duration: 246.613165ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:59:35.875543Z","caller":"traceutil/trace.go:171","msg":"trace[371910181] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:586; }","duration":"133.134646ms","start":"2026-02-07T03:59:35.742393Z","end":"2026-02-07T03:59:35.875528Z","steps":["trace[371910181] 'read index received' (duration: 133.00937ms)","trace[371910181] 'applied index is now lower than readState.Index' (duration: 124.636µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T03:59:35.875744Z","caller":"traceutil/trace.go:171","msg":"trace[238198790] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"232.670371ms","start":"2026-02-07T03:59:35.643068Z","end":"2026-02-07T03:59:35.875738Z","steps":["trace[238198790] 'process raft request' (duration: 232.37509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:35.875916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.511173ms","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-07T03:59:35.875935Z","caller":"traceutil/trace.go:171","msg":"trace[2120872100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:569; }","duration":"133.576386ms","start":"2026-02-07T03:59:35.742354Z","end":"2026-02-07T03:59:35.87593Z","steps":["trace[2120872100] 'agreement among raft nodes before linearized reading' (duration: 133.483807ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:59:36.02394Z","caller":"traceutil/trace.go:171","msg":"trace[440414113] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"143.42756ms","start":"2026-02-07T03:59:35.88049Z","end":"2026-02-07T03:59:36.023917Z","steps":["trace[440414113] 'process raft request' (duration: 143.254727ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:59:36.046499Z","caller":"traceutil/trace.go:171","msg":"trace[2126683063] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"164.101031ms","start":"2026-02-07T03:59:35.882381Z","end":"2026-02-07T03:59:36.046482Z","steps":["trace[2126683063] 'process raft request' (duration: 164.059983ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:59:36.046627Z","caller":"traceutil/trace.go:171","msg":"trace[1269359528] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"165.03535ms","start":"2026-02-07T03:59:35.881587Z","end":"2026-02-07T03:59:36.046622Z","steps":["trace[1269359528] 'process raft request' (duration: 164.760658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:38.117322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055492767s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T03:59:38.117372Z","caller":"traceutil/trace.go:171","msg":"trace[357797494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"1.055571241s","start":"2026-02-07T03:59:37.06179Z","end":"2026-02-07T03:59:38.117361Z","steps":["trace[357797494] 'range keys from in-memory index tree' (duration: 1.055411192s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:38.117561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:37.061773Z","time spent":"1.055782265s","remote":"127.0.0.1:33650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T03:59:38.118058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.004282ms","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-07T03:59:38.118078Z","caller":"traceutil/trace.go:171","msg":"trace[762125103] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:572; }","duration":"235.05073ms","start":"2026-02-07T03:59:37.883021Z","end":"2026-02-07T03:59:38.118072Z","steps":["trace[762125103] 'range keys from in-memory index tree' (duration: 234.910216ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:38.634448Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674583716542175,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-07T03:59:38.647573Z","caller":"traceutil/trace.go:171","msg":"trace[902265035] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"524.374971ms","start":"2026-02-07T03:59:38.123177Z","end":"2026-02-07T03:59:38.647552Z","steps":["trace[902265035] 'process raft request' (duration: 524.254672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:38.64769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:38.123162Z","time spent":"524.467917ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T03:59:38.714425Z","caller":"traceutil/trace.go:171","msg":"trace[1020517095] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:591; }","duration":"580.810216ms","start":"2026-02-07T03:59:38.1336Z","end":"2026-02-07T03:59:38.714411Z","steps":["trace[1020517095] 'read index received' (duration: 514.477346ms)","trace[1020517095] 'applied index is now lower than readState.Index' (duration: 66.33218ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T03:59:38.714532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.913173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T03:59:38.714555Z","caller":"traceutil/trace.go:171","msg":"trace[180159810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"580.996046ms","start":"2026-02-07T03:59:38.133552Z","end":"2026-02-07T03:59:38.714548Z","steps":["trace[180159810] 'agreement among raft nodes before linearized reading' (duration: 580.912063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:38.714577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:38.133539Z","time spent":"581.032232ms","remote":"127.0.0.1:33650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-07T03:59:38.714813Z","caller":"traceutil/trace.go:171","msg":"trace[1142945283] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"590.831742ms","start":"2026-02-07T03:59:38.123975Z","end":"2026-02-07T03:59:38.714807Z","steps":["trace[1142945283] 'process raft request' (duration: 590.314768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:38.714862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:38.12396Z","time spent":"590.868858ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T03:59:38.71494Z","caller":"traceutil/trace.go:171","msg":"trace[1816922426] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"581.285683ms","start":"2026-02-07T03:59:38.133649Z","end":"2026-02-07T03:59:38.714935Z","steps":["trace[1816922426] 'process raft request' (duration: 580.709469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:38.714966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:38.133641Z","time spent":"581.310628ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T03:59:38.715024Z","caller":"traceutil/trace.go:171","msg":"trace[363512833] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"579.78422ms","start":"2026-02-07T03:59:38.135235Z","end":"2026-02-07T03:59:38.715019Z","steps":["trace[363512833] 'process raft request' (duration: 579.142165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:38.715052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:38.135228Z","time spent":"579.807633ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T03:59:38.715114Z","caller":"traceutil/trace.go:171","msg":"trace[916458344] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"302.56257ms","start":"2026-02-07T03:59:38.412548Z","end":"2026-02-07T03:59:38.71511Z","steps":["trace[916458344] 'process raft request' (duration: 301.842364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:38.715139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:38.41253Z","time spent":"302.594945ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T03:59:39.933278Z","caller":"traceutil/trace.go:171","msg":"trace[1887324705] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"247.107127ms","start":"2026-02-07T03:59:39.686155Z","end":"2026-02-07T03:59:39.933262Z","steps":["trace[1887324705] 'process raft request' (duration: 247.025625ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:59:40.06522Z","caller":"traceutil/trace.go:171","msg":"trace[1789626357] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"361.853043ms","start":"2026-02-07T03:59:39.703352Z","end":"2026-02-07T03:59:40.065205Z","steps":["trace[1789626357] 'process raft request' (duration: 361.490124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:40.065337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:39.703336Z","time spent":"361.927663ms","remote":"127.0.0.1:33822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16897,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T03:59:40.219608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.719494ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-07T03:59:40.741028Z","caller":"traceutil/trace.go:171","msg":"trace[541427909] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"309.195516ms","start":"2026-02-07T03:59:40.431817Z","end":"2026-02-07T03:59:40.741013Z","steps":["trace[541427909] 'process raft request' (duration: 309.103933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:40.741115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:40.431801Z","time spent":"309.274286ms","remote":"127.0.0.1:33724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T03:59:42.245668Z","caller":"traceutil/trace.go:171","msg":"trace[476458102] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"177.982507ms","start":"2026-02-07T03:59:42.067409Z","end":"2026-02-07T03:59:42.245392Z","steps":["trace[476458102] 'read index received' (duration: 177.768013ms)","trace[476458102] 'applied index is now lower than readState.Index' (duration: 213.773µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T03:59:42.245737Z","caller":"traceutil/trace.go:171","msg":"trace[17037574] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"255.880883ms","start":"2026-02-07T03:59:41.989753Z","end":"2026-02-07T03:59:42.245634Z","steps":["trace[17037574] 'process raft request' (duration: 255.475358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:42.246274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.756213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T03:59:42.249462Z","caller":"traceutil/trace.go:171","msg":"trace[1474615027] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"182.0812ms","start":"2026-02-07T03:59:42.067369Z","end":"2026-02-07T03:59:42.24945Z","steps":["trace[1474615027] 'agreement among raft nodes before linearized reading' (duration: 178.390041ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:59:44.836863Z","caller":"traceutil/trace.go:171","msg":"trace[1847968591] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"109.017812ms","start":"2026-02-07T03:59:44.727205Z","end":"2026-02-07T03:59:44.836222Z","steps":["trace[1847968591] 'process raft request' (duration: 108.708585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:45.576978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.041809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T03:59:45.57705Z","caller":"traceutil/trace.go:171","msg":"trace[767050908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"512.147267ms","start":"2026-02-07T03:59:45.064886Z","end":"2026-02-07T03:59:45.577033Z","steps":["trace[767050908] 'range keys from in-memory index tree' (duration: 511.989805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:45.577088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:45.064872Z","time spent":"512.209021ms","remote":"127.0.0.1:33650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-07T03:59:45.618844Z","caller":"traceutil/trace.go:171","msg":"trace[1449162379] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"404.68585ms","start":"2026-02-07T03:59:45.214144Z","end":"2026-02-07T03:59:45.618829Z","steps":["trace[1449162379] 'process raft request' (duration: 404.375806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:45.618959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:45.214126Z","time spent":"404.790678ms","remote":"127.0.0.1:33688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T03:59:45.635062Z","caller":"traceutil/trace.go:171","msg":"trace[1707482156] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"190.386605ms","start":"2026-02-07T03:59:45.444664Z","end":"2026-02-07T03:59:45.635051Z","steps":["trace[1707482156] 'process raft request' (duration: 190.043409ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:59:49.1431Z","caller":"traceutil/trace.go:171","msg":"trace[592218047] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"122.66792ms","start":"2026-02-07T03:59:49.020413Z","end":"2026-02-07T03:59:49.143081Z","steps":["trace[592218047] 'process raft request' (duration: 91.831976ms)","trace[592218047] 'compare' (duration: 30.762041ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T03:59:50.679329Z","caller":"traceutil/trace.go:171","msg":"trace[1007202727] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"110.571475ms","start":"2026-02-07T03:59:50.56874Z","end":"2026-02-07T03:59:50.679311Z","steps":["trace[1007202727] 'process raft request' (duration: 110.536654ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:59:50.679687Z","caller":"traceutil/trace.go:171","msg":"trace[955860688] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"123.170724ms","start":"2026-02-07T03:59:50.556508Z","end":"2026-02-07T03:59:50.679679Z","steps":["trace[955860688] 'process raft request' (duration: 111.177791ms)","trace[955860688] 'compare' (duration: 11.510861ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T03:59:54.004545Z","caller":"traceutil/trace.go:171","msg":"trace[1115958114] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"111.601376ms","start":"2026-02-07T03:59:53.892927Z","end":"2026-02-07T03:59:54.004528Z","steps":["trace[1115958114] 'process raft request' (duration: 111.187397ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:59:54.004755Z","caller":"traceutil/trace.go:171","msg":"trace[1417786161] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"107.949872ms","start":"2026-02-07T03:59:53.896799Z","end":"2026-02-07T03:59:54.004749Z","steps":["trace[1417786161] 'process raft request' (duration: 107.402743ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:59:54.540241Z","caller":"traceutil/trace.go:171","msg":"trace[1764374392] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"366.679263ms","start":"2026-02-07T03:59:54.173543Z","end":"2026-02-07T03:59:54.540222Z","steps":["trace[1764374392] 'process raft request' (duration: 366.571324ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T03:59:54.540241Z","caller":"traceutil/trace.go:171","msg":"trace[625720920] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:773; }","duration":"366.477875ms","start":"2026-02-07T03:59:54.17375Z","end":"2026-02-07T03:59:54.540228Z","steps":["trace[625720920] 'read index received' (duration: 366.471025ms)","trace[625720920] 'applied index is now lower than readState.Index' (duration: 5.57µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T03:59:54.540366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:54.173526Z","time spent":"366.769942ms","remote":"127.0.0.1:34114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T03:59:54.540461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.703043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-ckprf\" ","response":"range_response_count:1 size:4811"} {"level":"info","ts":"2026-02-07T03:59:54.540495Z","caller":"traceutil/trace.go:171","msg":"trace[329459849] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-ckprf; range_end:; response_count:1; response_revision:750; }","duration":"366.775882ms","start":"2026-02-07T03:59:54.173708Z","end":"2026-02-07T03:59:54.540484Z","steps":["trace[329459849] 'agreement among raft nodes before linearized reading' (duration: 366.645533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:54.540521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:54.173702Z","time spent":"366.812661ms","remote":"127.0.0.1:33822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4834,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-ckprf\" "} {"level":"info","ts":"2026-02-07T03:59:54.595451Z","caller":"traceutil/trace.go:171","msg":"trace[472113413] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"410.362158ms","start":"2026-02-07T03:59:54.18507Z","end":"2026-02-07T03:59:54.595432Z","steps":["trace[472113413] 'process raft request' (duration: 410.314129ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:54.595576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:54.185055Z","time spent":"410.451017ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T03:59:54.595756Z","caller":"traceutil/trace.go:171","msg":"trace[1256976972] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"421.965133ms","start":"2026-02-07T03:59:54.173784Z","end":"2026-02-07T03:59:54.595749Z","steps":["trace[1256976972] 'process raft request' (duration: 421.510528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:54.595795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:54.173778Z","time spent":"421.999183ms","remote":"127.0.0.1:33724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T03:59:54.596269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.438169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" ","response":"range_response_count:1 size:3881"} {"level":"info","ts":"2026-02-07T03:59:54.596299Z","caller":"traceutil/trace.go:171","msg":"trace[380630335] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-77cccfdc44; range_end:; response_count:1; response_revision:752; }","duration":"422.469049ms","start":"2026-02-07T03:59:54.173822Z","end":"2026-02-07T03:59:54.596291Z","steps":["trace[380630335] 'agreement among raft nodes before linearized reading' (duration: 422.374549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T03:59:54.59632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T03:59:54.17382Z","time spent":"422.494008ms","remote":"127.0.0.1:34146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3904,"request content":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" "} {"level":"info","ts":"2026-02-07T04:00:01.157985Z","caller":"traceutil/trace.go:171","msg":"trace[1927284318] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"140.816339ms","start":"2026-02-07T04:00:01.017138Z","end":"2026-02-07T04:00:01.157954Z","steps":["trace[1927284318] 'process raft request' (duration: 140.502608ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:00:15.154923Z","caller":"traceutil/trace.go:171","msg":"trace[345672236] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"114.390611ms","start":"2026-02-07T04:00:15.040491Z","end":"2026-02-07T04:00:15.154882Z","steps":["trace[345672236] 'process raft request' (duration: 114.25596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:16.219087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.520173ms","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-07T04:00:16.21915Z","caller":"traceutil/trace.go:171","msg":"trace[1570080897] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:833; }","duration":"995.622038ms","start":"2026-02-07T04:00:15.22351Z","end":"2026-02-07T04:00:16.219132Z","steps":["trace[1570080897] 'range keys from in-memory index tree' (duration: 995.352906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:16.219794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.595129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T04:00:16.219828Z","caller":"traceutil/trace.go:171","msg":"trace[929333972] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:833; }","duration":"838.634064ms","start":"2026-02-07T04:00:15.381184Z","end":"2026-02-07T04:00:16.219818Z","steps":["trace[929333972] 'count revisions from in-memory index tree' (duration: 838.535937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:16.220241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:15.381134Z","time spent":"839.022339ms","remote":"127.0.0.1:34014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-07T04:00:16.22039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:15.223462Z","time spent":"996.919955ms","remote":"127.0.0.1:33898","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-07T04:00:16.220594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.84618ms","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":"warn","ts":"2026-02-07T04:00:16.220885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.494814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:00:16.220623Z","caller":"traceutil/trace.go:171","msg":"trace[1870466661] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:833; }","duration":"196.881955ms","start":"2026-02-07T04:00:16.023731Z","end":"2026-02-07T04:00:16.220613Z","steps":["trace[1870466661] 'range keys from in-memory index tree' (duration: 196.792877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:16.221426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.727606ms","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-07T04:00:16.220912Z","caller":"traceutil/trace.go:171","msg":"trace[1284251171] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:833; }","duration":"449.549607ms","start":"2026-02-07T04:00:15.771356Z","end":"2026-02-07T04:00:16.220906Z","steps":["trace[1284251171] 'range keys from in-memory index tree' (duration: 449.45055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:16.221625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:15.771342Z","time spent":"450.209584ms","remote":"127.0.0.1:33650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-07T04:00:16.221455Z","caller":"traceutil/trace.go:171","msg":"trace[665518902] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:833; }","duration":"197.775669ms","start":"2026-02-07T04:00:16.023671Z","end":"2026-02-07T04:00:16.221447Z","steps":["trace[665518902] 'range keys from in-memory index tree' (duration: 197.617921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:16.2223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.927655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-07T04:00:16.222508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.870953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:00:16.222537Z","caller":"traceutil/trace.go:171","msg":"trace[788458825] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:833; }","duration":"335.931744ms","start":"2026-02-07T04:00:15.886599Z","end":"2026-02-07T04:00:16.222531Z","steps":["trace[788458825] 'count revisions from in-memory index tree' (duration: 335.803323ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:00:16.222377Z","caller":"traceutil/trace.go:171","msg":"trace[2038934763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:833; }","duration":"174.011543ms","start":"2026-02-07T04:00:16.048358Z","end":"2026-02-07T04:00:16.22237Z","steps":["trace[2038934763] 'range keys from in-memory index tree' (duration: 173.877002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:16.222628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:15.886584Z","time spent":"336.03335ms","remote":"127.0.0.1:34066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-02-07T04:00:16.223264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.922485ms","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-07T04:00:16.223377Z","caller":"traceutil/trace.go:171","msg":"trace[1887445950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:833; }","duration":"175.044668ms","start":"2026-02-07T04:00:16.048325Z","end":"2026-02-07T04:00:16.22337Z","steps":["trace[1887445950] 'range keys from in-memory index tree' (duration: 174.835157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:16.224058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.811428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:00:16.224087Z","caller":"traceutil/trace.go:171","msg":"trace[1272631095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:833; }","duration":"163.848103ms","start":"2026-02-07T04:00:16.060231Z","end":"2026-02-07T04:00:16.224079Z","steps":["trace[1272631095] 'range keys from in-memory index tree' (duration: 163.788252ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:00:16.582781Z","caller":"traceutil/trace.go:171","msg":"trace[198500946] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"351.28078ms","start":"2026-02-07T04:00:16.231475Z","end":"2026-02-07T04:00:16.582756Z","steps":["trace[198500946] 'process raft request' (duration: 351.151368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:16.582892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:16.231458Z","time spent":"351.383965ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:00:16.636917Z","caller":"traceutil/trace.go:171","msg":"trace[1569120912] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"400.284515ms","start":"2026-02-07T04:00:16.236616Z","end":"2026-02-07T04:00:16.6369Z","steps":["trace[1569120912] 'process raft request' (duration: 400.239971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:16.637019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:16.236604Z","time spent":"400.360855ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:00:16.637104Z","caller":"traceutil/trace.go:171","msg":"trace[1839146788] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"403.396067ms","start":"2026-02-07T04:00:16.233702Z","end":"2026-02-07T04:00:16.637098Z","steps":["trace[1839146788] 'process raft request' (duration: 403.065794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:16.637128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:16.233672Z","time spent":"403.441161ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:00:16.6372Z","caller":"traceutil/trace.go:171","msg":"trace[854094032] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"398.677502ms","start":"2026-02-07T04:00:16.238518Z","end":"2026-02-07T04:00:16.637195Z","steps":["trace[854094032] 'process raft request' (duration: 398.357757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:16.637224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:16.238509Z","time spent":"398.701599ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:00:16.637556Z","caller":"traceutil/trace.go:171","msg":"trace[339780] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"398.153556ms","start":"2026-02-07T04:00:16.239397Z","end":"2026-02-07T04:00:16.637551Z","steps":["trace[339780] 'process raft request' (duration: 397.776929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:16.637583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:16.239389Z","time spent":"398.180062ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:00:16.637611Z","caller":"traceutil/trace.go:171","msg":"trace[1744952945] linearizableReadLoop","detail":"{readStateIndex:867; appliedIndex:863; }","duration":"378.815597ms","start":"2026-02-07T04:00:16.25877Z","end":"2026-02-07T04:00:16.637586Z","steps":["trace[1744952945] 'read index received' (duration: 324.666565ms)","trace[1744952945] 'applied index is now lower than readState.Index' (duration: 53.980485ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:00:16.637785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.997282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T04:00:16.637818Z","caller":"traceutil/trace.go:171","msg":"trace[417672303] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:838; }","duration":"379.072951ms","start":"2026-02-07T04:00:16.25874Z","end":"2026-02-07T04:00:16.637813Z","steps":["trace[417672303] 'agreement among raft nodes before linearized reading' (duration: 379.001521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:16.637837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:16.258727Z","time spent":"379.105006ms","remote":"127.0.0.1:33828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-02-07T04:00:21.112238Z","caller":"traceutil/trace.go:171","msg":"trace[2116936354] linearizableReadLoop","detail":"{readStateIndex:875; appliedIndex:874; }","duration":"473.394202ms","start":"2026-02-07T04:00:20.638827Z","end":"2026-02-07T04:00:21.112222Z","steps":["trace[2116936354] 'read index received' (duration: 473.237327ms)","trace[2116936354] 'applied index is now lower than readState.Index' (duration: 156.335µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:00:21.112455Z","caller":"traceutil/trace.go:171","msg":"trace[1050611763] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"518.104349ms","start":"2026-02-07T04:00:20.594345Z","end":"2026-02-07T04:00:21.112449Z","steps":["trace[1050611763] 'process raft request' (duration: 517.766663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:21.112515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:20.594328Z","time spent":"518.142113ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:00:21.112624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.810845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:00:21.11264Z","caller":"traceutil/trace.go:171","msg":"trace[990752861] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:845; }","duration":"473.872256ms","start":"2026-02-07T04:00:20.638763Z","end":"2026-02-07T04:00:21.112635Z","steps":["trace[990752861] 'agreement among raft nodes before linearized reading' (duration: 473.84267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:21.112655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:20.638745Z","time spent":"473.905111ms","remote":"127.0.0.1:33842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-07T04:00:21.112766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.163858ms","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-07T04:00:21.112779Z","caller":"traceutil/trace.go:171","msg":"trace[1964062606] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:845; }","duration":"463.196823ms","start":"2026-02-07T04:00:20.649578Z","end":"2026-02-07T04:00:21.112775Z","steps":["trace[1964062606] 'agreement among raft nodes before linearized reading' (duration: 463.140092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:21.112791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:20.649567Z","time spent":"463.221499ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T04:00:21.112897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.956571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T04:00:21.112913Z","caller":"traceutil/trace.go:171","msg":"trace[1187088107] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:845; }","duration":"228.992635ms","start":"2026-02-07T04:00:20.883915Z","end":"2026-02-07T04:00:21.112908Z","steps":["trace[1187088107] 'agreement among raft nodes before linearized reading' (duration: 228.967809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:21.113031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.310359ms","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-07T04:00:21.113044Z","caller":"traceutil/trace.go:171","msg":"trace[4057602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:845; }","duration":"454.342534ms","start":"2026-02-07T04:00:20.658697Z","end":"2026-02-07T04:00:21.11304Z","steps":["trace[4057602] 'agreement among raft nodes before linearized reading' (duration: 454.302041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:21.113055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:20.658685Z","time spent":"454.36793ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-07T04:00:21.113134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.550186ms","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-07T04:00:21.113146Z","caller":"traceutil/trace.go:171","msg":"trace[1518099751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:845; }","duration":"455.58658ms","start":"2026-02-07T04:00:20.657556Z","end":"2026-02-07T04:00:21.113142Z","steps":["trace[1518099751] 'agreement among raft nodes before linearized reading' (duration: 455.546126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:21.113157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:20.657545Z","time spent":"455.609846ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-07T04:00:21.113233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.919753ms","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-07T04:00:21.113245Z","caller":"traceutil/trace.go:171","msg":"trace[483101999] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:845; }","duration":"459.968975ms","start":"2026-02-07T04:00:20.653272Z","end":"2026-02-07T04:00:21.113241Z","steps":["trace[483101999] 'agreement among raft nodes before linearized reading' (duration: 459.927742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:21.113255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:20.653259Z","time spent":"459.994351ms","remote":"127.0.0.1:33898","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-07T04:00:21.404353Z","caller":"traceutil/trace.go:171","msg":"trace[1411139686] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"283.772186ms","start":"2026-02-07T04:00:21.120565Z","end":"2026-02-07T04:00:21.404337Z","steps":["trace[1411139686] 'process raft request' (duration: 280.900625ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:00:21.404495Z","caller":"traceutil/trace.go:171","msg":"trace[18424620] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"281.738981ms","start":"2026-02-07T04:00:21.122739Z","end":"2026-02-07T04:00:21.404478Z","steps":["trace[18424620] 'process raft request' (duration: 281.544942ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:00:21.404611Z","caller":"traceutil/trace.go:171","msg":"trace[1521087781] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"281.818349ms","start":"2026-02-07T04:00:21.122787Z","end":"2026-02-07T04:00:21.404605Z","steps":["trace[1521087781] 'process raft request' (duration: 281.652365ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:00:43.429746Z","caller":"traceutil/trace.go:171","msg":"trace[1455562046] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"110.543348ms","start":"2026-02-07T04:00:43.319185Z","end":"2026-02-07T04:00:43.429729Z","steps":["trace[1455562046] 'process raft request' (duration: 101.669548ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:00:45.567405Z","caller":"traceutil/trace.go:171","msg":"trace[1826423215] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"129.647928ms","start":"2026-02-07T04:00:45.437742Z","end":"2026-02-07T04:00:45.56739Z","steps":["trace[1826423215] 'process raft request' (duration: 129.54953ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:00:55.943809Z","caller":"traceutil/trace.go:171","msg":"trace[1006782358] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:988; }","duration":"300.942453ms","start":"2026-02-07T04:00:55.642851Z","end":"2026-02-07T04:00:55.943793Z","steps":["trace[1006782358] 'read index received' (duration: 300.834638ms)","trace[1006782358] 'applied index is now lower than readState.Index' (duration: 107.265µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:00:55.950497Z","caller":"traceutil/trace.go:171","msg":"trace[635142629] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"566.776123ms","start":"2026-02-07T04:00:55.383713Z","end":"2026-02-07T04:00:55.950489Z","steps":["trace[635142629] 'process raft request' (duration: 560.008247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:55.952377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:55.383684Z","time spent":"568.651996ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:00:55.950678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.811365ms","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-07T04:00:55.952492Z","caller":"traceutil/trace.go:171","msg":"trace[1370928539] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:952; }","duration":"309.670163ms","start":"2026-02-07T04:00:55.642815Z","end":"2026-02-07T04:00:55.952485Z","steps":["trace[1370928539] 'agreement among raft nodes before linearized reading' (duration: 307.695272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:55.952509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:55.642801Z","time spent":"309.703115ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-07T04:00:55.955072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.534718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T04:00:55.95531Z","caller":"traceutil/trace.go:171","msg":"trace[2049985796] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:952; }","duration":"239.794287ms","start":"2026-02-07T04:00:55.715508Z","end":"2026-02-07T04:00:55.955302Z","steps":["trace[2049985796] 'agreement among raft nodes before linearized reading' (duration: 237.150912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:55.955158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.582147ms","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-07T04:00:55.955666Z","caller":"traceutil/trace.go:171","msg":"trace[1839471291] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:952; }","duration":"244.116932ms","start":"2026-02-07T04:00:55.711544Z","end":"2026-02-07T04:00:55.955661Z","steps":["trace[1839471291] 'agreement among raft nodes before linearized reading' (duration: 241.106302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:56.629015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:56.103284Z","time spent":"525.727661ms","remote":"127.0.0.1:41716","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-07T04:00:56.62939Z","caller":"traceutil/trace.go:171","msg":"trace[1176775418] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"670.008013ms","start":"2026-02-07T04:00:55.95937Z","end":"2026-02-07T04:00:56.629378Z","steps":["trace[1176775418] 'process raft request' (duration: 666.704725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:56.629465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:55.959364Z","time spent":"670.046494ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:00:56.633258Z","caller":"traceutil/trace.go:171","msg":"trace[1946159847] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"671.716211ms","start":"2026-02-07T04:00:55.961531Z","end":"2026-02-07T04:00:56.633247Z","steps":["trace[1946159847] 'process raft request' (duration: 671.028422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:56.633311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:55.961524Z","time spent":"671.759441ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:00:56.633515Z","caller":"traceutil/trace.go:171","msg":"trace[1043007788] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"670.635532ms","start":"2026-02-07T04:00:55.962871Z","end":"2026-02-07T04:00:56.633507Z","steps":["trace[1043007788] 'process raft request' (duration: 669.730183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:56.633557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:55.962864Z","time spent":"670.670554ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:00:56.633693Z","caller":"traceutil/trace.go:171","msg":"trace[1386668674] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"625.465642ms","start":"2026-02-07T04:00:56.008219Z","end":"2026-02-07T04:00:56.633684Z","steps":["trace[1386668674] 'process raft request' (duration: 624.435343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:00:56.633759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:00:56.008205Z","time spent":"625.531957ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:01:15.606784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.620861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:01:15.606834Z","caller":"traceutil/trace.go:171","msg":"trace[519500052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1034; }","duration":"281.710448ms","start":"2026-02-07T04:01:15.325113Z","end":"2026-02-07T04:01:15.606824Z","steps":["trace[519500052] 'range keys from in-memory index tree' (duration: 281.50298ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:01:16.316376Z","caller":"traceutil/trace.go:171","msg":"trace[1546787864] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"243.333591ms","start":"2026-02-07T04:01:16.073024Z","end":"2026-02-07T04:01:16.316358Z","steps":["trace[1546787864] 'process raft request' (duration: 243.223119ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:01:16.317007Z","caller":"traceutil/trace.go:171","msg":"trace[352282781] linearizableReadLoop","detail":"{readStateIndex:1077; appliedIndex:1076; }","duration":"243.371062ms","start":"2026-02-07T04:01:16.073626Z","end":"2026-02-07T04:01:16.316997Z","steps":["trace[352282781] 'read index received' (duration: 242.563065ms)","trace[352282781] 'applied index is now lower than readState.Index' (duration: 807.017µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:01:16.317111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.469917ms","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-07T04:01:16.317131Z","caller":"traceutil/trace.go:171","msg":"trace[445720628] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1035; }","duration":"243.522404ms","start":"2026-02-07T04:01:16.073603Z","end":"2026-02-07T04:01:16.317125Z","steps":["trace[445720628] 'agreement among raft nodes before linearized reading' (duration: 243.433066ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:01:16.942144Z","caller":"traceutil/trace.go:171","msg":"trace[1884639390] linearizableReadLoop","detail":"{readStateIndex:1079; appliedIndex:1078; }","duration":"150.369414ms","start":"2026-02-07T04:01:16.791748Z","end":"2026-02-07T04:01:16.942117Z","steps":["trace[1884639390] 'read index received' (duration: 150.173913ms)","trace[1884639390] 'applied index is now lower than readState.Index' (duration: 194.251µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:01:16.942405Z","caller":"traceutil/trace.go:171","msg":"trace[1722056072] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"165.136719ms","start":"2026-02-07T04:01:16.777247Z","end":"2026-02-07T04:01:16.942384Z","steps":["trace[1722056072] 'process raft request' (duration: 164.689201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:01:16.942762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.992588ms","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-07T04:01:16.942886Z","caller":"traceutil/trace.go:171","msg":"trace[1633508000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1037; }","duration":"151.149028ms","start":"2026-02-07T04:01:16.79172Z","end":"2026-02-07T04:01:16.942869Z","steps":["trace[1633508000] 'agreement among raft nodes before linearized reading' (duration: 150.913857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:01:16.943154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.18298ms","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-07T04:01:16.943284Z","caller":"traceutil/trace.go:171","msg":"trace[584233634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1037; }","duration":"140.331683ms","start":"2026-02-07T04:01:16.802936Z","end":"2026-02-07T04:01:16.943268Z","steps":["trace[584233634] 'agreement among raft nodes before linearized reading' (duration: 140.131463ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:01:17.243662Z","caller":"traceutil/trace.go:171","msg":"trace[162832287] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"293.626807ms","start":"2026-02-07T04:01:16.949996Z","end":"2026-02-07T04:01:17.243623Z","steps":["trace[162832287] 'process raft request' (duration: 293.512876ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:01:17.283613Z","caller":"traceutil/trace.go:171","msg":"trace[701232130] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1080; }","duration":"215.644328ms","start":"2026-02-07T04:01:17.067944Z","end":"2026-02-07T04:01:17.283588Z","steps":["trace[701232130] 'read index received' (duration: 176.119508ms)","trace[701232130] 'applied index is now lower than readState.Index' (duration: 39.52363ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:01:17.284032Z","caller":"traceutil/trace.go:171","msg":"trace[1904846150] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"333.995155ms","start":"2026-02-07T04:01:16.950024Z","end":"2026-02-07T04:01:17.284019Z","steps":["trace[1904846150] 'process raft request' (duration: 333.41809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:01:17.284109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.149601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:01:17.284322Z","caller":"traceutil/trace.go:171","msg":"trace[864975578] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1039; }","duration":"216.368376ms","start":"2026-02-07T04:01:17.067939Z","end":"2026-02-07T04:01:17.284307Z","steps":["trace[864975578] 'agreement among raft nodes before linearized reading' (duration: 216.128366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:01:17.284275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:01:16.95001Z","time spent":"334.183718ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}