{"level":"warn","ts":"2026-02-03T22:36:49.458Z","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-03T22:36:49.458161Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57: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-03T22:36:49.458508Z","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-03T22:36:49.458559Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-02-03T22:36:49.458594Z","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-03T22:36:49.459307Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-02-03T22:36:49.459446Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57: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-03T22:36:49.467621Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.912892ms"} {"level":"info","ts":"2026-02-03T22:36:49.485166Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-02-03T22:36:49.485303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-02-03T22:36:49.48535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-02-03T22:36:49.485372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-03T22:36:49.485475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-02-03T22:36:49.485549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-02-03T22:36:49.496712Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-03T22:36:49.497895Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-03T22:36:49.498942Z","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-03T22:36:49.500782Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-03T22:36:49.502181Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-03T22:36:49.502351Z","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-03T22:36:49.502378Z","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-03T22:36:49.502386Z","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-03T22:36:49.518101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-02-03T22:36:49.518288Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-02-03T22:36:49.519962Z","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-03T22:36:49.520704Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-03T22:36:49.520833Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-03T22:36:49.520127Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-02-03T22:36:49.52105Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-02-03T22:36:49.588804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-02-03T22:36:49.589014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-03T22:36:49.592218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-02-03T22:36:49.592296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-02-03T22:36:49.59234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-02-03T22:36:49.592367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-02-03T22:36:49.592461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-02-03T22:36:49.594972Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T22:36:49.597944Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-02-03T22:36:49.597993Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T22:36:49.598111Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T22:36:49.598276Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T22:36:49.598314Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T22:36:49.598445Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T22:36:49.598662Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-03T22:36:49.598675Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-03T22:36:49.600062Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-03T22:36:49.602496Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-02-03T22:37:10.978026Z","caller":"traceutil/trace.go:171","msg":"trace[1661828590] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"141.958359ms","start":"2026-02-03T22:37:10.83605Z","end":"2026-02-03T22:37:10.978008Z","steps":["trace[1661828590] 'process raft request' (duration: 141.840326ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:37:19.714189Z","caller":"traceutil/trace.go:171","msg":"trace[1951515164] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:487; }","duration":"145.15589ms","start":"2026-02-03T22:37:19.569013Z","end":"2026-02-03T22:37:19.714169Z","steps":["trace[1951515164] 'read index received' (duration: 144.985985ms)","trace[1951515164] 'applied index is now lower than readState.Index' (duration: 169.025µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:37:19.714448Z","caller":"traceutil/trace.go:171","msg":"trace[567587949] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"699.415032ms","start":"2026-02-03T22:37:19.015021Z","end":"2026-02-03T22:37:19.714436Z","steps":["trace[567587949] 'process raft request' (duration: 699.030561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:19.714902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:19.015004Z","time spent":"699.478485ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T22:37:19.71514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.125037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4106"} {"level":"info","ts":"2026-02-03T22:37:19.71519Z","caller":"traceutil/trace.go:171","msg":"trace[1198224918] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:472; }","duration":"146.210199ms","start":"2026-02-03T22:37:19.568968Z","end":"2026-02-03T22:37:19.715179Z","steps":["trace[1198224918] 'agreement among raft nodes before linearized reading' (duration: 146.129797ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:37:22.115806Z","caller":"traceutil/trace.go:171","msg":"trace[184645733] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:492; }","duration":"375.834195ms","start":"2026-02-03T22:37:21.739955Z","end":"2026-02-03T22:37:22.115789Z","steps":["trace[184645733] 'read index received' (duration: 375.68988ms)","trace[184645733] 'applied index is now lower than readState.Index' (duration: 143.725µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:37:22.115962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.989089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-03T22:37:22.115985Z","caller":"traceutil/trace.go:171","msg":"trace[1417983709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:477; }","duration":"376.066311ms","start":"2026-02-03T22:37:21.739912Z","end":"2026-02-03T22:37:22.115978Z","steps":["trace[1417983709] 'agreement among raft nodes before linearized reading' (duration: 375.954908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:22.11601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:21.7399Z","time spent":"376.103672ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-03T22:37:22.116213Z","caller":"traceutil/trace.go:171","msg":"trace[863429183] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"392.810833ms","start":"2026-02-03T22:37:21.723393Z","end":"2026-02-03T22:37:22.116204Z","steps":["trace[863429183] 'process raft request' (duration: 392.299279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:22.11627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:21.723382Z","time spent":"392.850064ms","remote":"127.0.0.1:52396","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-03T22:37:26.564372Z","caller":"traceutil/trace.go:171","msg":"trace[405117118] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:506; }","duration":"438.194355ms","start":"2026-02-03T22:37:26.126159Z","end":"2026-02-03T22:37:26.564353Z","steps":["trace[405117118] 'read index received' (duration: 438.0324ms)","trace[405117118] 'applied index is now lower than readState.Index' (duration: 161.015µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:37:26.564482Z","caller":"traceutil/trace.go:171","msg":"trace[1135497642] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"458.254881ms","start":"2026-02-03T22:37:26.106219Z","end":"2026-02-03T22:37:26.564474Z","steps":["trace[1135497642] 'process raft request' (duration: 457.991364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:26.564568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:26.106197Z","time spent":"458.305563ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T22:37:26.565001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.067588ms","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-03T22:37:26.565897Z","caller":"traceutil/trace.go:171","msg":"trace[677349338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:490; }","duration":"377.975744ms","start":"2026-02-03T22:37:26.187908Z","end":"2026-02-03T22:37:26.565884Z","steps":["trace[677349338] 'agreement among raft nodes before linearized reading' (duration: 376.954035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:26.565964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:26.187882Z","time spent":"378.072177ms","remote":"127.0.0.1:52396","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-03T22:37:26.565457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.495392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-v2r7q\" ","response":"range_response_count:1 size:16652"} {"level":"warn","ts":"2026-02-03T22:37:26.565269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.119051ms","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-03T22:37:26.568288Z","caller":"traceutil/trace.go:171","msg":"trace[1291813842] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:490; }","duration":"442.154267ms","start":"2026-02-03T22:37:26.126113Z","end":"2026-02-03T22:37:26.568267Z","steps":["trace[1291813842] 'agreement among raft nodes before linearized reading' (duration: 439.07798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:26.568569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:26.126072Z","time spent":"442.482896ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-03T22:37:26.566063Z","caller":"traceutil/trace.go:171","msg":"trace[1737727971] range","detail":"{range_begin:/registry/pods/kube-system/cilium-v2r7q; range_end:; response_count:1; response_revision:490; }","duration":"263.146421ms","start":"2026-02-03T22:37:26.302908Z","end":"2026-02-03T22:37:26.566055Z","steps":["trace[1737727971] 'agreement among raft nodes before linearized reading' (duration: 262.459421ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:37:27.447207Z","caller":"traceutil/trace.go:171","msg":"trace[1254086069] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:508; }","duration":"868.484656ms","start":"2026-02-03T22:37:26.578698Z","end":"2026-02-03T22:37:27.447182Z","steps":["trace[1254086069] 'read index received' (duration: 17.443433ms)","trace[1254086069] 'applied index is now lower than readState.Index' (duration: 851.040363ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:37:27.44744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.715083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-03T22:37:27.447473Z","caller":"traceutil/trace.go:171","msg":"trace[1113439200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:494; }","duration":"868.789545ms","start":"2026-02-03T22:37:26.578674Z","end":"2026-02-03T22:37:27.447463Z","steps":["trace[1113439200] 'agreement among raft nodes before linearized reading' (duration: 868.61945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:27.447509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:26.578649Z","time spent":"868.852246ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-03T22:37:27.447815Z","caller":"traceutil/trace.go:171","msg":"trace[2079493026] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"874.800885ms","start":"2026-02-03T22:37:26.573003Z","end":"2026-02-03T22:37:27.447803Z","steps":["trace[2079493026] 'process raft request' (duration: 873.967881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:27.447899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:26.572986Z","time spent":"874.862886ms","remote":"127.0.0.1:52396","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-03T22:37:27.448028Z","caller":"traceutil/trace.go:171","msg":"trace[436712289] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"874.900478ms","start":"2026-02-03T22:37:26.573106Z","end":"2026-02-03T22:37:27.448007Z","steps":["trace[436712289] 'process raft request' (duration: 873.989422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:27.448075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:26.573101Z","time spent":"874.949049ms","remote":"127.0.0.1:52396","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-03T22:37:27.44847Z","caller":"traceutil/trace.go:171","msg":"trace[648668270] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"865.952494ms","start":"2026-02-03T22:37:26.582504Z","end":"2026-02-03T22:37:27.448456Z","steps":["trace[648668270] 'process raft request' (duration: 864.638327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:27.450613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:26.582488Z","time spent":"868.084885ms","remote":"127.0.0.1:52302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T22:37:29.602239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.71928ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-03T22:37:29.60249Z","caller":"traceutil/trace.go:171","msg":"trace[1071814422] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"271.017434ms","start":"2026-02-03T22:37:29.331459Z","end":"2026-02-03T22:37:29.602477Z","steps":["trace[1071814422] 'process raft request' (duration: 149.873562ms)","trace[1071814422] 'compare' (duration: 119.984299ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:37:29.603355Z","caller":"traceutil/trace.go:171","msg":"trace[1160808276] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:531; }","duration":"150.49095ms","start":"2026-02-03T22:37:29.452854Z","end":"2026-02-03T22:37:29.603345Z","steps":["trace[1160808276] 'read index received' (duration: 28.431023ms)","trace[1160808276] 'applied index is now lower than readState.Index' (duration: 122.059337ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:37:29.603426Z","caller":"traceutil/trace.go:171","msg":"trace[74824279] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"268.256106ms","start":"2026-02-03T22:37:29.335161Z","end":"2026-02-03T22:37:29.603417Z","steps":["trace[74824279] 'process raft request' (duration: 267.897846ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:37:29.60368Z","caller":"traceutil/trace.go:171","msg":"trace[1195218721] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"186.97602ms","start":"2026-02-03T22:37:29.41669Z","end":"2026-02-03T22:37:29.603666Z","steps":["trace[1195218721] 'process raft request' (duration: 186.631301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:29.603902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.044426ms","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-03T22:37:29.604006Z","caller":"traceutil/trace.go:171","msg":"trace[1135914502] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:516; }","duration":"151.19548ms","start":"2026-02-03T22:37:29.452803Z","end":"2026-02-03T22:37:29.603998Z","steps":["trace[1135914502] 'agreement among raft nodes before linearized reading' (duration: 151.056116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:29.604321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.729579ms","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-03T22:37:29.604523Z","caller":"traceutil/trace.go:171","msg":"trace[472645061] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:516; }","duration":"149.903243ms","start":"2026-02-03T22:37:29.454563Z","end":"2026-02-03T22:37:29.604466Z","steps":["trace[472645061] 'agreement among raft nodes before linearized reading' (duration: 149.732098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:29.610341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.782979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-03T22:37:29.613573Z","caller":"traceutil/trace.go:171","msg":"trace[1543543243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:516; }","duration":"119.049381ms","start":"2026-02-03T22:37:29.494508Z","end":"2026-02-03T22:37:29.613558Z","steps":["trace[1543543243] 'agreement among raft nodes before linearized reading' (duration: 115.497671ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:37:32.678061Z","caller":"traceutil/trace.go:171","msg":"trace[152556277] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"205.859184ms","start":"2026-02-03T22:37:32.472175Z","end":"2026-02-03T22:37:32.678035Z","steps":["trace[152556277] 'process raft request' (duration: 202.679724ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:37:38.878622Z","caller":"traceutil/trace.go:171","msg":"trace[1110351923] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"168.365395ms","start":"2026-02-03T22:37:38.710239Z","end":"2026-02-03T22:37:38.878605Z","steps":["trace[1110351923] 'process raft request' (duration: 168.244801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:42.174186Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322100858263556726,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T22:37:42.610472Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.099918242s","expected-duration":"1s"} {"level":"info","ts":"2026-02-03T22:37:42.610722Z","caller":"traceutil/trace.go:171","msg":"trace[1275232893] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"1.219240842s","start":"2026-02-03T22:37:41.391467Z","end":"2026-02-03T22:37:42.610708Z","steps":["trace[1275232893] 'process raft request' (duration: 1.219123078s)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:37:42.610801Z","caller":"traceutil/trace.go:171","msg":"trace[117562814] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:684; }","duration":"937.475115ms","start":"2026-02-03T22:37:41.673312Z","end":"2026-02-03T22:37:42.610787Z","steps":["trace[117562814] 'read index received' (duration: 937.471405ms)","trace[117562814] 'applied index is now lower than readState.Index' (duration: 3.1µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:37:42.610931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.609999ms","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-03T22:37:42.610958Z","caller":"traceutil/trace.go:171","msg":"trace[1089769620] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:662; }","duration":"937.665551ms","start":"2026-02-03T22:37:41.673287Z","end":"2026-02-03T22:37:42.610952Z","steps":["trace[1089769620] 'agreement among raft nodes before linearized reading' (duration: 937.550977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:42.610973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:41.673273Z","time spent":"937.695811ms","remote":"127.0.0.1:52396","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-03T22:37:42.611243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:41.391446Z","time spent":"1.219302424s","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T22:37:43.111801Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322100858263556729,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T22:37:43.470364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.577484ms","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-03T22:37:43.470443Z","caller":"traceutil/trace.go:171","msg":"trace[467604797] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:662; }","duration":"422.680137ms","start":"2026-02-03T22:37:43.047751Z","end":"2026-02-03T22:37:43.470432Z","steps":["trace[467604797] 'range keys from in-memory index tree' (duration: 422.567354ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:37:43.470742Z","caller":"traceutil/trace.go:171","msg":"trace[2021920682] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:684; }","duration":"859.912715ms","start":"2026-02-03T22:37:42.610821Z","end":"2026-02-03T22:37:43.470734Z","steps":["trace[2021920682] 'read index received' (duration: 776.621333ms)","trace[2021920682] 'applied index is now lower than readState.Index' (duration: 83.290772ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:37:43.470804Z","caller":"traceutil/trace.go:171","msg":"trace[1859551608] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"1.046184516s","start":"2026-02-03T22:37:42.424613Z","end":"2026-02-03T22:37:43.470797Z","steps":["trace[1859551608] 'process raft request' (duration: 962.76005ms)","trace[1859551608] 'compare' (duration: 82.614073ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:37:43.470853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:42.424602Z","time spent":"1.046218667s","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T22:37:43.471041Z","caller":"traceutil/trace.go:171","msg":"trace[632766189] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"856.86957ms","start":"2026-02-03T22:37:42.614165Z","end":"2026-02-03T22:37:43.471035Z","steps":["trace[632766189] 'process raft request' (duration: 856.50256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:43.471086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:42.614153Z","time spent":"856.910141ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T22:37:43.471294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046854225s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-ncdp7\" ","response":"range_response_count:1 size:4820"} {"level":"info","ts":"2026-02-03T22:37:43.471321Z","caller":"traceutil/trace.go:171","msg":"trace[1082298988] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-ncdp7; range_end:; response_count:1; response_revision:665; }","duration":"1.046905337s","start":"2026-02-03T22:37:42.424407Z","end":"2026-02-03T22:37:43.471313Z","steps":["trace[1082298988] 'agreement among raft nodes before linearized reading' (duration: 1.046798244s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:43.471344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:42.424392Z","time spent":"1.046948108s","remote":"127.0.0.1:52302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4843,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-ncdp7\" "} {"level":"warn","ts":"2026-02-03T22:37:43.471469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.780999578s","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-03T22:37:43.471491Z","caller":"traceutil/trace.go:171","msg":"trace[1349591473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:665; }","duration":"1.781020859s","start":"2026-02-03T22:37:41.690465Z","end":"2026-02-03T22:37:43.471485Z","steps":["trace[1349591473] 'agreement among raft nodes before linearized reading' (duration: 1.780966197s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:43.471511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:41.69046Z","time spent":"1.781047749s","remote":"127.0.0.1:52396","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":"warn","ts":"2026-02-03T22:37:43.471622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.784216418s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-03T22:37:43.471645Z","caller":"traceutil/trace.go:171","msg":"trace[1162126817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:665; }","duration":"1.78425041s","start":"2026-02-03T22:37:41.68739Z","end":"2026-02-03T22:37:43.47164Z","steps":["trace[1162126817] 'agreement among raft nodes before linearized reading' (duration: 1.784197468s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:43.471665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:41.687384Z","time spent":"1.784276971s","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T22:37:43.471739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.791642008s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:37:43.47176Z","caller":"traceutil/trace.go:171","msg":"trace[774016858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:665; }","duration":"1.791660778s","start":"2026-02-03T22:37:41.680093Z","end":"2026-02-03T22:37:43.471754Z","steps":["trace[774016858] 'agreement among raft nodes before linearized reading' (duration: 1.791631927s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:43.471778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:41.680077Z","time spent":"1.79169589s","remote":"127.0.0.1:52152","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-03T22:37:43.471893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.79717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-03T22:37:43.471914Z","caller":"traceutil/trace.go:171","msg":"trace[918035749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:665; }","duration":"570.841742ms","start":"2026-02-03T22:37:42.901068Z","end":"2026-02-03T22:37:43.47191Z","steps":["trace[918035749] 'agreement among raft nodes before linearized reading' (duration: 570.79012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:43.471934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:42.901054Z","time spent":"570.876142ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T22:37:43.981965Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322100858263556734,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T22:37:44.496523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.651046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T22:37:44.4966Z","caller":"traceutil/trace.go:171","msg":"trace[747679543] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"1.020262435s","start":"2026-02-03T22:37:43.476324Z","end":"2026-02-03T22:37:44.496586Z","steps":["trace[747679543] 'process raft request' (duration: 699.492945ms)","trace[747679543] 'compare' (duration: 320.530063ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:37:44.49664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:43.476316Z","time spent":"1.020306026s","remote":"127.0.0.1:52396","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-03T22:37:44.5258Z","caller":"traceutil/trace.go:171","msg":"trace[1133883769] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:687; }","duration":"1.0445353s","start":"2026-02-03T22:37:43.481244Z","end":"2026-02-03T22:37:44.52578Z","steps":["trace[1133883769] 'read index received' (duration: 694.641378ms)","trace[1133883769] 'applied index is now lower than readState.Index' (duration: 349.893132ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:37:44.525837Z","caller":"traceutil/trace.go:171","msg":"trace[1615418668] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"1.048195033s","start":"2026-02-03T22:37:43.477622Z","end":"2026-02-03T22:37:44.525817Z","steps":["trace[1615418668] 'process raft request' (duration: 1.047921765s)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:37:44.525897Z","caller":"traceutil/trace.go:171","msg":"trace[1279574582] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"1.048213864s","start":"2026-02-03T22:37:43.477675Z","end":"2026-02-03T22:37:44.525888Z","steps":["trace[1279574582] 'process raft request' (duration: 1.04806562s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:44.525965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:43.47767Z","time spent":"1.048248995s","remote":"127.0.0.1:52396","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-03T22:37:44.525986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:43.477607Z","time spent":"1.048282775s","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T22:37:44.526125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.044876979s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-03T22:37:44.526635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033073646s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-03T22:37:44.526674Z","caller":"traceutil/trace.go:171","msg":"trace[49056282] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:668; }","duration":"1.033123537s","start":"2026-02-03T22:37:43.493539Z","end":"2026-02-03T22:37:44.526662Z","steps":["trace[49056282] 'agreement among raft nodes before linearized reading' (duration: 1.032980813s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:44.526724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:43.493532Z","time spent":"1.033168089s","remote":"127.0.0.1:52162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.57\" "} {"level":"info","ts":"2026-02-03T22:37:44.527793Z","caller":"traceutil/trace.go:171","msg":"trace[163474242] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:668; }","duration":"1.0449224s","start":"2026-02-03T22:37:43.481223Z","end":"2026-02-03T22:37:44.526145Z","steps":["trace[163474242] 'agreement among raft nodes before linearized reading' (duration: 1.044856318s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:44.527832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:43.481215Z","time spent":"1.046605498s","remote":"127.0.0.1:52152","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-03T22:37:44.725687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.246482ms","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-03T22:37:44.725745Z","caller":"traceutil/trace.go:171","msg":"trace[1829240544] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:669; }","duration":"111.356215ms","start":"2026-02-03T22:37:44.614376Z","end":"2026-02-03T22:37:44.725732Z","steps":["trace[1829240544] 'range keys from in-memory index tree' (duration: 111.120028ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:37:48.93041Z","caller":"traceutil/trace.go:171","msg":"trace[2079010519] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"361.838579ms","start":"2026-02-03T22:37:48.568552Z","end":"2026-02-03T22:37:48.93039Z","steps":["trace[2079010519] 'process raft request' (duration: 361.726196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:48.930748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:48.568539Z","time spent":"361.929782ms","remote":"127.0.0.1:52396","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-03T22:37:49.19577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.442223ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-03T22:37:49.195841Z","caller":"traceutil/trace.go:171","msg":"trace[401922365] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:702; }","duration":"586.097012ms","start":"2026-02-03T22:37:48.609731Z","end":"2026-02-03T22:37:49.195828Z","steps":["trace[401922365] 'read index received' (duration: 320.757399ms)","trace[401922365] 'applied index is now lower than readState.Index' (duration: 265.338513ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:37:49.195986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.245936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-03T22:37:49.196038Z","caller":"traceutil/trace.go:171","msg":"trace[702276563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:679; }","duration":"586.290908ms","start":"2026-02-03T22:37:48.609707Z","end":"2026-02-03T22:37:49.195998Z","steps":["trace[702276563] 'agreement among raft nodes before linearized reading' (duration: 586.191225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:49.196061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:48.609694Z","time spent":"586.36156ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T22:37:49.196182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.297848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-03T22:37:49.196236Z","caller":"traceutil/trace.go:171","msg":"trace[1113782348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:679; }","duration":"586.37545ms","start":"2026-02-03T22:37:48.609845Z","end":"2026-02-03T22:37:49.19622Z","steps":["trace[1113782348] 'agreement among raft nodes before linearized reading' (duration: 586.230446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:49.196279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:48.609832Z","time spent":"586.436472ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T22:37:49.196296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.099781ms","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-03T22:37:49.196316Z","caller":"traceutil/trace.go:171","msg":"trace[89133520] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:679; }","duration":"410.153723ms","start":"2026-02-03T22:37:48.786156Z","end":"2026-02-03T22:37:49.19631Z","steps":["trace[89133520] 'agreement among raft nodes before linearized reading' (duration: 410.094681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:49.196333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:48.786138Z","time spent":"410.191114ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-03T22:37:49.196386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.941255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:37:49.196399Z","caller":"traceutil/trace.go:171","msg":"trace[1608640037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:679; }","duration":"513.953875ms","start":"2026-02-03T22:37:48.682441Z","end":"2026-02-03T22:37:49.196395Z","steps":["trace[1608640037] 'agreement among raft nodes before linearized reading' (duration: 513.933165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:49.196414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:48.682396Z","time spent":"514.014987ms","remote":"127.0.0.1:52152","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-03T22:37:50.498118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.624792ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T22:37:50.498211Z","caller":"traceutil/trace.go:171","msg":"trace[2134437782] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:704; }","duration":"927.170215ms","start":"2026-02-03T22:37:49.571032Z","end":"2026-02-03T22:37:50.498202Z","steps":["trace[2134437782] 'read index received' (duration: 485.377898ms)","trace[2134437782] 'applied index is now lower than readState.Index' (duration: 441.791757ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:37:50.498294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.268018ms","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-03T22:37:50.498306Z","caller":"traceutil/trace.go:171","msg":"trace[1935885996] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:682; }","duration":"927.31257ms","start":"2026-02-03T22:37:49.570989Z","end":"2026-02-03T22:37:50.498301Z","steps":["trace[1935885996] 'agreement among raft nodes before linearized reading' (duration: 927.236917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:50.498322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:49.570972Z","time spent":"927.34509ms","remote":"127.0.0.1:52396","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-03T22:37:50.498471Z","caller":"traceutil/trace.go:171","msg":"trace[912457635] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"1.292737048s","start":"2026-02-03T22:37:49.205728Z","end":"2026-02-03T22:37:50.498465Z","steps":["trace[912457635] 'process raft request' (duration: 850.717875ms)","trace[912457635] 'compare' (duration: 441.461167ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:37:50.4985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:49.205717Z","time spent":"1.292767399s","remote":"127.0.0.1:52396","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-03T22:37:50.498576Z","caller":"traceutil/trace.go:171","msg":"trace[301272922] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"1.288694664s","start":"2026-02-03T22:37:49.209874Z","end":"2026-02-03T22:37:50.498569Z","steps":["trace[301272922] 'process raft request' (duration: 1.288300163s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:50.498602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:49.209864Z","time spent":"1.288723015s","remote":"127.0.0.1:52396","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-03T22:37:50.841129Z","caller":"traceutil/trace.go:171","msg":"trace[65804893] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:706; }","duration":"158.572169ms","start":"2026-02-03T22:37:50.682536Z","end":"2026-02-03T22:37:50.841109Z","steps":["trace[65804893] 'read index received' (duration: 157.792797ms)","trace[65804893] 'applied index is now lower than readState.Index' (duration: 778.332µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:37:50.84125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.698413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:37:50.8418Z","caller":"traceutil/trace.go:171","msg":"trace[1882657686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:683; }","duration":"159.291679ms","start":"2026-02-03T22:37:50.682495Z","end":"2026-02-03T22:37:50.841787Z","steps":["trace[1882657686] 'agreement among raft nodes before linearized reading' (duration: 158.691402ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:37:50.84241Z","caller":"traceutil/trace.go:171","msg":"trace[153089184] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"340.156317ms","start":"2026-02-03T22:37:50.50224Z","end":"2026-02-03T22:37:50.842396Z","steps":["trace[153089184] 'process raft request' (duration: 338.16156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:50.842501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:37:50.502227Z","time spent":"340.216478ms","remote":"127.0.0.1:52396","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-03T22:37:52.618726Z","caller":"traceutil/trace.go:171","msg":"trace[2117541782] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"103.017659ms","start":"2026-02-03T22:37:52.515689Z","end":"2026-02-03T22:37:52.618707Z","steps":["trace[2117541782] 'process raft request' (duration: 102.933647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:37:52.802634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.464339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-03T22:37:52.802564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.616037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:37:52.802684Z","caller":"traceutil/trace.go:171","msg":"trace[1524382938] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:692; }","duration":"110.556162ms","start":"2026-02-03T22:37:52.692118Z","end":"2026-02-03T22:37:52.802674Z","steps":["trace[1524382938] 'count revisions from in-memory index tree' (duration: 110.406518ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:37:52.802731Z","caller":"traceutil/trace.go:171","msg":"trace[1174271948] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:692; }","duration":"120.831783ms","start":"2026-02-03T22:37:52.68187Z","end":"2026-02-03T22:37:52.802701Z","steps":["trace[1174271948] 'range keys from in-memory index tree' (duration: 120.540715ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:37:53.037532Z","caller":"traceutil/trace.go:171","msg":"trace[410230331] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"187.831013ms","start":"2026-02-03T22:37:52.849681Z","end":"2026-02-03T22:37:53.037512Z","steps":["trace[410230331] 'process raft request' (duration: 187.561226ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:38:01.558199Z","caller":"traceutil/trace.go:171","msg":"trace[1623118274] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"181.622249ms","start":"2026-02-03T22:38:01.37656Z","end":"2026-02-03T22:38:01.558183Z","steps":["trace[1623118274] 'process raft request' (duration: 181.525237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:02.355583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.470566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:38:02.355645Z","caller":"traceutil/trace.go:171","msg":"trace[837648710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:719; }","duration":"675.563549ms","start":"2026-02-03T22:38:01.680069Z","end":"2026-02-03T22:38:02.355632Z","steps":["trace[837648710] 'range keys from in-memory index tree' (duration: 675.423795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:02.355671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:01.680056Z","time spent":"675.60988ms","remote":"127.0.0.1:52152","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-03T22:38:02.356072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:01.80572Z","time spent":"550.350493ms","remote":"127.0.0.1:49502","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-03T22:38:11.294063Z","caller":"traceutil/trace.go:171","msg":"trace[723210222] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:772; }","duration":"126.831212ms","start":"2026-02-03T22:38:11.167215Z","end":"2026-02-03T22:38:11.294046Z","steps":["trace[723210222] 'read index received' (duration: 126.654277ms)","trace[723210222] 'applied index is now lower than readState.Index' (duration: 176.415µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:38:11.294472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.237633ms","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-03T22:38:11.294601Z","caller":"traceutil/trace.go:171","msg":"trace[1104392376] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:745; }","duration":"127.391048ms","start":"2026-02-03T22:38:11.167178Z","end":"2026-02-03T22:38:11.29457Z","steps":["trace[1104392376] 'agreement among raft nodes before linearized reading' (duration: 127.144321ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:38:11.294933Z","caller":"traceutil/trace.go:171","msg":"trace[1669129494] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"165.382971ms","start":"2026-02-03T22:38:11.129542Z","end":"2026-02-03T22:38:11.294925Z","steps":["trace[1669129494] 'process raft request' (duration: 164.372792ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:38:13.356616Z","caller":"traceutil/trace.go:171","msg":"trace[443250567] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:775; }","duration":"434.075299ms","start":"2026-02-03T22:38:12.922519Z","end":"2026-02-03T22:38:13.356595Z","steps":["trace[443250567] 'read index received' (duration: 433.858873ms)","trace[443250567] 'applied index is now lower than readState.Index' (duration: 215.486µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:38:13.356725Z","caller":"traceutil/trace.go:171","msg":"trace[1024921478] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"656.206402ms","start":"2026-02-03T22:38:12.70051Z","end":"2026-02-03T22:38:13.356716Z","steps":["trace[1024921478] 'process raft request' (duration: 655.931565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:13.356841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:12.700494Z","time spent":"656.261563ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T22:38:13.357353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.81487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-03T22:38:13.357468Z","caller":"traceutil/trace.go:171","msg":"trace[1681119346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:748; }","duration":"434.970614ms","start":"2026-02-03T22:38:12.922474Z","end":"2026-02-03T22:38:13.357445Z","steps":["trace[1681119346] 'agreement among raft nodes before linearized reading' (duration: 434.676646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:13.35754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:12.922458Z","time spent":"435.070147ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T22:38:13.515078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.770444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T22:38:13.516074Z","caller":"traceutil/trace.go:171","msg":"trace[798835389] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"151.54797ms","start":"2026-02-03T22:38:13.364511Z","end":"2026-02-03T22:38:13.516059Z","steps":["trace[798835389] 'process raft request' (duration: 151.292692ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:38:13.516891Z","caller":"traceutil/trace.go:171","msg":"trace[2018058113] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"152.618171ms","start":"2026-02-03T22:38:13.364257Z","end":"2026-02-03T22:38:13.516876Z","steps":["trace[2018058113] 'process raft request' (duration: 48.991244ms)","trace[2018058113] 'compare' (duration: 101.595219ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:38:31.975139Z","caller":"traceutil/trace.go:171","msg":"trace[1204806164] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"480.858262ms","start":"2026-02-03T22:38:31.494259Z","end":"2026-02-03T22:38:31.975117Z","steps":["trace[1204806164] 'process raft request' (duration: 480.724898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:31.975298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:31.49425Z","time spent":"480.957905ms","remote":"127.0.0.1:52396","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-03T22:38:31.976033Z","caller":"traceutil/trace.go:171","msg":"trace[97585027] linearizableReadLoop","detail":"{readStateIndex:889; appliedIndex:887; }","duration":"276.627492ms","start":"2026-02-03T22:38:31.699393Z","end":"2026-02-03T22:38:31.97602Z","steps":["trace[97585027] 'read index received' (duration: 275.510531ms)","trace[97585027] 'applied index is now lower than readState.Index' (duration: 1.116191ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:38:31.97633Z","caller":"traceutil/trace.go:171","msg":"trace[246853516] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"479.676619ms","start":"2026-02-03T22:38:31.496641Z","end":"2026-02-03T22:38:31.976317Z","steps":["trace[246853516] 'process raft request' (duration: 479.302658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:31.976413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:31.496634Z","time spent":"479.72168ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T22:38:31.976596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.200248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:38:31.976651Z","caller":"traceutil/trace.go:171","msg":"trace[155373177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:855; }","duration":"277.25305ms","start":"2026-02-03T22:38:31.699365Z","end":"2026-02-03T22:38:31.976618Z","steps":["trace[155373177] 'agreement among raft nodes before linearized reading' (duration: 277.203579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:31.976913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.480366ms","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-03T22:38:31.976965Z","caller":"traceutil/trace.go:171","msg":"trace[879916436] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:855; }","duration":"277.510037ms","start":"2026-02-03T22:38:31.699423Z","end":"2026-02-03T22:38:31.976933Z","steps":["trace[879916436] 'agreement among raft nodes before linearized reading' (duration: 277.390194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:31.977952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.266939ms","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-03T22:38:31.978018Z","caller":"traceutil/trace.go:171","msg":"trace[1409197439] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:855; }","duration":"278.354401ms","start":"2026-02-03T22:38:31.699648Z","end":"2026-02-03T22:38:31.978002Z","steps":["trace[1409197439] 'agreement among raft nodes before linearized reading' (duration: 278.149836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:34.15015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.997318ms","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-03T22:38:34.150262Z","caller":"traceutil/trace.go:171","msg":"trace[1959364417] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:859; }","duration":"166.135972ms","start":"2026-02-03T22:38:33.984102Z","end":"2026-02-03T22:38:34.150238Z","steps":["trace[1959364417] 'range keys from in-memory index tree' (duration: 165.798763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:34.150547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.9048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:38:34.150577Z","caller":"traceutil/trace.go:171","msg":"trace[1066465524] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:859; }","duration":"470.956941ms","start":"2026-02-03T22:38:33.67961Z","end":"2026-02-03T22:38:34.150567Z","steps":["trace[1066465524] 'range keys from in-memory index tree' (duration: 470.857438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:34.150606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:33.679595Z","time spent":"471.003373ms","remote":"127.0.0.1:52152","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-03T22:38:34.151349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.24335ms","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-03T22:38:34.151383Z","caller":"traceutil/trace.go:171","msg":"trace[245036231] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:859; }","duration":"158.289101ms","start":"2026-02-03T22:38:33.993084Z","end":"2026-02-03T22:38:34.151374Z","steps":["trace[245036231] 'range keys from in-memory index tree' (duration: 158.114466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:34.151589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.651822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-03T22:38:34.151616Z","caller":"traceutil/trace.go:171","msg":"trace[231320958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:859; }","duration":"163.717614ms","start":"2026-02-03T22:38:33.98789Z","end":"2026-02-03T22:38:34.151608Z","steps":["trace[231320958] 'range keys from in-memory index tree' (duration: 163.549039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:34.151826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.057422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-03T22:38:34.151852Z","caller":"traceutil/trace.go:171","msg":"trace[984624670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:859; }","duration":"159.095353ms","start":"2026-02-03T22:38:33.992749Z","end":"2026-02-03T22:38:34.151844Z","steps":["trace[984624670] 'range keys from in-memory index tree' (duration: 158.9956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:34.152316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.552308ms","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-03T22:38:34.152347Z","caller":"traceutil/trace.go:171","msg":"trace[205450726] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:859; }","duration":"158.591339ms","start":"2026-02-03T22:38:33.993746Z","end":"2026-02-03T22:38:34.152337Z","steps":["trace[205450726] 'range keys from in-memory index tree' (duration: 158.472036ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:38:34.353355Z","caller":"traceutil/trace.go:171","msg":"trace[798354119] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"195.256164ms","start":"2026-02-03T22:38:34.158081Z","end":"2026-02-03T22:38:34.353337Z","steps":["trace[798354119] 'process raft request' (duration: 195.150181ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:38:34.420351Z","caller":"traceutil/trace.go:171","msg":"trace[729571648] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"261.609168ms","start":"2026-02-03T22:38:34.158728Z","end":"2026-02-03T22:38:34.420337Z","steps":["trace[729571648] 'process raft request' (duration: 261.32655ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:38:34.420627Z","caller":"traceutil/trace.go:171","msg":"trace[1719583598] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"260.816356ms","start":"2026-02-03T22:38:34.159806Z","end":"2026-02-03T22:38:34.420623Z","steps":["trace[1719583598] 'process raft request' (duration: 260.337243ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:38:34.420736Z","caller":"traceutil/trace.go:171","msg":"trace[1125673977] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"259.577042ms","start":"2026-02-03T22:38:34.161155Z","end":"2026-02-03T22:38:34.420732Z","steps":["trace[1125673977] 'process raft request' (duration: 259.010676ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:38:34.420802Z","caller":"traceutil/trace.go:171","msg":"trace[747335912] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"259.584661ms","start":"2026-02-03T22:38:34.161213Z","end":"2026-02-03T22:38:34.420798Z","steps":["trace[747335912] 'process raft request' (duration: 259.319454ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:38:34.420864Z","caller":"traceutil/trace.go:171","msg":"trace[1724178904] linearizableReadLoop","detail":"{readStateIndex:899; appliedIndex:895; }","duration":"140.358494ms","start":"2026-02-03T22:38:34.280196Z","end":"2026-02-03T22:38:34.420554Z","steps":["trace[1724178904] 'read index received' (duration: 73.542316ms)","trace[1724178904] 'applied index is now lower than readState.Index' (duration: 66.815738ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:38:34.421067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.698574ms","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-03T22:38:34.421087Z","caller":"traceutil/trace.go:171","msg":"trace[1141692354] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:864; }","duration":"140.924759ms","start":"2026-02-03T22:38:34.280157Z","end":"2026-02-03T22:38:34.421082Z","steps":["trace[1141692354] 'agreement among raft nodes before linearized reading' (duration: 140.720324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:34.785477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.428846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T22:38:34.785522Z","caller":"traceutil/trace.go:171","msg":"trace[1942122152] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:864; }","duration":"117.502828ms","start":"2026-02-03T22:38:34.668008Z","end":"2026-02-03T22:38:34.785511Z","steps":["trace[1942122152] 'count revisions from in-memory index tree' (duration: 117.377635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:36.924983Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322100858263557246,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-03T22:38:37.245032Z","caller":"traceutil/trace.go:171","msg":"trace[1936487810] linearizableReadLoop","detail":"{readStateIndex:901; appliedIndex:900; }","duration":"820.748299ms","start":"2026-02-03T22:38:36.424259Z","end":"2026-02-03T22:38:37.245007Z","steps":["trace[1936487810] 'read index received' (duration: 820.624736ms)","trace[1936487810] 'applied index is now lower than readState.Index' (duration: 123.143µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:38:37.24525Z","caller":"traceutil/trace.go:171","msg":"trace[1613938430] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"887.272288ms","start":"2026-02-03T22:38:36.35797Z","end":"2026-02-03T22:38:37.245242Z","steps":["trace[1613938430] 'process raft request' (duration: 886.952549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:37.245321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:36.35796Z","time spent":"887.31414ms","remote":"127.0.0.1:52396","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-03T22:38:37.2455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.239943ms","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-03T22:38:37.245517Z","caller":"traceutil/trace.go:171","msg":"trace[1622849491] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:866; }","duration":"821.280765ms","start":"2026-02-03T22:38:36.424231Z","end":"2026-02-03T22:38:37.245511Z","steps":["trace[1622849491] 'agreement among raft nodes before linearized reading' (duration: 821.208573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:37.245531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:36.424216Z","time spent":"821.311665ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-03T22:38:37.245633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.341566ms","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-03T22:38:37.24565Z","caller":"traceutil/trace.go:171","msg":"trace[1209521764] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:866; }","duration":"821.365987ms","start":"2026-02-03T22:38:36.42428Z","end":"2026-02-03T22:38:37.245646Z","steps":["trace[1209521764] 'agreement among raft nodes before linearized reading' (duration: 821.325146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:37.245662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:36.424278Z","time spent":"821.381558ms","remote":"127.0.0.1:52396","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-03T22:38:37.245775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.385469ms","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-03T22:38:37.24579Z","caller":"traceutil/trace.go:171","msg":"trace[599764918] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:866; }","duration":"405.42716ms","start":"2026-02-03T22:38:36.840357Z","end":"2026-02-03T22:38:37.245784Z","steps":["trace[599764918] 'agreement among raft nodes before linearized reading' (duration: 405.40297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:37.245802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:36.840342Z","time spent":"405.456331ms","remote":"127.0.0.1:52536","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-03T22:38:37.245884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.710452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:38:37.245899Z","caller":"traceutil/trace.go:171","msg":"trace[1994922725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:866; }","duration":"562.757203ms","start":"2026-02-03T22:38:36.683139Z","end":"2026-02-03T22:38:37.245896Z","steps":["trace[1994922725] 'agreement among raft nodes before linearized reading' (duration: 562.734733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:37.24591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:36.683121Z","time spent":"562.787024ms","remote":"127.0.0.1:52152","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-03T22:38:37.245989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.709993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T22:38:37.246006Z","caller":"traceutil/trace.go:171","msg":"trace[1334525990] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:866; }","duration":"658.816656ms","start":"2026-02-03T22:38:36.587186Z","end":"2026-02-03T22:38:37.246002Z","steps":["trace[1334525990] 'agreement among raft nodes before linearized reading' (duration: 658.791126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:37.246018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:36.587169Z","time spent":"658.845337ms","remote":"127.0.0.1:52524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":30,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-02-03T22:38:37.246109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.030013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-03T22:38:37.246136Z","caller":"traceutil/trace.go:171","msg":"trace[1112366806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:866; }","duration":"715.093045ms","start":"2026-02-03T22:38:36.531039Z","end":"2026-02-03T22:38:37.246132Z","steps":["trace[1112366806] 'agreement among raft nodes before linearized reading' (duration: 715.039584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:37.246149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:36.531023Z","time spent":"715.121756ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T22:38:37.246224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.228283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-03T22:38:37.24625Z","caller":"traceutil/trace.go:171","msg":"trace[2128530340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:866; }","duration":"821.262374ms","start":"2026-02-03T22:38:36.424984Z","end":"2026-02-03T22:38:37.246246Z","steps":["trace[2128530340] 'agreement among raft nodes before linearized reading' (duration: 821.215593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:37.246262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:36.424979Z","time spent":"821.280125ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-03T22:38:41.466486Z","caller":"traceutil/trace.go:171","msg":"trace[1136359556] linearizableReadLoop","detail":"{readStateIndex:913; appliedIndex:912; }","duration":"170.32774ms","start":"2026-02-03T22:38:41.29614Z","end":"2026-02-03T22:38:41.466468Z","steps":["trace[1136359556] 'read index received' (duration: 170.208597ms)","trace[1136359556] 'applied index is now lower than readState.Index' (duration: 118.673µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:38:41.466622Z","caller":"traceutil/trace.go:171","msg":"trace[671377192] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"209.64347ms","start":"2026-02-03T22:38:41.256972Z","end":"2026-02-03T22:38:41.466616Z","steps":["trace[671377192] 'process raft request' (duration: 209.416264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:41.466762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.609858ms","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-03T22:38:41.466795Z","caller":"traceutil/trace.go:171","msg":"trace[1760101520] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:877; }","duration":"170.67639ms","start":"2026-02-03T22:38:41.296114Z","end":"2026-02-03T22:38:41.46679Z","steps":["trace[1760101520] 'agreement among raft nodes before linearized reading' (duration: 170.588547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:41.466879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.848589ms","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-03T22:38:41.466891Z","caller":"traceutil/trace.go:171","msg":"trace[1336939027] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:877; }","duration":"145.88505ms","start":"2026-02-03T22:38:41.321002Z","end":"2026-02-03T22:38:41.466887Z","steps":["trace[1336939027] 'agreement among raft nodes before linearized reading' (duration: 145.848259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:41.467185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.577393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-03T22:38:41.467199Z","caller":"traceutil/trace.go:171","msg":"trace[454708340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:877; }","duration":"144.600803ms","start":"2026-02-03T22:38:41.322594Z","end":"2026-02-03T22:38:41.467195Z","steps":["trace[454708340] 'agreement among raft nodes before linearized reading' (duration: 144.366387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:41.467315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.266311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-03T22:38:41.467327Z","caller":"traceutil/trace.go:171","msg":"trace[131816233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:877; }","duration":"146.285051ms","start":"2026-02-03T22:38:41.321039Z","end":"2026-02-03T22:38:41.467324Z","steps":["trace[131816233] 'agreement among raft nodes before linearized reading' (duration: 146.24872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:42.029726Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322100858263557278,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-03T22:38:42.178199Z","caller":"traceutil/trace.go:171","msg":"trace[1971918774] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"706.074521ms","start":"2026-02-03T22:38:41.47211Z","end":"2026-02-03T22:38:42.178184Z","steps":["trace[1971918774] 'process raft request' (duration: 705.997649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:42.178288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:41.472096Z","time spent":"706.144543ms","remote":"127.0.0.1:52396","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-03T22:38:42.220427Z","caller":"traceutil/trace.go:171","msg":"trace[1187066616] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:913; }","duration":"691.657614ms","start":"2026-02-03T22:38:41.528751Z","end":"2026-02-03T22:38:42.220408Z","steps":["trace[1187066616] 'read index received' (duration: 649.323318ms)","trace[1187066616] 'applied index is now lower than readState.Index' (duration: 42.333406ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:38:42.220782Z","caller":"traceutil/trace.go:171","msg":"trace[1937937261] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"748.585572ms","start":"2026-02-03T22:38:41.472181Z","end":"2026-02-03T22:38:42.220767Z","steps":["trace[1937937261] 'process raft request' (duration: 748.011326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:42.220874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:41.472177Z","time spent":"748.645784ms","remote":"127.0.0.1:52396","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-03T22:38:42.221084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.312032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:38:42.221144Z","caller":"traceutil/trace.go:171","msg":"trace[469021896] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:881; }","duration":"692.411995ms","start":"2026-02-03T22:38:41.52872Z","end":"2026-02-03T22:38:42.221132Z","steps":["trace[469021896] 'agreement among raft nodes before linearized reading' (duration: 692.301662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:42.221168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:41.528705Z","time spent":"692.457556ms","remote":"127.0.0.1:52272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-02-03T22:38:42.221298Z","caller":"traceutil/trace.go:171","msg":"trace[908383811] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"749.083236ms","start":"2026-02-03T22:38:41.472207Z","end":"2026-02-03T22:38:42.221291Z","steps":["trace[908383811] 'process raft request' (duration: 748.075357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:42.221255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.423594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-03T22:38:42.221353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:41.472204Z","time spent":"749.106346ms","remote":"127.0.0.1:52396","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-03T22:38:42.221363Z","caller":"traceutil/trace.go:171","msg":"trace[414438678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:881; }","duration":"539.559479ms","start":"2026-02-03T22:38:41.681791Z","end":"2026-02-03T22:38:42.22135Z","steps":["trace[414438678] 'agreement among raft nodes before linearized reading' (duration: 539.425185ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:38:42.221429Z","caller":"traceutil/trace.go:171","msg":"trace[344060686] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"747.683707ms","start":"2026-02-03T22:38:41.473739Z","end":"2026-02-03T22:38:42.221423Z","steps":["trace[344060686] 'process raft request' (duration: 746.577825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:42.221469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:41.473723Z","time spent":"747.727477ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T22:38:42.221401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:41.681777Z","time spent":"539.61435ms","remote":"127.0.0.1:52152","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-03T22:38:48.701081Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322100858263557338,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T22:38:48.999224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.98581ms","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-03T22:38:49.000038Z","caller":"traceutil/trace.go:171","msg":"trace[10024204] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:902; }","duration":"893.716031ms","start":"2026-02-03T22:38:48.105794Z","end":"2026-02-03T22:38:48.99951Z","steps":["trace[10024204] 'count revisions from in-memory index tree' (duration: 892.909248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:49.001011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:48.105773Z","time spent":"894.315597ms","remote":"127.0.0.1:52296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-02-03T22:38:49.001572Z","caller":"traceutil/trace.go:171","msg":"trace[563348452] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"801.295301ms","start":"2026-02-03T22:38:48.200266Z","end":"2026-02-03T22:38:49.001561Z","steps":["trace[563348452] 'read index received' (duration: 800.140328ms)","trace[563348452] 'applied index is now lower than readState.Index' (duration: 1.154543ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:38:49.003049Z","caller":"traceutil/trace.go:171","msg":"trace[119410025] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"835.861476ms","start":"2026-02-03T22:38:48.167179Z","end":"2026-02-03T22:38:49.003041Z","steps":["trace[119410025] 'process raft request' (duration: 832.702217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:49.003145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:48.167131Z","time spent":"835.939498ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T22:38:49.003433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.164973ms","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-03T22:38:49.003453Z","caller":"traceutil/trace.go:171","msg":"trace[482910466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:903; }","duration":"803.212365ms","start":"2026-02-03T22:38:48.200236Z","end":"2026-02-03T22:38:49.003448Z","steps":["trace[482910466] 'agreement among raft nodes before linearized reading' (duration: 803.133723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:49.00403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:48.200221Z","time spent":"803.76726ms","remote":"127.0.0.1:52396","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-03T22:38:49.72609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.107433ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-03T22:38:49.726225Z","caller":"traceutil/trace.go:171","msg":"trace[1525944810] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:940; }","duration":"724.569412ms","start":"2026-02-03T22:38:49.001598Z","end":"2026-02-03T22:38:49.726167Z","steps":["trace[1525944810] 'read index received' (duration: 31.418597ms)","trace[1525944810] 'applied index is now lower than readState.Index' (duration: 693.149625ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:38:49.727083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043451929s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:38:49.727135Z","caller":"traceutil/trace.go:171","msg":"trace[2052108998] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:903; }","duration":"1.043532461s","start":"2026-02-03T22:38:48.683589Z","end":"2026-02-03T22:38:49.727121Z","steps":["trace[2052108998] 'agreement among raft nodes before linearized reading' (duration: 1.043447719s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:49.727173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:48.683574Z","time spent":"1.043591923s","remote":"127.0.0.1:52152","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-03T22:38:49.727466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.482434847s","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-03T22:38:49.727503Z","caller":"traceutil/trace.go:171","msg":"trace[1637307566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:903; }","duration":"1.482482248s","start":"2026-02-03T22:38:48.245011Z","end":"2026-02-03T22:38:49.727494Z","steps":["trace[1637307566] 'agreement among raft nodes before linearized reading' (duration: 1.482359244s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:49.727536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:48.245007Z","time spent":"1.482522459s","remote":"127.0.0.1:52396","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-03T22:38:49.727708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.482715445s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-03T22:38:49.727741Z","caller":"traceutil/trace.go:171","msg":"trace[1818991714] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:903; }","duration":"1.482762696s","start":"2026-02-03T22:38:48.24497Z","end":"2026-02-03T22:38:49.727733Z","steps":["trace[1818991714] 'agreement among raft nodes before linearized reading' (duration: 1.482680753s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:49.727773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:48.244962Z","time spent":"1.482805617s","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-03T22:38:49.72793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.489268219s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-03T22:38:49.727964Z","caller":"traceutil/trace.go:171","msg":"trace[705748011] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:903; }","duration":"1.489340191s","start":"2026-02-03T22:38:48.238615Z","end":"2026-02-03T22:38:49.727955Z","steps":["trace[705748011] 'agreement among raft nodes before linearized reading' (duration: 1.489258989s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:49.727996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:48.238596Z","time spent":"1.489394693s","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-03T22:38:49.729509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.613928ms","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-03T22:38:49.729557Z","caller":"traceutil/trace.go:171","msg":"trace[688431356] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:904; }","duration":"204.706771ms","start":"2026-02-03T22:38:49.524838Z","end":"2026-02-03T22:38:49.729545Z","steps":["trace[688431356] 'agreement among raft nodes before linearized reading' (duration: 204.563157ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:38:49.729787Z","caller":"traceutil/trace.go:171","msg":"trace[1594802819] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"719.234602ms","start":"2026-02-03T22:38:49.010541Z","end":"2026-02-03T22:38:49.729776Z","steps":["trace[1594802819] 'process raft request' (duration: 718.713468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:49.729834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:49.010526Z","time spent":"719.273454ms","remote":"127.0.0.1:52396","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-03T22:38:50.406076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.851256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T22:38:50.406306Z","caller":"traceutil/trace.go:171","msg":"trace[905372448] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:943; }","duration":"587.710137ms","start":"2026-02-03T22:38:49.81858Z","end":"2026-02-03T22:38:50.406291Z","steps":["trace[905372448] 'read index received' (duration: 116.502041ms)","trace[905372448] 'applied index is now lower than readState.Index' (duration: 471.207226ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:38:50.406383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.7972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:38:50.406407Z","caller":"traceutil/trace.go:171","msg":"trace[366199763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:908; }","duration":"587.849321ms","start":"2026-02-03T22:38:49.818549Z","end":"2026-02-03T22:38:50.406399Z","steps":["trace[366199763] 'agreement among raft nodes before linearized reading' (duration: 587.78511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:50.406436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:49.818522Z","time spent":"587.907302ms","remote":"127.0.0.1:52152","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-03T22:38:50.406702Z","caller":"traceutil/trace.go:171","msg":"trace[941361162] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"668.861759ms","start":"2026-02-03T22:38:49.737829Z","end":"2026-02-03T22:38:50.406691Z","steps":["trace[941361162] 'process raft request' (duration: 197.294562ms)","trace[941361162] 'compare' (duration: 469.302873ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:38:50.40676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:49.737819Z","time spent":"668.909ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T22:38:50.406886Z","caller":"traceutil/trace.go:171","msg":"trace[1491693123] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"668.858089ms","start":"2026-02-03T22:38:49.738019Z","end":"2026-02-03T22:38:50.406877Z","steps":["trace[1491693123] 'process raft request' (duration: 668.159469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:50.406933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:49.738012Z","time spent":"668.89469ms","remote":"127.0.0.1:52396","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-03T22:38:50.407055Z","caller":"traceutil/trace.go:171","msg":"trace[1874291819] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"667.46507ms","start":"2026-02-03T22:38:49.739579Z","end":"2026-02-03T22:38:50.407044Z","steps":["trace[1874291819] 'process raft request' (duration: 666.676367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:38:50.4071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:38:49.73957Z","time spent":"667.50505ms","remote":"127.0.0.1:52396","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-03T22:39:04.192183Z","caller":"traceutil/trace.go:171","msg":"trace[909885515] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"236.722752ms","start":"2026-02-03T22:39:03.955434Z","end":"2026-02-03T22:39:04.192157Z","steps":["trace[909885515] 'process raft request' (duration: 198.18412ms)","trace[909885515] 'compare' (duration: 38.256674ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:39:04.882959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.801896ms","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-03T22:39:04.883031Z","caller":"traceutil/trace.go:171","msg":"trace[577042754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:971; }","duration":"225.913789ms","start":"2026-02-03T22:39:04.6571Z","end":"2026-02-03T22:39:04.883014Z","steps":["trace[577042754] 'range keys from in-memory index tree' (duration: 225.593509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:39:04.883171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.614948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-03T22:39:04.883219Z","caller":"traceutil/trace.go:171","msg":"trace[168103741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:971; }","duration":"216.68367ms","start":"2026-02-03T22:39:04.666516Z","end":"2026-02-03T22:39:04.883199Z","steps":["trace[168103741] 'range keys from in-memory index tree' (duration: 216.485394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:39:04.883709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.534217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:39:04.883752Z","caller":"traceutil/trace.go:171","msg":"trace[1967653925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:971; }","duration":"200.599008ms","start":"2026-02-03T22:39:04.683142Z","end":"2026-02-03T22:39:04.883741Z","steps":["trace[1967653925] 'range keys from in-memory index tree' (duration: 200.493916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:39:04.88454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.497634ms","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-03T22:39:04.884649Z","caller":"traceutil/trace.go:171","msg":"trace[944187808] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:971; }","duration":"216.573787ms","start":"2026-02-03T22:39:04.668004Z","end":"2026-02-03T22:39:04.884578Z","steps":["trace[944187808] 'range keys from in-memory index tree' (duration: 216.364531ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:39:10.211994Z","caller":"traceutil/trace.go:171","msg":"trace[399895182] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"169.803134ms","start":"2026-02-03T22:39:10.037587Z","end":"2026-02-03T22:39:10.20739Z","steps":["trace[399895182] 'process raft request' (duration: 166.187073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:40:02.830526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.851017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-03T22:40:02.830621Z","caller":"traceutil/trace.go:171","msg":"trace[1430615221] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1227; }","duration":"132.996592ms","start":"2026-02-03T22:40:02.697604Z","end":"2026-02-03T22:40:02.830601Z","steps":["trace[1430615221] 'range keys from in-memory index tree' (duration: 132.682152ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:41:17.136241Z","caller":"traceutil/trace.go:171","msg":"trace[1521788569] transaction","detail":"{read_only:false; response_revision:1531; number_of_response:1; }","duration":"116.672181ms","start":"2026-02-03T22:41:17.01955Z","end":"2026-02-03T22:41:17.136222Z","steps":["trace[1521788569] 'process raft request' (duration: 95.977982ms)","trace[1521788569] 'compare' (duration: 20.619107ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:41:27.931701Z","caller":"traceutil/trace.go:171","msg":"trace[1191880706] transaction","detail":"{read_only:false; response_revision:1574; number_of_response:1; }","duration":"170.897717ms","start":"2026-02-03T22:41:27.760768Z","end":"2026-02-03T22:41:27.931666Z","steps":["trace[1191880706] 'process raft request' (duration: 170.676431ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:42:10.360976Z","caller":"traceutil/trace.go:171","msg":"trace[906920741] transaction","detail":"{read_only:false; response_revision:1767; number_of_response:1; }","duration":"123.544315ms","start":"2026-02-03T22:42:10.237399Z","end":"2026-02-03T22:42:10.360943Z","steps":["trace[906920741] 'process raft request' (duration: 39.859025ms)","trace[906920741] 'compare' (duration: 83.504694ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:42:16.624445Z","caller":"traceutil/trace.go:171","msg":"trace[1082690685] linearizableReadLoop","detail":"{readStateIndex:1876; appliedIndex:1875; }","duration":"120.651706ms","start":"2026-02-03T22:42:16.503776Z","end":"2026-02-03T22:42:16.624428Z","steps":["trace[1082690685] 'read index received' (duration: 120.489632ms)","trace[1082690685] 'applied index is now lower than readState.Index' (duration: 161.454µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:42:16.624678Z","caller":"traceutil/trace.go:171","msg":"trace[2034088811] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"219.383445ms","start":"2026-02-03T22:42:16.405286Z","end":"2026-02-03T22:42:16.62467Z","steps":["trace[2034088811] 'process raft request' (duration: 219.042776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:42:16.624882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.095547ms","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-03T22:42:16.624905Z","caller":"traceutil/trace.go:171","msg":"trace[245897752] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1796; }","duration":"121.198759ms","start":"2026-02-03T22:42:16.503698Z","end":"2026-02-03T22:42:16.624897Z","steps":["trace[245897752] 'agreement among raft nodes before linearized reading' (duration: 121.107917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:42:16.625039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.353784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-03T22:42:16.625058Z","caller":"traceutil/trace.go:171","msg":"trace[2139777501] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1796; }","duration":"103.399995ms","start":"2026-02-03T22:42:16.521652Z","end":"2026-02-03T22:42:16.625052Z","steps":["trace[2139777501] 'agreement among raft nodes before linearized reading' (duration: 103.329843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:42:16.982957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.898331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T22:42:16.985112Z","caller":"traceutil/trace.go:171","msg":"trace[1914149896] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"354.748587ms","start":"2026-02-03T22:42:16.630258Z","end":"2026-02-03T22:42:16.985006Z","steps":["trace[1914149896] 'process raft request' (duration: 37.635331ms)","trace[1914149896] 'compare' (duration: 314.498361ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:42:16.985246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:42:16.630239Z","time spent":"354.947192ms","remote":"127.0.0.1:52396","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-03T22:42:16.986885Z","caller":"traceutil/trace.go:171","msg":"trace[1710361519] linearizableReadLoop","detail":"{readStateIndex:1879; appliedIndex:1877; }","duration":"143.59588ms","start":"2026-02-03T22:42:16.843273Z","end":"2026-02-03T22:42:16.986869Z","steps":["trace[1710361519] 'read index received' (duration: 143.437286ms)","trace[1710361519] 'applied index is now lower than readState.Index' (duration: 157.864µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:42:16.987008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.752003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:42:16.987045Z","caller":"traceutil/trace.go:171","msg":"trace[493693361] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1799; }","duration":"143.798424ms","start":"2026-02-03T22:42:16.843238Z","end":"2026-02-03T22:42:16.987037Z","steps":["trace[493693361] 'agreement among raft nodes before linearized reading' (duration: 143.698322ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:42:16.98871Z","caller":"traceutil/trace.go:171","msg":"trace[1109631081] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"356.338567ms","start":"2026-02-03T22:42:16.63235Z","end":"2026-02-03T22:42:16.988688Z","steps":["trace[1109631081] 'process raft request' (duration: 354.484701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:42:16.989078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:42:16.632341Z","time spent":"356.667855ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T22:42:16.9895Z","caller":"traceutil/trace.go:171","msg":"trace[2052243987] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"359.094856ms","start":"2026-02-03T22:42:16.630392Z","end":"2026-02-03T22:42:16.989486Z","steps":["trace[2052243987] 'process raft request' (duration: 356.361928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:42:16.989699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:42:16.630386Z","time spent":"359.270821ms","remote":"127.0.0.1:52396","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-03T22:42:23.503517Z","caller":"traceutil/trace.go:171","msg":"trace[2005517567] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"184.01708ms","start":"2026-02-03T22:42:23.319454Z","end":"2026-02-03T22:42:23.503471Z","steps":["trace[2005517567] 'process raft request' (duration: 183.904288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:42:24.060719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.438121ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T22:42:24.06089Z","caller":"traceutil/trace.go:171","msg":"trace[617473938] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"468.88938ms","start":"2026-02-03T22:42:23.591978Z","end":"2026-02-03T22:42:24.060868Z","steps":["trace[617473938] 'process raft request' (duration: 208.191294ms)","trace[617473938] 'compare' (duration: 259.976148ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:42:24.060981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:42:23.591963Z","time spent":"468.984513ms","remote":"127.0.0.1:52162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T22:42:24.061479Z","caller":"traceutil/trace.go:171","msg":"trace[776352172] linearizableReadLoop","detail":"{readStateIndex:1912; appliedIndex:1910; }","duration":"457.428004ms","start":"2026-02-03T22:42:23.60404Z","end":"2026-02-03T22:42:24.061468Z","steps":["trace[776352172] 'read index received' (duration: 196.138253ms)","trace[776352172] 'applied index is now lower than readState.Index' (duration: 261.288901ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:42:24.061716Z","caller":"traceutil/trace.go:171","msg":"trace[1884472088] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"467.260449ms","start":"2026-02-03T22:42:23.594445Z","end":"2026-02-03T22:42:24.061705Z","steps":["trace[1884472088] 'process raft request' (duration: 466.954752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:42:24.061958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:42:23.594431Z","time spent":"467.351652ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T22:42:24.062127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.083121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-03T22:42:24.062166Z","caller":"traceutil/trace.go:171","msg":"trace[1706809160] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1830; }","duration":"458.136492ms","start":"2026-02-03T22:42:23.604022Z","end":"2026-02-03T22:42:24.062159Z","steps":["trace[1706809160] 'agreement among raft nodes before linearized reading' (duration: 458.041489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:42:24.062201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:42:23.604014Z","time spent":"458.180703ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-03T22:42:24.062393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.127407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-03T22:42:24.062501Z","caller":"traceutil/trace.go:171","msg":"trace[231352234] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1830; }","duration":"265.267241ms","start":"2026-02-03T22:42:23.797226Z","end":"2026-02-03T22:42:24.062493Z","steps":["trace[231352234] 'agreement among raft nodes before linearized reading' (duration: 265.124267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:42:24.062736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.365798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-03T22:42:24.062819Z","caller":"traceutil/trace.go:171","msg":"trace[1709460645] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1830; }","duration":"162.44831ms","start":"2026-02-03T22:42:23.900363Z","end":"2026-02-03T22:42:24.062811Z","steps":["trace[1709460645] 'agreement among raft nodes before linearized reading' (duration: 162.327807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:42:26.469965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.297196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:42:26.470051Z","caller":"traceutil/trace.go:171","msg":"trace[1483140897] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1839; }","duration":"272.411919ms","start":"2026-02-03T22:42:26.197621Z","end":"2026-02-03T22:42:26.470033Z","steps":["trace[1483140897] 'range keys from in-memory index tree' (duration: 272.203344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:42:26.470093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.25795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-03T22:42:26.47017Z","caller":"traceutil/trace.go:171","msg":"trace[771317908] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1839; }","duration":"393.374893ms","start":"2026-02-03T22:42:26.076776Z","end":"2026-02-03T22:42:26.470151Z","steps":["trace[771317908] 'range keys from in-memory index tree' (duration: 393.003683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:42:26.470218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:42:26.076765Z","time spent":"393.440394ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-03T22:42:26.470603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.135702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-03T22:42:26.470642Z","caller":"traceutil/trace.go:171","msg":"trace[207623423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1839; }","duration":"402.212694ms","start":"2026-02-03T22:42:26.068418Z","end":"2026-02-03T22:42:26.470631Z","steps":["trace[207623423] 'range keys from in-memory index tree' (duration: 402.020839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:42:26.470677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:42:26.068399Z","time spent":"402.270035ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T22:42:26.470697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.802964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-03T22:42:26.473162Z","caller":"traceutil/trace.go:171","msg":"trace[1255413259] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1839; }","duration":"395.845844ms","start":"2026-02-03T22:42:26.074878Z","end":"2026-02-03T22:42:26.470724Z","steps":["trace[1255413259] 'range keys from in-memory index tree' (duration: 395.675661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:42:26.473224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:42:26.074834Z","time spent":"398.374827ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-03T22:42:26.769746Z","caller":"traceutil/trace.go:171","msg":"trace[807494222] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"289.65817ms","start":"2026-02-03T22:42:26.480066Z","end":"2026-02-03T22:42:26.769724Z","steps":["trace[807494222] 'process raft request' (duration: 289.528437ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:42:26.936236Z","caller":"traceutil/trace.go:171","msg":"trace[1581125144] linearizableReadLoop","detail":"{readStateIndex:1924; appliedIndex:1922; }","duration":"257.185858ms","start":"2026-02-03T22:42:26.679033Z","end":"2026-02-03T22:42:26.936219Z","steps":["trace[1581125144] 'read index received' (duration: 91.337063ms)","trace[1581125144] 'applied index is now lower than readState.Index' (duration: 165.847895ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:42:26.936338Z","caller":"traceutil/trace.go:171","msg":"trace[732707905] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"454.552521ms","start":"2026-02-03T22:42:26.481777Z","end":"2026-02-03T22:42:26.936329Z","steps":["trace[732707905] 'process raft request' (duration: 356.955202ms)","trace[732707905] 'compare' (duration: 97.330122ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:42:26.936421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:42:26.48177Z","time spent":"454.589023ms","remote":"127.0.0.1:52396","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-03T22:42:26.936514Z","caller":"traceutil/trace.go:171","msg":"trace[2037703905] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"453.272379ms","start":"2026-02-03T22:42:26.483236Z","end":"2026-02-03T22:42:26.936508Z","steps":["trace[2037703905] 'process raft request' (duration: 452.936751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:42:26.936548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T22:42:26.48323Z","time spent":"453.29838ms","remote":"127.0.0.1:52396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T22:42:26.936743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.756317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-03T22:42:26.936808Z","caller":"traceutil/trace.go:171","msg":"trace[1678004776] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1842; }","duration":"228.858079ms","start":"2026-02-03T22:42:26.707933Z","end":"2026-02-03T22:42:26.936791Z","steps":["trace[1678004776] 'agreement among raft nodes before linearized reading' (duration: 228.691785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:42:26.936897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.866475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:42:26.936924Z","caller":"traceutil/trace.go:171","msg":"trace[1488612338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1842; }","duration":"257.916316ms","start":"2026-02-03T22:42:26.679Z","end":"2026-02-03T22:42:26.936916Z","steps":["trace[1488612338] 'agreement among raft nodes before linearized reading' (duration: 257.872105ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:42:29.15747Z","caller":"traceutil/trace.go:171","msg":"trace[1809557872] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"207.751503ms","start":"2026-02-03T22:42:28.949673Z","end":"2026-02-03T22:42:29.157425Z","steps":["trace[1809557872] 'process raft request' (duration: 170.383759ms)","trace[1809557872] 'compare' (duration: 36.736878ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:42:29.157867Z","caller":"traceutil/trace.go:171","msg":"trace[45244880] linearizableReadLoop","detail":"{readStateIndex:1936; appliedIndex:1935; }","duration":"197.768994ms","start":"2026-02-03T22:42:28.960087Z","end":"2026-02-03T22:42:29.157856Z","steps":["trace[45244880] 'read index received' (duration: 2.959644ms)","trace[45244880] 'applied index is now lower than readState.Index' (duration: 194.80764ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:42:29.227742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.661051ms","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-03T22:42:29.227802Z","caller":"traceutil/trace.go:171","msg":"trace[1388859235] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1854; }","duration":"163.778894ms","start":"2026-02-03T22:42:29.06401Z","end":"2026-02-03T22:42:29.227788Z","steps":["trace[1388859235] 'agreement among raft nodes before linearized reading' (duration: 163.582559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:42:29.227828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.730903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-03T22:42:29.227876Z","caller":"traceutil/trace.go:171","msg":"trace[1240261704] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1854; }","duration":"267.809695ms","start":"2026-02-03T22:42:28.960054Z","end":"2026-02-03T22:42:29.227863Z","steps":["trace[1240261704] 'agreement among raft nodes before linearized reading' (duration: 197.875407ms)","trace[1240261704] 'range keys from in-memory index tree' (duration: 69.807725ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T22:42:29.22805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.523557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-03T22:42:29.228075Z","caller":"traceutil/trace.go:171","msg":"trace[1606537699] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1854; }","duration":"147.570459ms","start":"2026-02-03T22:42:29.080497Z","end":"2026-02-03T22:42:29.228067Z","steps":["trace[1606537699] 'agreement among raft nodes before linearized reading' (duration: 147.484727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:42:29.228078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.386109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-03T22:42:29.228096Z","caller":"traceutil/trace.go:171","msg":"trace[833978774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1854; }","duration":"160.41166ms","start":"2026-02-03T22:42:29.067678Z","end":"2026-02-03T22:42:29.228089Z","steps":["trace[833978774] 'agreement among raft nodes before linearized reading' (duration: 160.362298ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T22:42:49.575282Z","caller":"traceutil/trace.go:171","msg":"trace[1560652870] linearizableReadLoop","detail":"{readStateIndex:2029; appliedIndex:2028; }","duration":"101.568609ms","start":"2026-02-03T22:42:49.473676Z","end":"2026-02-03T22:42:49.575245Z","steps":["trace[1560652870] 'read index received' (duration: 101.438856ms)","trace[1560652870] 'applied index is now lower than readState.Index' (duration: 128.953µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T22:42:49.575395Z","caller":"traceutil/trace.go:171","msg":"trace[527005295] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"113.828626ms","start":"2026-02-03T22:42:49.461554Z","end":"2026-02-03T22:42:49.575383Z","steps":["trace[527005295] 'process raft request' (duration: 113.570399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T22:42:49.575533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.838496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-system/\" range_end:\"/registry/persistentvolumeclaims/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T22:42:49.575574Z","caller":"traceutil/trace.go:171","msg":"trace[1395928102] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-system/; range_end:/registry/persistentvolumeclaims/kube-system0; response_count:0; response_revision:1942; }","duration":"101.912267ms","start":"2026-02-03T22:42:49.473651Z","end":"2026-02-03T22:42:49.575564Z","steps":["trace[1395928102] 'agreement among raft nodes before linearized reading' (duration: 101.830955ms)"],"step_count":1}