{"level":"warn","ts":"2026-05-09T01:36:59.024453Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T01:36:59.024574Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53: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.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-05-09T01:36:59.024808Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T01:36:59.024831Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-05-09T01:36:59.024857Z","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-05-09T01:36:59.025831Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-05-09T01:36:59.026012Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-05-09T01:36:59.030797Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.384694ms"} {"level":"info","ts":"2026-05-09T01:36:59.038593Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-05-09T01:36:59.038658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T01:36:59.03879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-05-09T01:36:59.038859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T01:36:59.03893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-05-09T01:36:59.03902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-05-09T01:36:59.045318Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T01:36:59.046719Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T01:36:59.04774Z","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-05-09T01:36:59.049973Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T01:36:59.050198Z","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-05-09T01:36:59.050284Z","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-05-09T01:36:59.050294Z","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-05-09T01:36:59.050448Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T01:36:59.051367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-05-09T01:36:59.054378Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-05-09T01:36:59.059988Z","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-05-09T01:36:59.060097Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-05-09T01:36:59.06015Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-05-09T01:36:59.0604Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T01:36:59.060462Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T01:36:59.440163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T01:36:59.44022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T01:36:59.440258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-05-09T01:36:59.440277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-05-09T01:36:59.440294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-05-09T01:36:59.440309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-05-09T01:36:59.440327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-05-09T01:36:59.442022Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T01:36:59.442091Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T01:36:59.442172Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T01:36:59.442288Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T01:36:59.442669Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T01:36:59.442695Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T01:36:59.443173Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T01:36:59.443286Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T01:36:59.443316Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T01:36:59.445504Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-05-09T01:36:59.445853Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T01:37:04.034514Z","caller":"traceutil/trace.go:171","msg":"trace[822514892] linearizableReadLoop","detail":"{readStateIndex:280; appliedIndex:279; }","duration":"168.641849ms","start":"2026-05-09T01:37:03.865845Z","end":"2026-05-09T01:37:04.034487Z","steps":["trace[822514892] 'read index received' (duration: 168.624469ms)","trace[822514892] 'applied index is now lower than readState.Index' (duration: 15.26µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:37:04.03451Z","caller":"traceutil/trace.go:171","msg":"trace[445587607] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"215.158126ms","start":"2026-05-09T01:37:03.819331Z","end":"2026-05-09T01:37:04.034489Z","steps":["trace[445587607] 'process raft request' (duration: 215.052864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:04.034718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.859254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:37:04.034769Z","caller":"traceutil/trace.go:171","msg":"trace[1465508831] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:0; response_revision:274; }","duration":"168.971968ms","start":"2026-05-09T01:37:03.865787Z","end":"2026-05-09T01:37:04.034759Z","steps":["trace[1465508831] 'agreement among raft nodes before linearized reading' (duration: 168.851794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:04.3824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.43766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T01:37:04.382514Z","caller":"traceutil/trace.go:171","msg":"trace[847647492] linearizableReadLoop","detail":"{readStateIndex:281; appliedIndex:280; }","duration":"347.893763ms","start":"2026-05-09T01:37:04.034604Z","end":"2026-05-09T01:37:04.382497Z","steps":["trace[847647492] 'read index received' (duration: 187.853288ms)","trace[847647492] 'applied index is now lower than readState.Index' (duration: 160.039385ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:37:04.382628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.18545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-09T01:37:04.382661Z","caller":"traceutil/trace.go:171","msg":"trace[1605131958] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:275; }","duration":"373.244912ms","start":"2026-05-09T01:37:04.009407Z","end":"2026-05-09T01:37:04.382652Z","steps":["trace[1605131958] 'agreement among raft nodes before linearized reading' (duration: 373.155729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:04.382709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.774339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T01:37:04.382726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:04.009389Z","time spent":"373.327714ms","remote":"127.0.0.1:60762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":688,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-09T01:37:04.382723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.33431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:37:04.382784Z","caller":"traceutil/trace.go:171","msg":"trace[1763556545] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"368.028596ms","start":"2026-05-09T01:37:04.014704Z","end":"2026-05-09T01:37:04.382732Z","steps":["trace[1763556545] 'process raft request' (duration: 207.803636ms)","trace[1763556545] 'compare' (duration: 159.310505ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:37:04.382835Z","caller":"traceutil/trace.go:171","msg":"trace[2081027946] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:275; }","duration":"372.947094ms","start":"2026-05-09T01:37:04.009861Z","end":"2026-05-09T01:37:04.382808Z","steps":["trace[2081027946] 'agreement among raft nodes before linearized reading' (duration: 372.676706ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:37:04.382862Z","caller":"traceutil/trace.go:171","msg":"trace[1399356921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:275; }","duration":"451.522164ms","start":"2026-05-09T01:37:03.931307Z","end":"2026-05-09T01:37:04.382829Z","steps":["trace[1399356921] 'agreement among raft nodes before linearized reading' (duration: 451.295668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:04.382885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:04.009844Z","time spent":"373.028115ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-05-09T01:37:04.382919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:03.931281Z","time spent":"451.626106ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T01:37:04.383116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:04.01469Z","time spent":"368.179659ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T01:37:04.428701Z","caller":"traceutil/trace.go:171","msg":"trace[1460947547] transaction","detail":"{read_only:false; number_of_response:0; response_revision:276; }","duration":"293.647684ms","start":"2026-05-09T01:37:04.135029Z","end":"2026-05-09T01:37:04.428677Z","steps":["trace[1460947547] 'process raft request' (duration: 293.524941ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:37:04.428712Z","caller":"traceutil/trace.go:171","msg":"trace[1549850757] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"391.058423ms","start":"2026-05-09T01:37:04.037588Z","end":"2026-05-09T01:37:04.428646Z","steps":["trace[1549850757] 'process raft request' (duration: 390.812417ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:37:04.428787Z","caller":"traceutil/trace.go:171","msg":"trace[751751226] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"288.632644ms","start":"2026-05-09T01:37:04.140133Z","end":"2026-05-09T01:37:04.428765Z","steps":["trace[751751226] 'process raft request' (duration: 288.549572ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:37:04.428794Z","caller":"traceutil/trace.go:171","msg":"trace[1409999849] transaction","detail":"{read_only:false; number_of_response:0; response_revision:276; }","duration":"326.394835ms","start":"2026-05-09T01:37:04.102351Z","end":"2026-05-09T01:37:04.428745Z","steps":["trace[1409999849] 'process raft request' (duration: 326.168389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:04.428883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.034662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T01:37:04.428883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:04.03757Z","time spent":"391.224887ms","remote":"127.0.0.1:60716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":373,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T01:37:04.428914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:04.102325Z","time spent":"326.533389ms","remote":"127.0.0.1:48678","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T01:37:04.428926Z","caller":"traceutil/trace.go:171","msg":"trace[1613874920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:280; }","duration":"295.094423ms","start":"2026-05-09T01:37:04.133821Z","end":"2026-05-09T01:37:04.428915Z","steps":["trace[1613874920] 'agreement among raft nodes before linearized reading' (duration: 294.99927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:04.428924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.236289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-09T01:37:04.428938Z","caller":"traceutil/trace.go:171","msg":"trace[784988254] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"291.760036ms","start":"2026-05-09T01:37:04.137164Z","end":"2026-05-09T01:37:04.428924Z","steps":["trace[784988254] 'process raft request' (duration: 291.469448ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:37:04.428972Z","caller":"traceutil/trace.go:171","msg":"trace[1217981442] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:280; }","duration":"391.30932ms","start":"2026-05-09T01:37:04.037652Z","end":"2026-05-09T01:37:04.428962Z","steps":["trace[1217981442] 'agreement among raft nodes before linearized reading' (duration: 391.209888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:04.429005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:04.037646Z","time spent":"391.350911ms","remote":"127.0.0.1:48616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-09T01:37:04.428774Z","caller":"traceutil/trace.go:171","msg":"trace[580789364] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"292.905825ms","start":"2026-05-09T01:37:04.13585Z","end":"2026-05-09T01:37:04.428756Z","steps":["trace[580789364] 'process raft request' (duration: 292.740351ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:37:04.429214Z","caller":"traceutil/trace.go:171","msg":"trace[292812178] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"291.758386ms","start":"2026-05-09T01:37:04.137445Z","end":"2026-05-09T01:37:04.429204Z","steps":["trace[292812178] 'process raft request' (duration: 291.214382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:05.16639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.021424ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T01:37:05.167013Z","caller":"traceutil/trace.go:171","msg":"trace[465517385] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"780.037643ms","start":"2026-05-09T01:37:04.386944Z","end":"2026-05-09T01:37:05.166982Z","steps":["trace[465517385] 'process raft request' (duration: 210.351811ms)","trace[465517385] 'compare' (duration: 568.692945ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:37:05.167082Z","caller":"traceutil/trace.go:171","msg":"trace[387863335] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"778.482822ms","start":"2026-05-09T01:37:04.388565Z","end":"2026-05-09T01:37:05.167048Z","steps":["trace[387863335] 'process raft request' (duration: 778.36761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:05.16716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:04.388555Z","time spent":"778.582335ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T01:37:05.167187Z","caller":"traceutil/trace.go:171","msg":"trace[1276506684] linearizableReadLoop","detail":"{readStateIndex:290; appliedIndex:288; }","duration":"738.445793ms","start":"2026-05-09T01:37:04.428714Z","end":"2026-05-09T01:37:05.16716Z","steps":["trace[1276506684] 'read index received' (duration: 168.68001ms)","trace[1276506684] 'applied index is now lower than readState.Index' (duration: 569.762973ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:37:05.167235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:04.386917Z","time spent":"780.203326ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T01:37:05.167425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.436958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-09T01:37:05.167483Z","caller":"traceutil/trace.go:171","msg":"trace[1428834435] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:283; }","duration":"781.536032ms","start":"2026-05-09T01:37:04.385937Z","end":"2026-05-09T01:37:05.167473Z","steps":["trace[1428834435] 'agreement among raft nodes before linearized reading' (duration: 781.419618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:05.167528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:04.38592Z","time spent":"781.600494ms","remote":"127.0.0.1:60762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":688,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-05-09T01:37:05.167582Z","caller":"traceutil/trace.go:171","msg":"trace[1086268254] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"734.765998ms","start":"2026-05-09T01:37:04.432806Z","end":"2026-05-09T01:37:05.167572Z","steps":["trace[1086268254] 'process raft request' (duration: 734.226984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:05.167541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.204407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"warn","ts":"2026-05-09T01:37:05.16765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:04.432786Z","time spent":"734.823229ms","remote":"127.0.0.1:60730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":400,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T01:37:05.167752Z","caller":"traceutil/trace.go:171","msg":"trace[330696087] transaction","detail":"{read_only:false; number_of_response:0; response_revision:285; }","duration":"109.78276ms","start":"2026-05-09T01:37:05.057956Z","end":"2026-05-09T01:37:05.167739Z","steps":["trace[330696087] 'process raft request' (duration: 109.739459ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:37:05.167831Z","caller":"traceutil/trace.go:171","msg":"trace[1655726262] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"734.792578ms","start":"2026-05-09T01:37:04.433027Z","end":"2026-05-09T01:37:05.16782Z","steps":["trace[1655726262] 'process raft request' (duration: 734.509881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:05.167887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:04.432989Z","time spent":"734.877311ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T01:37:05.167969Z","caller":"traceutil/trace.go:171","msg":"trace[851209959] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"734.595022ms","start":"2026-05-09T01:37:04.433349Z","end":"2026-05-09T01:37:05.167944Z","steps":["trace[851209959] 'process raft request' (duration: 734.293235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:05.168083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:04.433338Z","time spent":"734.693776ms","remote":"127.0.0.1:48706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T01:37:05.168713Z","caller":"traceutil/trace.go:171","msg":"trace[1108533499] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:283; }","duration":"780.418033ms","start":"2026-05-09T01:37:04.387197Z","end":"2026-05-09T01:37:05.167615Z","steps":["trace[1108533499] 'agreement among raft nodes before linearized reading' (duration: 780.075044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:05.168798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:04.387184Z","time spent":"781.598083ms","remote":"127.0.0.1:48678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4040,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-09T01:37:05.171618Z","caller":"traceutil/trace.go:171","msg":"trace[139511054] transaction","detail":"{read_only:false; number_of_response:0; response_revision:285; }","duration":"113.314761ms","start":"2026-05-09T01:37:05.058295Z","end":"2026-05-09T01:37:05.17161Z","steps":["trace[139511054] 'process raft request' (duration: 113.25125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:05.172058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.293887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-05-09T01:37:05.172114Z","caller":"traceutil/trace.go:171","msg":"trace[1570613643] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:285; }","duration":"741.385869ms","start":"2026-05-09T01:37:04.430714Z","end":"2026-05-09T01:37:05.1721Z","steps":["trace[1570613643] 'agreement among raft nodes before linearized reading' (duration: 741.246516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:05.172149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:04.430699Z","time spent":"741.44284ms","remote":"127.0.0.1:48678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4040,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-09T01:37:05.172295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.865294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:37:05.172375Z","caller":"traceutil/trace.go:171","msg":"trace[1269022700] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:285; }","duration":"317.953057ms","start":"2026-05-09T01:37:04.854404Z","end":"2026-05-09T01:37:05.172357Z","steps":["trace[1269022700] 'agreement among raft nodes before linearized reading' (duration: 317.809522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:05.172403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.319998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-05-09T01:37:05.172444Z","caller":"traceutil/trace.go:171","msg":"trace[1628383485] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:285; }","duration":"741.40766ms","start":"2026-05-09T01:37:04.431027Z","end":"2026-05-09T01:37:05.172434Z","steps":["trace[1628383485] 'agreement among raft nodes before linearized reading' (duration: 741.332558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:05.172465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:04.854354Z","time spent":"318.056389ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T01:37:05.172487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:04.431016Z","time spent":"741.463721ms","remote":"127.0.0.1:48686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3490,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-05-09T01:37:05.172485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.283834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-05-09T01:37:05.172621Z","caller":"traceutil/trace.go:171","msg":"trace[1421485776] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:285; }","duration":"115.394996ms","start":"2026-05-09T01:37:05.057143Z","end":"2026-05-09T01:37:05.172538Z","steps":["trace[1421485776] 'agreement among raft nodes before linearized reading' (duration: 115.19023ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:37:29.255929Z","caller":"traceutil/trace.go:171","msg":"trace[1083986899] transaction","detail":"{read_only:false; number_of_response:1; response_revision:513; }","duration":"319.900227ms","start":"2026-05-09T01:37:28.935989Z","end":"2026-05-09T01:37:29.255889Z","steps":["trace[1083986899] 'process raft request' (duration: 319.808905ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:37:29.255998Z","caller":"traceutil/trace.go:171","msg":"trace[1030632605] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"319.99776ms","start":"2026-05-09T01:37:28.935928Z","end":"2026-05-09T01:37:29.255925Z","steps":["trace[1030632605] 'process raft request' (duration: 275.403235ms)","trace[1030632605] 'compare' (duration: 44.313818ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:37:29.256283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:28.935906Z","time spent":"320.194604ms","remote":"127.0.0.1:48546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T01:37:29.256586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:28.935964Z","time spent":"320.056031ms","remote":"127.0.0.1:48546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7367,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:37:29.278224Z","caller":"traceutil/trace.go:171","msg":"trace[878669841] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"317.150415ms","start":"2026-05-09T01:37:28.96105Z","end":"2026-05-09T01:37:29.2782Z","steps":["trace[878669841] 'process raft request' (duration: 316.910448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:29.278367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:28.96103Z","time spent":"317.252058ms","remote":"127.0.0.1:48546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T01:37:29.278512Z","caller":"traceutil/trace.go:171","msg":"trace[248568139] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"317.209347ms","start":"2026-05-09T01:37:28.961278Z","end":"2026-05-09T01:37:29.278487Z","steps":["trace[248568139] 'process raft request' (duration: 316.862418ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:37:29.27853Z","caller":"traceutil/trace.go:171","msg":"trace[2090191605] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"311.056955ms","start":"2026-05-09T01:37:28.96741Z","end":"2026-05-09T01:37:29.278466Z","steps":["trace[2090191605] 'process raft request' (duration: 310.767068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:29.278612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:28.961249Z","time spent":"317.294018ms","remote":"127.0.0.1:48546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T01:37:29.27863Z","caller":"traceutil/trace.go:171","msg":"trace[1110631102] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"310.934193ms","start":"2026-05-09T01:37:28.967538Z","end":"2026-05-09T01:37:29.278472Z","steps":["trace[1110631102] 'process raft request' (duration: 310.671376ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:37:29.278527Z","caller":"traceutil/trace.go:171","msg":"trace[111229230] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"317.307089ms","start":"2026-05-09T01:37:28.961179Z","end":"2026-05-09T01:37:29.278486Z","steps":["trace[111229230] 'process raft request' (duration: 316.909369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:29.278759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:28.967532Z","time spent":"311.140688ms","remote":"127.0.0.1:48546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:37:29.278931Z","caller":"traceutil/trace.go:171","msg":"trace[902367520] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"311.342474ms","start":"2026-05-09T01:37:28.967576Z","end":"2026-05-09T01:37:29.278919Z","steps":["trace[902367520] 'process raft request' (duration: 310.673696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:29.278713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:28.967391Z","time spent":"311.253201ms","remote":"127.0.0.1:60898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T01:37:29.278943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:28.961164Z","time spent":"317.660038ms","remote":"127.0.0.1:48546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T01:37:29.278996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:28.967553Z","time spent":"311.411336ms","remote":"127.0.0.1:60898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T01:37:29.27925Z","caller":"traceutil/trace.go:171","msg":"trace[1728166439] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"311.59789ms","start":"2026-05-09T01:37:28.96764Z","end":"2026-05-09T01:37:29.279238Z","steps":["trace[1728166439] 'process raft request' (duration: 310.649416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:29.279315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:28.967622Z","time spent":"311.659722ms","remote":"127.0.0.1:48546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T01:37:29.27942Z","caller":"traceutil/trace.go:171","msg":"trace[606499011] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"307.435423ms","start":"2026-05-09T01:37:28.971972Z","end":"2026-05-09T01:37:29.279408Z","steps":["trace[606499011] 'process raft request' (duration: 307.165615ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:37:29.279509Z","caller":"traceutil/trace.go:171","msg":"trace[880724624] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"305.348798ms","start":"2026-05-09T01:37:28.974143Z","end":"2026-05-09T01:37:29.279492Z","steps":["trace[880724624] 'process raft request' (duration: 305.171862ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:37:29.279586Z","caller":"traceutil/trace.go:171","msg":"trace[1243588902] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"305.382578ms","start":"2026-05-09T01:37:28.974194Z","end":"2026-05-09T01:37:29.279577Z","steps":["trace[1243588902] 'process raft request' (duration: 305.183852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:29.279638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:28.97419Z","time spent":"305.426289ms","remote":"127.0.0.1:48546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T01:37:29.279672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:28.974136Z","time spent":"305.43982ms","remote":"127.0.0.1:48546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:37:29.279745Z","caller":"traceutil/trace.go:171","msg":"trace[1986518177] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"287.359877ms","start":"2026-05-09T01:37:28.992377Z","end":"2026-05-09T01:37:29.279737Z","steps":["trace[1986518177] 'process raft request' (duration: 287.07452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:29.279525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:28.971961Z","time spent":"307.525895ms","remote":"127.0.0.1:48546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7326,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:37:29.279705Z","caller":"traceutil/trace.go:171","msg":"trace[518945842] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"189.481341ms","start":"2026-05-09T01:37:29.090206Z","end":"2026-05-09T01:37:29.279688Z","steps":["trace[518945842] 'process raft request' (duration: 189.400309ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:37:30.777638Z","caller":"traceutil/trace.go:171","msg":"trace[1096535909] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"100.358113ms","start":"2026-05-09T01:37:30.677245Z","end":"2026-05-09T01:37:30.777603Z","steps":["trace[1096535909] 'process raft request' (duration: 99.987843ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:37:31.493582Z","caller":"traceutil/trace.go:171","msg":"trace[1278731931] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"169.96806ms","start":"2026-05-09T01:37:31.323592Z","end":"2026-05-09T01:37:31.49356Z","steps":["trace[1278731931] 'process raft request' (duration: 169.585051ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:37:31.758189Z","caller":"traceutil/trace.go:171","msg":"trace[491437551] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:606; }","duration":"320.44098ms","start":"2026-05-09T01:37:31.437731Z","end":"2026-05-09T01:37:31.758172Z","steps":["trace[491437551] 'read index received' (duration: 55.693665ms)","trace[491437551] 'applied index is now lower than readState.Index' (duration: 264.746545ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:37:31.75842Z","caller":"traceutil/trace.go:171","msg":"trace[14493938] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"434.506569ms","start":"2026-05-09T01:37:31.323906Z","end":"2026-05-09T01:37:31.758412Z","steps":["trace[14493938] 'process raft request' (duration: 434.094969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:31.758515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:31.323874Z","time spent":"434.570472ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:37:31.758664Z","caller":"traceutil/trace.go:171","msg":"trace[785870169] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"432.448926ms","start":"2026-05-09T01:37:31.326208Z","end":"2026-05-09T01:37:31.758657Z","steps":["trace[785870169] 'process raft request' (duration: 431.894321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:31.758721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:31.326185Z","time spent":"432.498928ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:37:31.758817Z","caller":"traceutil/trace.go:171","msg":"trace[1297933368] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"412.72481ms","start":"2026-05-09T01:37:31.346086Z","end":"2026-05-09T01:37:31.75881Z","steps":["trace[1297933368] 'process raft request' (duration: 412.041352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:31.758872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:31.346065Z","time spent":"412.769912ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T01:37:31.758974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.262132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:37:31.758999Z","caller":"traceutil/trace.go:171","msg":"trace[332558933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"321.321383ms","start":"2026-05-09T01:37:31.437671Z","end":"2026-05-09T01:37:31.758992Z","steps":["trace[332558933] 'agreement among raft nodes before linearized reading' (duration: 321.285762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:31.75903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:31.437651Z","time spent":"321.362154ms","remote":"127.0.0.1:48482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T01:37:35.612132Z","caller":"traceutil/trace.go:171","msg":"trace[1374092287] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"102.514998ms","start":"2026-05-09T01:37:35.509598Z","end":"2026-05-09T01:37:35.612113Z","steps":["trace[1374092287] 'process raft request' (duration: 102.397485ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:37:35.899924Z","caller":"traceutil/trace.go:171","msg":"trace[109235362] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"128.60956ms","start":"2026-05-09T01:37:35.771287Z","end":"2026-05-09T01:37:35.899897Z","steps":["trace[109235362] 'read index received' (duration: 126.32955ms)","trace[109235362] 'applied index is now lower than readState.Index' (duration: 2.27914ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:37:35.899988Z","caller":"traceutil/trace.go:171","msg":"trace[1975001381] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"156.584131ms","start":"2026-05-09T01:37:35.743363Z","end":"2026-05-09T01:37:35.899947Z","steps":["trace[1975001381] 'process raft request' (duration: 154.99874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:35.900536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.203876ms","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-05-09T01:37:35.900649Z","caller":"traceutil/trace.go:171","msg":"trace[744695792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"129.34779ms","start":"2026-05-09T01:37:35.771234Z","end":"2026-05-09T01:37:35.900582Z","steps":["trace[744695792] 'agreement among raft nodes before linearized reading' (duration: 128.786715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:35.953357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.772722ms","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-05-09T01:37:35.953416Z","caller":"traceutil/trace.go:171","msg":"trace[1550377473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:617; }","duration":"180.842855ms","start":"2026-05-09T01:37:35.772558Z","end":"2026-05-09T01:37:35.953401Z","steps":["trace[1550377473] 'agreement among raft nodes before linearized reading' (duration: 180.711531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:35.953457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.256412ms","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-05-09T01:37:35.953529Z","caller":"traceutil/trace.go:171","msg":"trace[1138749000] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:617; }","duration":"169.364264ms","start":"2026-05-09T01:37:35.784151Z","end":"2026-05-09T01:37:35.953515Z","steps":["trace[1138749000] 'agreement among raft nodes before linearized reading' (duration: 169.197159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:36.64824Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899112964039678,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T01:37:36.846314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.881249ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T01:37:36.846553Z","caller":"traceutil/trace.go:171","msg":"trace[31585417] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:640; }","duration":"698.936597ms","start":"2026-05-09T01:37:36.147595Z","end":"2026-05-09T01:37:36.846532Z","steps":["trace[31585417] 'read index received' (duration: 698.400372ms)","trace[31585417] 'applied index is now lower than readState.Index' (duration: 535.115µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:37:36.846687Z","caller":"traceutil/trace.go:171","msg":"trace[514357856] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"879.208566ms","start":"2026-05-09T01:37:35.967448Z","end":"2026-05-09T01:37:36.846657Z","steps":["trace[514357856] 'process raft request' (duration: 879.041292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:36.846785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:35.967437Z","time spent":"879.305119ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T01:37:36.846882Z","caller":"traceutil/trace.go:171","msg":"trace[2017160228] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"888.742596ms","start":"2026-05-09T01:37:35.958114Z","end":"2026-05-09T01:37:36.846856Z","steps":["trace[2017160228] 'process raft request' (duration: 888.304894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:36.846989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:35.958095Z","time spent":"888.838258ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:37:36.847016Z","caller":"traceutil/trace.go:171","msg":"trace[1615919176] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"889.737341ms","start":"2026-05-09T01:37:35.957254Z","end":"2026-05-09T01:37:36.846992Z","steps":["trace[1615919176] 'process raft request' (duration: 18.076992ms)","trace[1615919176] 'compare' (duration: 870.736945ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:37:36.847107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.667883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T01:37:36.847157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:35.957233Z","time spent":"889.865545ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:37:36.847164Z","caller":"traceutil/trace.go:171","msg":"trace[1618208382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"411.818957ms","start":"2026-05-09T01:37:36.435327Z","end":"2026-05-09T01:37:36.847146Z","steps":["trace[1618208382] 'agreement among raft nodes before linearized reading' (duration: 411.679333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:36.847168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.550194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-n8ggh\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-05-09T01:37:36.84722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:36.435305Z","time spent":"411.901729ms","remote":"127.0.0.1:48482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T01:37:36.847267Z","caller":"traceutil/trace.go:171","msg":"trace[799378093] range","detail":"{range_begin:/registry/pods/kube-system/cilium-n8ggh; range_end:; response_count:1; response_revision:621; }","duration":"699.657776ms","start":"2026-05-09T01:37:36.147582Z","end":"2026-05-09T01:37:36.847239Z","steps":["trace[799378093] 'agreement among raft nodes before linearized reading' (duration: 699.432551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:36.847342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:36.147533Z","time spent":"699.795049ms","remote":"127.0.0.1:48686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-n8ggh\" "} {"level":"info","ts":"2026-05-09T01:37:37.504351Z","caller":"traceutil/trace.go:171","msg":"trace[2006917506] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"643.191151ms","start":"2026-05-09T01:37:36.861138Z","end":"2026-05-09T01:37:37.504329Z","steps":["trace[2006917506] 'process raft request' (duration: 643.032337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:37.504469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:36.861125Z","time spent":"643.288343ms","remote":"127.0.0.1:48686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T01:37:37.519773Z","caller":"traceutil/trace.go:171","msg":"trace[1471764919] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"658.383967ms","start":"2026-05-09T01:37:36.861329Z","end":"2026-05-09T01:37:37.519713Z","steps":["trace[1471764919] 'process raft request' (duration: 658.177332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:37.519981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:36.861321Z","time spent":"658.573122ms","remote":"127.0.0.1:48686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:37:54.551699Z","caller":"traceutil/trace.go:171","msg":"trace[567949903] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"503.654416ms","start":"2026-05-09T01:37:54.048019Z","end":"2026-05-09T01:37:54.551673Z","steps":["trace[567949903] 'process raft request' (duration: 503.43496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:54.552043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:54.048001Z","time spent":"503.776509ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:37:54.68053Z","caller":"traceutil/trace.go:171","msg":"trace[794468107] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:812; }","duration":"245.557654ms","start":"2026-05-09T01:37:54.434949Z","end":"2026-05-09T01:37:54.680506Z","steps":["trace[794468107] 'read index received' (duration: 116.498073ms)","trace[794468107] 'applied index is now lower than readState.Index' (duration: 129.058541ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:37:54.680613Z","caller":"traceutil/trace.go:171","msg":"trace[634929161] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"408.928232ms","start":"2026-05-09T01:37:54.271647Z","end":"2026-05-09T01:37:54.680575Z","steps":["trace[634929161] 'process raft request' (duration: 408.715636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:54.680652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.676627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:37:54.680685Z","caller":"traceutil/trace.go:171","msg":"trace[715624124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"245.769089ms","start":"2026-05-09T01:37:54.434906Z","end":"2026-05-09T01:37:54.680675Z","steps":["trace[715624124] 'agreement among raft nodes before linearized reading' (duration: 245.688147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:37:54.68087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:37:54.271623Z","time spent":"409.065555ms","remote":"127.0.0.1:48744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:38:01.708623Z","caller":"traceutil/trace.go:171","msg":"trace[1130774599] linearizableReadLoop","detail":"{readStateIndex:883; appliedIndex:882; }","duration":"126.676709ms","start":"2026-05-09T01:38:01.581915Z","end":"2026-05-09T01:38:01.708592Z","steps":["trace[1130774599] 'read index received' (duration: 126.438212ms)","trace[1130774599] 'applied index is now lower than readState.Index' (duration: 237.237µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:38:01.708676Z","caller":"traceutil/trace.go:171","msg":"trace[1252037971] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"199.848871ms","start":"2026-05-09T01:38:01.508787Z","end":"2026-05-09T01:38:01.708636Z","steps":["trace[1252037971] 'process raft request' (duration: 199.640925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:38:01.708795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.856523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T01:38:01.70885Z","caller":"traceutil/trace.go:171","msg":"trace[1337637226] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:855; }","duration":"126.974226ms","start":"2026-05-09T01:38:01.581864Z","end":"2026-05-09T01:38:01.708839Z","steps":["trace[1337637226] 'agreement among raft nodes before linearized reading' (duration: 126.856553ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:38:01.966999Z","caller":"traceutil/trace.go:171","msg":"trace[302795000] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"174.967501ms","start":"2026-05-09T01:38:01.792005Z","end":"2026-05-09T01:38:01.966972Z","steps":["trace[302795000] 'process raft request' (duration: 174.718603ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:38:01.972845Z","caller":"traceutil/trace.go:171","msg":"trace[1002226281] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"137.116332ms","start":"2026-05-09T01:38:01.835711Z","end":"2026-05-09T01:38:01.972828Z","steps":["trace[1002226281] 'process raft request' (duration: 136.998729ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:38:09.506946Z","caller":"traceutil/trace.go:171","msg":"trace[528780081] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"114.88169ms","start":"2026-05-09T01:38:09.392036Z","end":"2026-05-09T01:38:09.506918Z","steps":["trace[528780081] 'process raft request' (duration: 109.792867ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:38:10.018474Z","caller":"traceutil/trace.go:171","msg":"trace[1877909475] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"201.059602ms","start":"2026-05-09T01:38:09.817378Z","end":"2026-05-09T01:38:10.018438Z","steps":["trace[1877909475] 'process raft request' (duration: 200.890578ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:38:29.603622Z","caller":"traceutil/trace.go:171","msg":"trace[1562643803] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1034; }","duration":"396.334033ms","start":"2026-05-09T01:38:29.207268Z","end":"2026-05-09T01:38:29.603602Z","steps":["trace[1562643803] 'read index received' (duration: 396.252191ms)","trace[1562643803] 'applied index is now lower than readState.Index' (duration: 81.192µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:38:29.603946Z","caller":"traceutil/trace.go:171","msg":"trace[1427300531] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"437.015247ms","start":"2026-05-09T01:38:29.166669Z","end":"2026-05-09T01:38:29.603685Z","steps":["trace[1427300531] 'process raft request' (duration: 436.819522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:38:29.604059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.773294ms","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-05-09T01:38:29.604138Z","caller":"traceutil/trace.go:171","msg":"trace[1612287529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:998; }","duration":"396.887947ms","start":"2026-05-09T01:38:29.207232Z","end":"2026-05-09T01:38:29.60412Z","steps":["trace[1612287529] 'agreement among raft nodes before linearized reading' (duration: 396.473496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:38:29.604133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:38:29.166644Z","time spent":"437.389336ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T01:38:29.604194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:38:29.207218Z","time spent":"396.963099ms","remote":"127.0.0.1:48772","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-05-09T01:38:29.980733Z","caller":"traceutil/trace.go:171","msg":"trace[615434541] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1035; }","duration":"377.028022ms","start":"2026-05-09T01:38:29.603679Z","end":"2026-05-09T01:38:29.980707Z","steps":["trace[615434541] 'read index received' (duration: 376.897719ms)","trace[615434541] 'applied index is now lower than readState.Index' (duration: 129.173µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:38:29.980757Z","caller":"traceutil/trace.go:171","msg":"trace[111695342] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"509.109335ms","start":"2026-05-09T01:38:29.471616Z","end":"2026-05-09T01:38:29.980725Z","steps":["trace[111695342] 'process raft request' (duration: 508.971722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:38:29.980863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.990785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:38:29.980916Z","caller":"traceutil/trace.go:171","msg":"trace[148919656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:999; }","duration":"545.082176ms","start":"2026-05-09T01:38:29.43582Z","end":"2026-05-09T01:38:29.980902Z","steps":["trace[148919656] 'agreement among raft nodes before linearized reading' (duration: 544.964444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:38:29.980915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:38:29.47158Z","time spent":"509.251189ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T01:38:29.980932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.183411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-09T01:38:29.980959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:38:29.435793Z","time spent":"545.145308ms","remote":"127.0.0.1:48482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T01:38:29.980968Z","caller":"traceutil/trace.go:171","msg":"trace[669915177] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:999; }","duration":"748.247382ms","start":"2026-05-09T01:38:29.232709Z","end":"2026-05-09T01:38:29.980957Z","steps":["trace[669915177] 'agreement among raft nodes before linearized reading' (duration: 748.112159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:38:29.981002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:38:29.232701Z","time spent":"748.292744ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-09T01:38:30.170613Z","caller":"traceutil/trace.go:171","msg":"trace[480068132] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"185.259741ms","start":"2026-05-09T01:38:29.985337Z","end":"2026-05-09T01:38:30.170596Z","steps":["trace[480068132] 'process raft request' (duration: 185.22877ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:38:30.170679Z","caller":"traceutil/trace.go:171","msg":"trace[1165060104] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1036; }","duration":"189.85816ms","start":"2026-05-09T01:38:29.980798Z","end":"2026-05-09T01:38:30.170656Z","steps":["trace[1165060104] 'read index received' (duration: 172.524512ms)","trace[1165060104] 'applied index is now lower than readState.Index' (duration: 17.331368ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:38:30.170677Z","caller":"traceutil/trace.go:171","msg":"trace[1404511248] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"562.778146ms","start":"2026-05-09T01:38:29.607857Z","end":"2026-05-09T01:38:30.170635Z","steps":["trace[1404511248] 'process raft request' (duration: 545.220131ms)","trace[1404511248] 'compare' (duration: 17.413091ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:38:30.170838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.255369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T01:38:30.170834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.16046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:38:30.170874Z","caller":"traceutil/trace.go:171","msg":"trace[705877596] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1001; }","duration":"188.328531ms","start":"2026-05-09T01:38:29.982535Z","end":"2026-05-09T01:38:30.170864Z","steps":["trace[705877596] 'agreement among raft nodes before linearized reading' (duration: 188.246998ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:38:30.170907Z","caller":"traceutil/trace.go:171","msg":"trace[1753698540] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1001; }","duration":"295.247653ms","start":"2026-05-09T01:38:29.875639Z","end":"2026-05-09T01:38:30.170887Z","steps":["trace[1753698540] 'agreement among raft nodes before linearized reading' (duration: 295.115029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:38:30.17123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:38:29.607836Z","time spent":"563.32032ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:38:53.877631Z","caller":"traceutil/trace.go:171","msg":"trace[654076757] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"164.687338ms","start":"2026-05-09T01:38:53.712925Z","end":"2026-05-09T01:38:53.877612Z","steps":["trace[654076757] 'process raft request' (duration: 164.573265ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:38:54.195992Z","caller":"traceutil/trace.go:171","msg":"trace[150069191] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"133.231594ms","start":"2026-05-09T01:38:54.062722Z","end":"2026-05-09T01:38:54.195953Z","steps":["trace[150069191] 'process raft request' (duration: 41.66798ms)","trace[150069191] 'compare' (duration: 91.294776ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:38:55.292815Z","caller":"traceutil/trace.go:171","msg":"trace[1890875909] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"362.141265ms","start":"2026-05-09T01:38:54.930653Z","end":"2026-05-09T01:38:55.292794Z","steps":["trace[1890875909] 'process raft request' (duration: 362.009672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:38:55.292947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:38:54.930635Z","time spent":"362.241358ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:38:56.471636Z","caller":"traceutil/trace.go:171","msg":"trace[166654491] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1127; }","duration":"134.113047ms","start":"2026-05-09T01:38:56.3375Z","end":"2026-05-09T01:38:56.471614Z","steps":["trace[166654491] 'read index received' (duration: 133.866621ms)","trace[166654491] 'applied index is now lower than readState.Index' (duration: 245.546µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:38:56.471705Z","caller":"traceutil/trace.go:171","msg":"trace[608020666] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"147.902363ms","start":"2026-05-09T01:38:56.323771Z","end":"2026-05-09T01:38:56.471673Z","steps":["trace[608020666] 'process raft request' (duration: 147.666337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:38:56.471841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.330932ms","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-05-09T01:38:56.471897Z","caller":"traceutil/trace.go:171","msg":"trace[1911801743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1086; }","duration":"134.426904ms","start":"2026-05-09T01:38:56.33746Z","end":"2026-05-09T01:38:56.471887Z","steps":["trace[1911801743] 'agreement among raft nodes before linearized reading' (duration: 134.269161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:38:56.471974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.580431ms","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-05-09T01:38:56.472073Z","caller":"traceutil/trace.go:171","msg":"trace[2035953145] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1086; }","duration":"121.754076ms","start":"2026-05-09T01:38:56.350303Z","end":"2026-05-09T01:38:56.472058Z","steps":["trace[2035953145] 'agreement among raft nodes before linearized reading' (duration: 121.514499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:38:56.951479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.582692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T01:38:56.951688Z","caller":"traceutil/trace.go:171","msg":"trace[473489788] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"475.012721ms","start":"2026-05-09T01:38:56.476644Z","end":"2026-05-09T01:38:56.951657Z","steps":["trace[473489788] 'process raft request' (duration: 226.10007ms)","trace[473489788] 'compare' (duration: 248.202813ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:38:56.951755Z","caller":"traceutil/trace.go:171","msg":"trace[903375408] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1128; }","duration":"332.321443ms","start":"2026-05-09T01:38:56.619411Z","end":"2026-05-09T01:38:56.951732Z","steps":["trace[903375408] 'read index received' (duration: 83.442282ms)","trace[903375408] 'applied index is now lower than readState.Index' (duration: 248.877631ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:38:56.951841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:38:56.476623Z","time spent":"475.132625ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:38:56.951863Z","caller":"traceutil/trace.go:171","msg":"trace[254636182] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"474.918288ms","start":"2026-05-09T01:38:56.476935Z","end":"2026-05-09T01:38:56.951853Z","steps":["trace[254636182] 'process raft request' (duration: 474.646492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:38:56.951931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:38:56.476911Z","time spent":"474.97946ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T01:38:56.951933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.594082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T01:38:56.951985Z","caller":"traceutil/trace.go:171","msg":"trace[608616386] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1088; }","duration":"241.706124ms","start":"2026-05-09T01:38:56.710267Z","end":"2026-05-09T01:38:56.951973Z","steps":["trace[608616386] 'agreement among raft nodes before linearized reading' (duration: 241.612342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:38:56.952023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.5933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:38:56.952123Z","caller":"traceutil/trace.go:171","msg":"trace[596635841] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1088; }","duration":"332.779384ms","start":"2026-05-09T01:38:56.619321Z","end":"2026-05-09T01:38:56.952101Z","steps":["trace[596635841] 'agreement among raft nodes before linearized reading' (duration: 332.60059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:38:56.952184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:38:56.619277Z","time spent":"332.892537ms","remote":"127.0.0.1:52758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-09T01:39:08.153317Z","caller":"traceutil/trace.go:171","msg":"trace[250057586] linearizableReadLoop","detail":"{readStateIndex:1222; appliedIndex:1221; }","duration":"139.450404ms","start":"2026-05-09T01:39:08.013848Z","end":"2026-05-09T01:39:08.153298Z","steps":["trace[250057586] 'read index received' (duration: 139.250099ms)","trace[250057586] 'applied index is now lower than readState.Index' (duration: 199.415µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:39:08.15343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.549596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:39:08.153454Z","caller":"traceutil/trace.go:171","msg":"trace[1120708501] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1175; }","duration":"139.624238ms","start":"2026-05-09T01:39:08.013823Z","end":"2026-05-09T01:39:08.153448Z","steps":["trace[1120708501] 'agreement among raft nodes before linearized reading' (duration: 139.553527ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:39:08.153435Z","caller":"traceutil/trace.go:171","msg":"trace[834406788] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"147.608136ms","start":"2026-05-09T01:39:08.005764Z","end":"2026-05-09T01:39:08.153373Z","steps":["trace[834406788] 'process raft request' (duration: 147.418161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:39:09.512279Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899112964040964,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T01:39:09.86328Z","caller":"traceutil/trace.go:171","msg":"trace[2067714994] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"859.170867ms","start":"2026-05-09T01:39:09.004084Z","end":"2026-05-09T01:39:09.863255Z","steps":["trace[2067714994] 'process raft request' (duration: 859.050044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:39:09.863419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:39:09.004071Z","time spent":"859.27817ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:39:09.864063Z","caller":"traceutil/trace.go:171","msg":"trace[562688976] linearizableReadLoop","detail":"{readStateIndex:1226; appliedIndex:1225; }","duration":"852.487404ms","start":"2026-05-09T01:39:09.011555Z","end":"2026-05-09T01:39:09.864042Z","steps":["trace[562688976] 'read index received' (duration: 852.156846ms)","trace[562688976] 'applied index is now lower than readState.Index' (duration: 329.738µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:39:09.86412Z","caller":"traceutil/trace.go:171","msg":"trace[235397495] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"857.197027ms","start":"2026-05-09T01:39:09.006899Z","end":"2026-05-09T01:39:09.864096Z","steps":["trace[235397495] 'process raft request' (duration: 857.021482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:39:09.864185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:39:09.006892Z","time spent":"857.258188ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T01:39:09.864215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.442115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T01:39:09.864225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.644908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T01:39:09.864316Z","caller":"traceutil/trace.go:171","msg":"trace[49835598] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1179; }","duration":"619.598438ms","start":"2026-05-09T01:39:09.244702Z","end":"2026-05-09T01:39:09.8643Z","steps":["trace[49835598] 'agreement among raft nodes before linearized reading' (duration: 619.455844ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:39:09.864349Z","caller":"traceutil/trace.go:171","msg":"trace[1203252145] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1179; }","duration":"852.813653ms","start":"2026-05-09T01:39:09.011518Z","end":"2026-05-09T01:39:09.864331Z","steps":["trace[1203252145] 'agreement among raft nodes before linearized reading' (duration: 852.627798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:39:09.864362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:39:09.244685Z","time spent":"619.66545ms","remote":"127.0.0.1:52746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-09T01:39:09.864394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:39:09.011501Z","time spent":"852.880814ms","remote":"127.0.0.1:48602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":17,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-05-09T01:39:09.864626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.839213ms","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-05-09T01:39:09.864656Z","caller":"traceutil/trace.go:171","msg":"trace[1860971751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1179; }","duration":"514.892464ms","start":"2026-05-09T01:39:09.349755Z","end":"2026-05-09T01:39:09.864648Z","steps":["trace[1860971751] 'agreement among raft nodes before linearized reading' (duration: 514.799742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:39:09.864673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:39:09.349743Z","time spent":"514.926495ms","remote":"127.0.0.1:48772","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-05-09T01:39:09.864664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.907446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:39:09.864714Z","caller":"traceutil/trace.go:171","msg":"trace[1289356864] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1179; }","duration":"428.984068ms","start":"2026-05-09T01:39:09.435715Z","end":"2026-05-09T01:39:09.864699Z","steps":["trace[1289356864] 'agreement among raft nodes before linearized reading' (duration: 428.907606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:39:09.864747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:39:09.435702Z","time spent":"429.038569ms","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T01:39:25.8879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.101766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-cgw5q\" ","response":"range_response_count:1 size:11057"} {"level":"info","ts":"2026-05-09T01:39:25.887955Z","caller":"traceutil/trace.go:171","msg":"trace[459792770] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-cgw5q; range_end:; response_count:1; response_revision:1246; }","duration":"335.209059ms","start":"2026-05-09T01:39:25.552733Z","end":"2026-05-09T01:39:25.887942Z","steps":["trace[459792770] 'range keys from in-memory index tree' (duration: 334.949851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:39:25.887922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.028636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:39:25.888044Z","caller":"traceutil/trace.go:171","msg":"trace[29441563] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1246; }","duration":"163.21535ms","start":"2026-05-09T01:39:25.724794Z","end":"2026-05-09T01:39:25.888009Z","steps":["trace[29441563] 'count revisions from in-memory index tree' (duration: 162.914453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:39:25.887981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:39:25.552713Z","time spent":"335.26339ms","remote":"127.0.0.1:48686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":11081,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-cgw5q\" "} {"level":"info","ts":"2026-05-09T01:39:26.270354Z","caller":"traceutil/trace.go:171","msg":"trace[163412599] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1296; }","duration":"297.511641ms","start":"2026-05-09T01:39:25.972815Z","end":"2026-05-09T01:39:26.270326Z","steps":["trace[163412599] 'read index received' (duration: 297.220693ms)","trace[163412599] 'applied index is now lower than readState.Index' (duration: 289.218µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:39:26.270428Z","caller":"traceutil/trace.go:171","msg":"trace[1161346527] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"371.229081ms","start":"2026-05-09T01:39:25.899165Z","end":"2026-05-09T01:39:26.270394Z","steps":["trace[1161346527] 'process raft request' (duration: 370.967175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:39:26.270618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:39:25.899114Z","time spent":"371.403265ms","remote":"127.0.0.1:48686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11371,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T01:39:26.270645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.336811ms","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-05-09T01:39:26.270683Z","caller":"traceutil/trace.go:171","msg":"trace[2004176129] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1247; }","duration":"286.411603ms","start":"2026-05-09T01:39:25.984261Z","end":"2026-05-09T01:39:26.270673Z","steps":["trace[2004176129] 'agreement among raft nodes before linearized reading' (duration: 286.240569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:39:26.270878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.011614ms","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-05-09T01:39:26.271034Z","caller":"traceutil/trace.go:171","msg":"trace[1063488856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1247; }","duration":"298.247959ms","start":"2026-05-09T01:39:25.972753Z","end":"2026-05-09T01:39:26.271001Z","steps":["trace[1063488856] 'agreement among raft nodes before linearized reading' (duration: 297.727135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:39:26.270983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.393913ms","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-05-09T01:39:26.271441Z","caller":"traceutil/trace.go:171","msg":"trace[2041362637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1247; }","duration":"281.815283ms","start":"2026-05-09T01:39:25.989539Z","end":"2026-05-09T01:39:26.271354Z","steps":["trace[2041362637] 'agreement among raft nodes before linearized reading' (duration: 281.219027ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:39:26.386684Z","caller":"traceutil/trace.go:171","msg":"trace[1882545625] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"111.11285ms","start":"2026-05-09T01:39:26.275554Z","end":"2026-05-09T01:39:26.386666Z","steps":["trace[1882545625] 'process raft request' (duration: 111.045198ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:39:26.386755Z","caller":"traceutil/trace.go:171","msg":"trace[601538457] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"111.407397ms","start":"2026-05-09T01:39:26.275294Z","end":"2026-05-09T01:39:26.386701Z","steps":["trace[601538457] 'process raft request' (duration: 111.206742ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:39:26.387183Z","caller":"traceutil/trace.go:171","msg":"trace[1046499803] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"108.317547ms","start":"2026-05-09T01:39:26.278856Z","end":"2026-05-09T01:39:26.387173Z","steps":["trace[1046499803] 'process raft request' (duration: 107.769513ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:39:26.387582Z","caller":"traceutil/trace.go:171","msg":"trace[1099078588] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"108.428101ms","start":"2026-05-09T01:39:26.279136Z","end":"2026-05-09T01:39:26.387564Z","steps":["trace[1099078588] 'process raft request' (duration: 107.834065ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:39:26.387714Z","caller":"traceutil/trace.go:171","msg":"trace[317664640] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"104.689264ms","start":"2026-05-09T01:39:26.282996Z","end":"2026-05-09T01:39:26.387686Z","steps":["trace[317664640] 'process raft request' (duration: 104.313114ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:39:27.28948Z","caller":"traceutil/trace.go:171","msg":"trace[995063084] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1308; }","duration":"429.023099ms","start":"2026-05-09T01:39:26.860433Z","end":"2026-05-09T01:39:27.289456Z","steps":["trace[995063084] 'read index received' (duration: 428.851004ms)","trace[995063084] 'applied index is now lower than readState.Index' (duration: 170.715µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:39:27.289647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.190903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:39:27.289707Z","caller":"traceutil/trace.go:171","msg":"trace[1619907499] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1259; }","duration":"429.305836ms","start":"2026-05-09T01:39:26.860391Z","end":"2026-05-09T01:39:27.289697Z","steps":["trace[1619907499] 'agreement among raft nodes before linearized reading' (duration: 429.160633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:39:27.289739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:39:26.86037Z","time spent":"429.362958ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-09T01:39:27.290033Z","caller":"traceutil/trace.go:171","msg":"trace[1676392503] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"453.912295ms","start":"2026-05-09T01:39:26.835594Z","end":"2026-05-09T01:39:27.289506Z","steps":["trace[1676392503] 'process raft request' (duration: 453.687388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:39:27.290122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:39:26.835575Z","time spent":"454.484599ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T01:39:27.545142Z","caller":"traceutil/trace.go:171","msg":"trace[592331450] linearizableReadLoop","detail":"{readStateIndex:1311; appliedIndex:1309; }","duration":"109.399375ms","start":"2026-05-09T01:39:27.435727Z","end":"2026-05-09T01:39:27.545126Z","steps":["trace[592331450] 'read index received' (duration: 23.311174ms)","trace[592331450] 'applied index is now lower than readState.Index' (duration: 86.087561ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:39:27.545242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.500369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:39:27.545267Z","caller":"traceutil/trace.go:171","msg":"trace[1733940614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1261; }","duration":"109.56608ms","start":"2026-05-09T01:39:27.435694Z","end":"2026-05-09T01:39:27.545261Z","steps":["trace[1733940614] 'agreement among raft nodes before linearized reading' (duration: 109.494138ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:39:27.545415Z","caller":"traceutil/trace.go:171","msg":"trace[1746191952] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"251.668653ms","start":"2026-05-09T01:39:27.293738Z","end":"2026-05-09T01:39:27.545407Z","steps":["trace[1746191952] 'process raft request' (duration: 165.191262ms)","trace[1746191952] 'compare' (duration: 85.837024ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:39:27.545604Z","caller":"traceutil/trace.go:171","msg":"trace[964000965] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"251.171118ms","start":"2026-05-09T01:39:27.294396Z","end":"2026-05-09T01:39:27.545567Z","steps":["trace[964000965] 'process raft request' (duration: 250.666666ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:39:40.595591Z","caller":"traceutil/trace.go:171","msg":"trace[19836850] linearizableReadLoop","detail":"{readStateIndex:1401; appliedIndex:1400; }","duration":"160.422548ms","start":"2026-05-09T01:39:40.435145Z","end":"2026-05-09T01:39:40.595568Z","steps":["trace[19836850] 'read index received' (duration: 160.394407ms)","trace[19836850] 'applied index is now lower than readState.Index' (duration: 26.691µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:39:40.595611Z","caller":"traceutil/trace.go:171","msg":"trace[1300054172] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"183.018494ms","start":"2026-05-09T01:39:40.412555Z","end":"2026-05-09T01:39:40.595573Z","steps":["trace[1300054172] 'process raft request' (duration: 182.85355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:39:40.595743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.580832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:39:40.595787Z","caller":"traceutil/trace.go:171","msg":"trace[2010682106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1348; }","duration":"160.673394ms","start":"2026-05-09T01:39:40.435101Z","end":"2026-05-09T01:39:40.595774Z","steps":["trace[2010682106] 'agreement among raft nodes before linearized reading' (duration: 160.590692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:39:41.09588Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794899112964041319,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T01:39:41.26257Z","caller":"traceutil/trace.go:171","msg":"trace[752719314] linearizableReadLoop","detail":"{readStateIndex:1402; appliedIndex:1401; }","duration":"666.880535ms","start":"2026-05-09T01:39:40.595666Z","end":"2026-05-09T01:39:41.262547Z","steps":["trace[752719314] 'read index received' (duration: 607.305721ms)","trace[752719314] 'applied index is now lower than readState.Index' (duration: 59.573404ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T01:39:41.262888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.816826ms","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-05-09T01:39:41.26297Z","caller":"traceutil/trace.go:171","msg":"trace[269950006] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1349; }","duration":"778.95498ms","start":"2026-05-09T01:39:40.483996Z","end":"2026-05-09T01:39:41.262951Z","steps":["trace[269950006] 'agreement among raft nodes before linearized reading' (duration: 778.715364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:39:41.262969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.844493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-09T01:39:41.263013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:39:40.483974Z","time spent":"779.029742ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-09T01:39:41.263014Z","caller":"traceutil/trace.go:171","msg":"trace[1914565411] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"825.547297ms","start":"2026-05-09T01:39:40.437432Z","end":"2026-05-09T01:39:41.262979Z","steps":["trace[1914565411] 'process raft request' (duration: 765.593563ms)","trace[1914565411] 'compare' (duration: 59.40896ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:39:41.263031Z","caller":"traceutil/trace.go:171","msg":"trace[713722684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1349; }","duration":"808.937336ms","start":"2026-05-09T01:39:40.454076Z","end":"2026-05-09T01:39:41.263013Z","steps":["trace[713722684] 'agreement among raft nodes before linearized reading' (duration: 808.771732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:39:41.263144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:39:40.454065Z","time spent":"809.063239ms","remote":"127.0.0.1:48772","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-05-09T01:39:41.263179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.922263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-09T01:39:41.263189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:39:40.437397Z","time spent":"825.706781ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:39:41.263244Z","caller":"traceutil/trace.go:171","msg":"trace[1647677191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1349; }","duration":"810.029965ms","start":"2026-05-09T01:39:40.4532Z","end":"2026-05-09T01:39:41.26323Z","steps":["trace[1647677191] 'agreement among raft nodes before linearized reading' (duration: 809.8297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:39:41.263278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:39:40.453182Z","time spent":"810.087427ms","remote":"127.0.0.1:48772","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-05-09T01:39:41.262988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.102474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:39:41.263466Z","caller":"traceutil/trace.go:171","msg":"trace[2036731724] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1349; }","duration":"666.590877ms","start":"2026-05-09T01:39:40.59683Z","end":"2026-05-09T01:39:41.263421Z","steps":["trace[2036731724] 'agreement among raft nodes before linearized reading' (duration: 666.115465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:39:41.263538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:39:40.596814Z","time spent":"666.71179ms","remote":"127.0.0.1:48470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T01:39:41.263557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.58695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-09T01:39:41.263436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.946975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T01:39:41.263735Z","caller":"traceutil/trace.go:171","msg":"trace[1907537365] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1349; }","duration":"494.912528ms","start":"2026-05-09T01:39:40.768742Z","end":"2026-05-09T01:39:41.263655Z","steps":["trace[1907537365] 'agreement among raft nodes before linearized reading' (duration: 494.557649ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:39:41.26367Z","caller":"traceutil/trace.go:171","msg":"trace[1863185843] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1349; }","duration":"746.798036ms","start":"2026-05-09T01:39:40.51686Z","end":"2026-05-09T01:39:41.263658Z","steps":["trace[1863185843] 'agreement among raft nodes before linearized reading' (duration: 745.938244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:39:41.263796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:39:40.768723Z","time spent":"495.057441ms","remote":"127.0.0.1:48772","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-05-09T01:39:41.263829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:39:40.516841Z","time spent":"746.972021ms","remote":"127.0.0.1:48720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-05-09T01:39:41.264069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.11089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-09T01:39:41.265041Z","caller":"traceutil/trace.go:171","msg":"trace[887029164] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1349; }","duration":"776.768784ms","start":"2026-05-09T01:39:40.488256Z","end":"2026-05-09T01:39:41.265025Z","steps":["trace[887029164] 'agreement among raft nodes before linearized reading' (duration: 774.643838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:39:41.265191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:39:40.488238Z","time spent":"776.940017ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-09T01:39:41.907962Z","caller":"traceutil/trace.go:171","msg":"trace[808617863] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"640.222844ms","start":"2026-05-09T01:39:41.267697Z","end":"2026-05-09T01:39:41.90792Z","steps":["trace[808617863] 'process raft request' (duration: 638.268433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:39:41.90824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:39:41.267676Z","time spent":"640.44644ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:39:41.90954Z","caller":"traceutil/trace.go:171","msg":"trace[1144586073] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1402; }","duration":"474.066278ms","start":"2026-05-09T01:39:41.435432Z","end":"2026-05-09T01:39:41.909498Z","steps":["trace[1144586073] 'read index received' (duration: 470.747622ms)","trace[1144586073] 'applied index is now lower than readState.Index' (duration: 3.317566ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T01:39:41.909564Z","caller":"traceutil/trace.go:171","msg":"trace[1411518259] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"640.496141ms","start":"2026-05-09T01:39:41.269044Z","end":"2026-05-09T01:39:41.90954Z","steps":["trace[1411518259] 'process raft request' (duration: 640.415168ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:39:41.909573Z","caller":"traceutil/trace.go:171","msg":"trace[1232443072] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"640.925983ms","start":"2026-05-09T01:39:41.268632Z","end":"2026-05-09T01:39:41.909558Z","steps":["trace[1232443072] 'process raft request' (duration: 640.758718ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:39:41.909553Z","caller":"traceutil/trace.go:171","msg":"trace[1664128248] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"640.557833ms","start":"2026-05-09T01:39:41.268972Z","end":"2026-05-09T01:39:41.90953Z","steps":["trace[1664128248] 'process raft request' (duration: 640.45136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:39:41.90969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.249003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T01:39:41.909691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:39:41.268658Z","time spent":"640.959903ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:39:41.909727Z","caller":"traceutil/trace.go:171","msg":"trace[2072322370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1354; }","duration":"474.335895ms","start":"2026-05-09T01:39:41.435381Z","end":"2026-05-09T01:39:41.909717Z","steps":["trace[2072322370] 'agreement among raft nodes before linearized reading' (duration: 474.261153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:39:41.909702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:39:41.268624Z","time spent":"641.030595ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T01:39:41.909838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:39:41.268959Z","time spent":"640.729257ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T01:39:41.909761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:39:41.435362Z","time spent":"474.391487ms","remote":"127.0.0.1:48482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T01:39:41.909904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.955374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-09T01:39:41.910713Z","caller":"traceutil/trace.go:171","msg":"trace[2067531507] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1354; }","duration":"234.790035ms","start":"2026-05-09T01:39:41.6759Z","end":"2026-05-09T01:39:41.91069Z","steps":["trace[2067531507] 'agreement among raft nodes before linearized reading' (duration: 233.891872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:39:41.91Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.456675ms","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:532"} {"level":"info","ts":"2026-05-09T01:39:41.910875Z","caller":"traceutil/trace.go:171","msg":"trace[239611379] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1354; }","duration":"108.353968ms","start":"2026-05-09T01:39:41.80251Z","end":"2026-05-09T01:39:41.910864Z","steps":["trace[239611379] 'agreement among raft nodes before linearized reading' (duration: 107.431914ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T01:39:41.909525Z","caller":"traceutil/trace.go:171","msg":"trace[230118669] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"641.170889ms","start":"2026-05-09T01:39:41.268331Z","end":"2026-05-09T01:39:41.909502Z","steps":["trace[230118669] 'process raft request' (duration: 640.956502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T01:39:41.911018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T01:39:41.268312Z","time spent":"642.661818ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T01:41:18.587867Z","caller":"traceutil/trace.go:171","msg":"trace[1895145105] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"115.330663ms","start":"2026-05-09T01:41:18.472511Z","end":"2026-05-09T01:41:18.587842Z","steps":["trace[1895145105] 'process raft request' (duration: 109.4547ms)"],"step_count":1}