{"level":"warn","ts":"2026-07-28T15:48:11.457775Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-28T15:48:11.457877Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.144: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.144:2380","--initial-cluster=instance=https://199.204.45.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.144: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-07-28T15:48:11.45796Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-28T15:48:11.458015Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-07-28T15:48:11.458035Z","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-07-28T15:48:11.458784Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"]} {"level":"info","ts":"2026-07-28T15:48:11.458917Z","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.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.144: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-07-28T15:48:11.465179Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.92598ms"} {"level":"info","ts":"2026-07-28T15:48:11.472709Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4594e5f716bad646","cluster-id":"ff2e51b364668606"} {"level":"info","ts":"2026-07-28T15:48:11.472842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=()"} {"level":"info","ts":"2026-07-28T15:48:11.472871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 0"} {"level":"info","ts":"2026-07-28T15:48:11.472896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4594e5f716bad646 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-28T15:48:11.472919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 1"} {"level":"info","ts":"2026-07-28T15:48:11.472963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"warn","ts":"2026-07-28T15:48:11.481745Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-28T15:48:11.486145Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-28T15:48:11.489803Z","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-07-28T15:48:11.49686Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4594e5f716bad646","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-28T15:48:11.501334Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4594e5f716bad646","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-28T15:48:11.50229Z","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-07-28T15:48:11.502402Z","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-07-28T15:48:11.502432Z","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-07-28T15:48:11.503597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"info","ts":"2026-07-28T15:48:11.504606Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","added-peer-id":"4594e5f716bad646","added-peer-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-07-28T15:48:11.505471Z","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-07-28T15:48:11.505734Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4594e5f716bad646","initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-28T15:48:11.50576Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-28T15:48:11.505832Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-07-28T15:48:11.505872Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-07-28T15:48:11.673736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 is starting a new election at term 1"} {"level":"info","ts":"2026-07-28T15:48:11.673909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-28T15:48:11.673939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgPreVoteResp from 4594e5f716bad646 at term 1"} {"level":"info","ts":"2026-07-28T15:48:11.673979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became candidate at term 2"} {"level":"info","ts":"2026-07-28T15:48:11.674002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgVoteResp from 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-07-28T15:48:11.674019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became leader at term 2"} {"level":"info","ts":"2026-07-28T15:48:11.674054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4594e5f716bad646 elected leader 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-07-28T15:48:11.675532Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4594e5f716bad646","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.144:2379]}","request-path":"/0/members/4594e5f716bad646/attributes","cluster-id":"ff2e51b364668606","publish-timeout":"7s"} {"level":"info","ts":"2026-07-28T15:48:11.675754Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-28T15:48:11.675858Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-28T15:48:11.675883Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-28T15:48:11.675802Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-28T15:48:11.675997Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T15:48:11.677433Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T15:48:11.67759Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T15:48:11.677638Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T15:48:11.680085Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.144:2379"} {"level":"info","ts":"2026-07-28T15:48:11.680797Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-28T15:48:36.321731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.965258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-28T15:48:36.321837Z","caller":"traceutil/trace.go:171","msg":"trace[2011124615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:461; }","duration":"475.144763ms","start":"2026-07-28T15:48:35.846672Z","end":"2026-07-28T15:48:36.321816Z","steps":["trace[2011124615] 'range keys from in-memory index tree' (duration: 474.885186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:48:36.321893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:48:35.846657Z","time spent":"475.226205ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-28T15:48:46.533506Z","caller":"traceutil/trace.go:171","msg":"trace[313223502] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"149.391886ms","start":"2026-07-28T15:48:46.38405Z","end":"2026-07-28T15:48:46.533442Z","steps":["trace[313223502] 'process raft request' (duration: 149.101189ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:48:48.788976Z","caller":"traceutil/trace.go:171","msg":"trace[875231931] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"276.894991ms","start":"2026-07-28T15:48:48.512059Z","end":"2026-07-28T15:48:48.788954Z","steps":["trace[875231931] 'process raft request' (duration: 276.756928ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:48:48.819753Z","caller":"traceutil/trace.go:171","msg":"trace[1898301410] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"289.31562ms","start":"2026-07-28T15:48:48.530427Z","end":"2026-07-28T15:48:48.819743Z","steps":["trace[1898301410] 'process raft request' (duration: 289.110585ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:48:48.819886Z","caller":"traceutil/trace.go:171","msg":"trace[1918580183] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"295.286918ms","start":"2026-07-28T15:48:48.524594Z","end":"2026-07-28T15:48:48.819881Z","steps":["trace[1918580183] 'process raft request' (duration: 294.664704ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:48:48.81996Z","caller":"traceutil/trace.go:171","msg":"trace[8940676] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"287.908567ms","start":"2026-07-28T15:48:48.532044Z","end":"2026-07-28T15:48:48.819953Z","steps":["trace[8940676] 'process raft request' (duration: 287.535749ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:48:48.819399Z","caller":"traceutil/trace.go:171","msg":"trace[662690995] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"302.706521ms","start":"2026-07-28T15:48:48.516668Z","end":"2026-07-28T15:48:48.819375Z","steps":["trace[662690995] 'process raft request' (duration: 299.775913ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:48:48.819978Z","caller":"traceutil/trace.go:171","msg":"trace[2095651813] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"283.75848ms","start":"2026-07-28T15:48:48.536189Z","end":"2026-07-28T15:48:48.819948Z","steps":["trace[2095651813] 'process raft request' (duration: 283.583846ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:48:48.820079Z","caller":"traceutil/trace.go:171","msg":"trace[2029229580] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"276.973613ms","start":"2026-07-28T15:48:48.543079Z","end":"2026-07-28T15:48:48.820053Z","steps":["trace[2029229580] 'process raft request' (duration: 276.735527ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:48:48.820049Z","caller":"traceutil/trace.go:171","msg":"trace[143921536] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"286.157916ms","start":"2026-07-28T15:48:48.533776Z","end":"2026-07-28T15:48:48.819934Z","steps":["trace[143921536] 'process raft request' (duration: 285.918821ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:48:48.820252Z","caller":"traceutil/trace.go:171","msg":"trace[1645165772] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"285.42245ms","start":"2026-07-28T15:48:48.5345Z","end":"2026-07-28T15:48:48.819923Z","steps":["trace[1645165772] 'process raft request' (duration: 285.230305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:48:48.820272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:48:48.516649Z","time spent":"303.374066ms","remote":"127.0.0.1:33312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T15:48:48.820535Z","caller":"traceutil/trace.go:171","msg":"trace[168135452] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"269.56941ms","start":"2026-07-28T15:48:48.550958Z","end":"2026-07-28T15:48:48.820527Z","steps":["trace[168135452] 'process raft request' (duration: 268.915815ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:48:48.820766Z","caller":"traceutil/trace.go:171","msg":"trace[1488927888] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"255.501173ms","start":"2026-07-28T15:48:48.565259Z","end":"2026-07-28T15:48:48.82076Z","steps":["trace[1488927888] 'process raft request' (duration: 255.233807ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:48:48.820878Z","caller":"traceutil/trace.go:171","msg":"trace[905395955] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:539; }","duration":"286.863633ms","start":"2026-07-28T15:48:48.534009Z","end":"2026-07-28T15:48:48.820872Z","steps":["trace[905395955] 'read index received' (duration: 254.877499ms)","trace[905395955] 'applied index is now lower than readState.Index' (duration: 31.984513ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T15:48:48.820917Z","caller":"traceutil/trace.go:171","msg":"trace[212596739] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"112.591318ms","start":"2026-07-28T15:48:48.708322Z","end":"2026-07-28T15:48:48.820913Z","steps":["trace[212596739] 'process raft request' (duration: 112.417254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:48:48.821001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.985615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T15:48:48.821023Z","caller":"traceutil/trace.go:171","msg":"trace[753609667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"287.013026ms","start":"2026-07-28T15:48:48.534003Z","end":"2026-07-28T15:48:48.821016Z","steps":["trace[753609667] 'agreement among raft nodes before linearized reading' (duration: 286.943584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:48:48.825406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.000176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-28T15:48:48.837037Z","caller":"traceutil/trace.go:171","msg":"trace[1328823194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:537; }","duration":"288.179953ms","start":"2026-07-28T15:48:48.537322Z","end":"2026-07-28T15:48:48.825502Z","steps":["trace[1328823194] 'agreement among raft nodes before linearized reading' (duration: 283.658328ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:48:54.993672Z","caller":"traceutil/trace.go:171","msg":"trace[1419615086] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"132.022361ms","start":"2026-07-28T15:48:54.861605Z","end":"2026-07-28T15:48:54.993628Z","steps":["trace[1419615086] 'read index received' (duration: 130.884914ms)","trace[1419615086] 'applied index is now lower than readState.Index' (duration: 1.136317ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T15:48:54.994075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.45067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-n788f\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-28T15:48:54.994125Z","caller":"traceutil/trace.go:171","msg":"trace[1255210873] range","detail":"{range_begin:/registry/pods/kube-system/cilium-n788f; range_end:; response_count:1; response_revision:619; }","duration":"132.564954ms","start":"2026-07-28T15:48:54.861545Z","end":"2026-07-28T15:48:54.99411Z","steps":["trace[1255210873] 'agreement among raft nodes before linearized reading' (duration: 132.243257ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:48:54.99424Z","caller":"traceutil/trace.go:171","msg":"trace[2112009140] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"261.528074ms","start":"2026-07-28T15:48:54.732142Z","end":"2026-07-28T15:48:54.99367Z","steps":["trace[2112009140] 'process raft request' (duration: 260.506039ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:48:55.144424Z","caller":"traceutil/trace.go:171","msg":"trace[605176688] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"125.393678ms","start":"2026-07-28T15:48:55.018981Z","end":"2026-07-28T15:48:55.144375Z","steps":["trace[605176688] 'process raft request' (duration: 51.782844ms)","trace[605176688] 'compare' (duration: 72.312533ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T15:48:56.108685Z","caller":"traceutil/trace.go:171","msg":"trace[1680090726] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"467.28452ms","start":"2026-07-28T15:48:55.641369Z","end":"2026-07-28T15:48:56.108654Z","steps":["trace[1680090726] 'process raft request' (duration: 461.68097ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:48:56.109096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:48:55.641338Z","time spent":"467.457134ms","remote":"127.0.0.1:33344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T15:48:56.196644Z","caller":"traceutil/trace.go:171","msg":"trace[589410669] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"551.806577ms","start":"2026-07-28T15:48:55.6448Z","end":"2026-07-28T15:48:56.196607Z","steps":["trace[589410669] 'process raft request' (duration: 551.600502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:48:56.196805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:48:55.644765Z","time spent":"551.95967ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T15:48:56.196836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.26406ms","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-07-28T15:48:56.196877Z","caller":"traceutil/trace.go:171","msg":"trace[254542396] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:625; }","duration":"513.384003ms","start":"2026-07-28T15:48:55.683483Z","end":"2026-07-28T15:48:56.196867Z","steps":["trace[254542396] 'agreement among raft nodes before linearized reading' (duration: 513.220129ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:48:56.196602Z","caller":"traceutil/trace.go:171","msg":"trace[360948925] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:643; }","duration":"512.974073ms","start":"2026-07-28T15:48:55.683582Z","end":"2026-07-28T15:48:56.196556Z","steps":["trace[360948925] 'read index received' (duration: 417.340219ms)","trace[360948925] 'applied index is now lower than readState.Index' (duration: 95.632694ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T15:48:56.196911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:48:55.683456Z","time spent":"513.448214ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-28T15:48:56.197146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.094046ms","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-07-28T15:48:56.19723Z","caller":"traceutil/trace.go:171","msg":"trace[105622139] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:625; }","duration":"415.232019ms","start":"2026-07-28T15:48:55.781976Z","end":"2026-07-28T15:48:56.197208Z","steps":["trace[105622139] 'agreement among raft nodes before linearized reading' (duration: 415.005014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:48:56.197278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:48:55.781947Z","time spent":"415.322551ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-28T15:48:57.461227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.271909ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T15:48:57.461316Z","caller":"traceutil/trace.go:171","msg":"trace[568098798] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:626; }","duration":"243.375501ms","start":"2026-07-28T15:48:57.217927Z","end":"2026-07-28T15:48:57.461303Z","steps":["trace[568098798] 'range keys from in-memory index tree' (duration: 243.261859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:48:57.461591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.313253ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T15:48:57.461852Z","caller":"traceutil/trace.go:171","msg":"trace[67031982] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:646; }","duration":"922.686784ms","start":"2026-07-28T15:48:56.539147Z","end":"2026-07-28T15:48:57.461834Z","steps":["trace[67031982] 'read index received' (duration: 343.845849ms)","trace[67031982] 'applied index is now lower than readState.Index' (duration: 578.839865ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T15:48:57.461959Z","caller":"traceutil/trace.go:171","msg":"trace[1683158167] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"1.259739993s","start":"2026-07-28T15:48:56.202207Z","end":"2026-07-28T15:48:57.461947Z","steps":["trace[1683158167] 'process raft request' (duration: 680.810916ms)","trace[1683158167] 'compare' (duration: 578.17104ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T15:48:57.462022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:48:56.202194Z","time spent":"1.259791705s","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T15:48:57.462245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.060982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-28T15:48:57.46234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.042626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-28T15:48:57.462378Z","caller":"traceutil/trace.go:171","msg":"trace[810106128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"443.113878ms","start":"2026-07-28T15:48:57.019252Z","end":"2026-07-28T15:48:57.462366Z","steps":["trace[810106128] 'agreement among raft nodes before linearized reading' (duration: 442.985775ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:48:57.462388Z","caller":"traceutil/trace.go:171","msg":"trace[1767863730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"923.224045ms","start":"2026-07-28T15:48:56.539129Z","end":"2026-07-28T15:48:57.462353Z","steps":["trace[1767863730] 'agreement among raft nodes before linearized reading' (duration: 922.899738ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:48:57.462418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:48:57.019234Z","time spent":"443.177279ms","remote":"127.0.0.1:33492","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-07-28T15:48:57.462451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:48:56.53906Z","time spent":"923.383199ms","remote":"127.0.0.1:33288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-28T15:48:57.462302Z","caller":"traceutil/trace.go:171","msg":"trace[223948635] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"1.230611325s","start":"2026-07-28T15:48:56.231657Z","end":"2026-07-28T15:48:57.462269Z","steps":["trace[223948635] 'process raft request' (duration: 1.230114264s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:48:57.462602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:48:56.231634Z","time spent":"1.230917362s","remote":"127.0.0.1:33566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T15:48:58.035293Z","caller":"traceutil/trace.go:171","msg":"trace[353295076] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"104.785788ms","start":"2026-07-28T15:48:57.930477Z","end":"2026-07-28T15:48:58.035263Z","steps":["trace[353295076] 'process raft request' (duration: 104.06082ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:48:59.387701Z","caller":"traceutil/trace.go:171","msg":"trace[123802730] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"364.847617ms","start":"2026-07-28T15:48:59.022815Z","end":"2026-07-28T15:48:59.387662Z","steps":["trace[123802730] 'process raft request' (duration: 363.911585ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:48:59.387963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:48:59.022785Z","time spent":"365.038261ms","remote":"127.0.0.1:33344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T15:48:59.393102Z","caller":"traceutil/trace.go:171","msg":"trace[1841648409] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"294.972921ms","start":"2026-07-28T15:48:59.09809Z","end":"2026-07-28T15:48:59.393063Z","steps":["trace[1841648409] 'process raft request' (duration: 294.592953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:49:02.208742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.482636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-07-28T15:49:02.208831Z","caller":"traceutil/trace.go:171","msg":"trace[1421532101] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:713; }","duration":"112.626349ms","start":"2026-07-28T15:49:02.096185Z","end":"2026-07-28T15:49:02.208811Z","steps":["trace[1421532101] 'range keys from in-memory index tree' (duration: 112.290072ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:49:12.532381Z","caller":"traceutil/trace.go:171","msg":"trace[272289647] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:821; }","duration":"261.845101ms","start":"2026-07-28T15:49:12.270512Z","end":"2026-07-28T15:49:12.532357Z","steps":["trace[272289647] 'read index received' (duration: 261.647556ms)","trace[272289647] 'applied index is now lower than readState.Index' (duration: 196.625µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T15:49:12.532491Z","caller":"traceutil/trace.go:171","msg":"trace[68965629] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"550.964946ms","start":"2026-07-28T15:49:11.98149Z","end":"2026-07-28T15:49:12.532455Z","steps":["trace[68965629] 'process raft request' (duration: 550.74189ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:49:12.532679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:49:11.981463Z","time spent":"551.12215ms","remote":"127.0.0.1:33474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T15:49:12.5327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.696083ms","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-07-28T15:49:12.53274Z","caller":"traceutil/trace.go:171","msg":"trace[1211017026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:798; }","duration":"260.757235ms","start":"2026-07-28T15:49:12.271972Z","end":"2026-07-28T15:49:12.532729Z","steps":["trace[1211017026] 'agreement among raft nodes before linearized reading' (duration: 260.516189ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:49:12.533307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.777142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-28T15:49:12.533391Z","caller":"traceutil/trace.go:171","msg":"trace[1813107129] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:798; }","duration":"262.912405ms","start":"2026-07-28T15:49:12.270459Z","end":"2026-07-28T15:49:12.533372Z","steps":["trace[1813107129] 'agreement among raft nodes before linearized reading' (duration: 262.002134ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:49:47.887547Z","caller":"traceutil/trace.go:171","msg":"trace[293168724] linearizableReadLoop","detail":"{readStateIndex:1048; appliedIndex:1047; }","duration":"177.693794ms","start":"2026-07-28T15:49:47.709835Z","end":"2026-07-28T15:49:47.887528Z","steps":["trace[293168724] 'read index received' (duration: 177.572141ms)","trace[293168724] 'applied index is now lower than readState.Index' (duration: 120.883µs)"],"step_count":2} {"level":"warn","ts":"2026-07-28T15:49:47.887708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.869578ms","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-07-28T15:49:47.887672Z","caller":"traceutil/trace.go:171","msg":"trace[1846256660] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"317.830301ms","start":"2026-07-28T15:49:47.569806Z","end":"2026-07-28T15:49:47.887636Z","steps":["trace[1846256660] 'process raft request' (duration: 317.603586ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:49:47.887742Z","caller":"traceutil/trace.go:171","msg":"trace[1119794413] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1014; }","duration":"177.95436ms","start":"2026-07-28T15:49:47.709779Z","end":"2026-07-28T15:49:47.887733Z","steps":["trace[1119794413] 'agreement among raft nodes before linearized reading' (duration: 177.854327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:49:47.887846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.034529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-28T15:49:47.887884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:49:47.569785Z","time spent":"317.986344ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T15:49:47.887924Z","caller":"traceutil/trace.go:171","msg":"trace[1980476560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1014; }","duration":"102.152531ms","start":"2026-07-28T15:49:47.78575Z","end":"2026-07-28T15:49:47.887902Z","steps":["trace[1980476560] 'agreement among raft nodes before linearized reading' (duration: 101.936656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:49:49.034387Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440203922454632847,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-28T15:49:49.097455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.871331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T15:49:49.097576Z","caller":"traceutil/trace.go:171","msg":"trace[2076732393] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"1.205362388s","start":"2026-07-28T15:49:47.892187Z","end":"2026-07-28T15:49:49.09755Z","steps":["trace[2076732393] 'process raft request' (duration: 562.291592ms)","trace[2076732393] 'compare' (duration: 642.261597ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T15:49:49.097668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:49:47.892155Z","time spent":"1.205467s","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T15:49:49.535314Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440203922454632847,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-28T15:49:49.548841Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.088234886s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-28T15:49:49.701321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.20433ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T15:49:49.702307Z","caller":"traceutil/trace.go:171","msg":"trace[1994560788] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1049; }","duration":"1.168322944s","start":"2026-07-28T15:49:48.53397Z","end":"2026-07-28T15:49:49.702293Z","steps":["trace[1994560788] 'read index received' (duration: 1.015022159s)","trace[1994560788] 'applied index is now lower than readState.Index' (duration: 153.300145ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T15:49:49.702376Z","caller":"traceutil/trace.go:171","msg":"trace[463488929] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"1.808874512s","start":"2026-07-28T15:49:47.893494Z","end":"2026-07-28T15:49:49.702368Z","steps":["trace[463488929] 'process raft request' (duration: 1.655540216s)","trace[463488929] 'compare' (duration: 151.984505ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T15:49:49.702422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:49:47.893458Z","time spent":"1.808937444s","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T15:49:49.702656Z","caller":"traceutil/trace.go:171","msg":"trace[1386002903] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"1.335858892s","start":"2026-07-28T15:49:48.366789Z","end":"2026-07-28T15:49:49.702648Z","steps":["trace[1386002903] 'process raft request' (duration: 1.335428552s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:49:49.7027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:49:48.366738Z","time spent":"1.335941863s","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T15:49:49.702856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.948864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-28T15:49:49.702891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.40324ms","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-07-28T15:49:49.702911Z","caller":"traceutil/trace.go:171","msg":"trace[1677795296] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1017; }","duration":"414.038895ms","start":"2026-07-28T15:49:49.288859Z","end":"2026-07-28T15:49:49.702898Z","steps":["trace[1677795296] 'agreement among raft nodes before linearized reading' (duration: 413.884512ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:49:49.70292Z","caller":"traceutil/trace.go:171","msg":"trace[102333114] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1017; }","duration":"913.456992ms","start":"2026-07-28T15:49:48.789457Z","end":"2026-07-28T15:49:49.702914Z","steps":["trace[102333114] 'agreement among raft nodes before linearized reading' (duration: 913.37257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:49:49.702945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:49:48.789442Z","time spent":"913.499222ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-28T15:49:49.70295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:49:49.288839Z","time spent":"414.102537ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-28T15:49:49.703053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169088333s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T15:49:49.703105Z","caller":"traceutil/trace.go:171","msg":"trace[1212350646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1017; }","duration":"1.169173715s","start":"2026-07-28T15:49:48.533917Z","end":"2026-07-28T15:49:49.703091Z","steps":["trace[1212350646] 'agreement among raft nodes before linearized reading' (duration: 1.169108113s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:49:49.703181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:49:48.533895Z","time spent":"1.169273877s","remote":"127.0.0.1:33288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-28T15:49:49.70333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.761256ms","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-07-28T15:49:49.703402Z","caller":"traceutil/trace.go:171","msg":"trace[256349296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1017; }","duration":"915.892958ms","start":"2026-07-28T15:49:48.787491Z","end":"2026-07-28T15:49:49.703384Z","steps":["trace[256349296] 'agreement among raft nodes before linearized reading' (duration: 915.674793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:49:49.703451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:49:48.787468Z","time spent":"915.97408ms","remote":"127.0.0.1:33492","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-07-28T15:50:13.732089Z","caller":"traceutil/trace.go:171","msg":"trace[1524902767] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1132; }","duration":"197.886385ms","start":"2026-07-28T15:50:13.534156Z","end":"2026-07-28T15:50:13.732043Z","steps":["trace[1524902767] 'read index received' (duration: 197.455595ms)","trace[1524902767] 'applied index is now lower than readState.Index' (duration: 428.84µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T15:50:13.732185Z","caller":"traceutil/trace.go:171","msg":"trace[415340709] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"491.994533ms","start":"2026-07-28T15:50:13.240151Z","end":"2026-07-28T15:50:13.732146Z","steps":["trace[415340709] 'process raft request' (duration: 491.596424ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:13.732397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.187732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-28T15:50:13.73242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:13.240135Z","time spent":"492.128376ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T15:50:13.732471Z","caller":"traceutil/trace.go:171","msg":"trace[1008771950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1094; }","duration":"198.332315ms","start":"2026-07-28T15:50:13.534125Z","end":"2026-07-28T15:50:13.732457Z","steps":["trace[1008771950] 'agreement among raft nodes before linearized reading' (duration: 198.077419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:14.232122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.143226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T15:50:14.232193Z","caller":"traceutil/trace.go:171","msg":"trace[304864990] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1094; }","duration":"269.277059ms","start":"2026-07-28T15:50:13.9629Z","end":"2026-07-28T15:50:14.232177Z","steps":["trace[304864990] 'count revisions from in-memory index tree' (duration: 269.062264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:14.232445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.534064ms","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-07-28T15:50:14.232464Z","caller":"traceutil/trace.go:171","msg":"trace[213343799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1094; }","duration":"293.591855ms","start":"2026-07-28T15:50:13.938866Z","end":"2026-07-28T15:50:14.232458Z","steps":["trace[213343799] 'range keys from in-memory index tree' (duration: 281.977884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:14.232133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.218053ms","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-07-28T15:50:14.232501Z","caller":"traceutil/trace.go:171","msg":"trace[1299302814] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1094; }","duration":"301.640953ms","start":"2026-07-28T15:50:13.930848Z","end":"2026-07-28T15:50:14.232489Z","steps":["trace[1299302814] 'range keys from in-memory index tree' (duration: 301.0607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:14.232527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:13.93082Z","time spent":"301.701846ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-28T15:50:14.232654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.983473ms","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-07-28T15:50:14.232672Z","caller":"traceutil/trace.go:171","msg":"trace[224941986] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1094; }","duration":"281.039654ms","start":"2026-07-28T15:50:13.951626Z","end":"2026-07-28T15:50:14.232666Z","steps":["trace[224941986] 'range keys from in-memory index tree' (duration: 280.888741ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:14.232461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.511889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-28T15:50:14.232722Z","caller":"traceutil/trace.go:171","msg":"trace[1496870057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1094; }","duration":"352.813057ms","start":"2026-07-28T15:50:13.879904Z","end":"2026-07-28T15:50:14.232717Z","steps":["trace[1496870057] 'range keys from in-memory index tree' (duration: 352.405977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:14.232741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:13.879881Z","time spent":"352.855689ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-28T15:50:14.232892Z","caller":"traceutil/trace.go:171","msg":"trace[1984642749] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"233.030094ms","start":"2026-07-28T15:50:13.999835Z","end":"2026-07-28T15:50:14.232865Z","steps":["trace[1984642749] 'process raft request' (duration: 219.648172ms)","trace[1984642749] 'compare' (duration: 12.565903ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T15:50:15.483057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:15.076462Z","time spent":"406.591391ms","remote":"127.0.0.1:33300","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-28T15:50:16.305039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.349631ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T15:50:16.30522Z","caller":"traceutil/trace.go:171","msg":"trace[2134913696] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"217.060802ms","start":"2026-07-28T15:50:16.088142Z","end":"2026-07-28T15:50:16.305203Z","steps":["trace[2134913696] 'process raft request' (duration: 216.99438ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:50:16.305386Z","caller":"traceutil/trace.go:171","msg":"trace[742468878] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"821.18031ms","start":"2026-07-28T15:50:15.484192Z","end":"2026-07-28T15:50:16.305373Z","steps":["trace[742468878] 'process raft request' (duration: 412.426809ms)","trace[742468878] 'compare' (duration: 408.242759ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T15:50:16.305443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:15.484168Z","time spent":"821.246141ms","remote":"127.0.0.1:33300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T15:50:16.305758Z","caller":"traceutil/trace.go:171","msg":"trace[1782842824] linearizableReadLoop","detail":"{readStateIndex:1140; appliedIndex:1139; }","duration":"731.96776ms","start":"2026-07-28T15:50:15.57378Z","end":"2026-07-28T15:50:16.305747Z","steps":["trace[1782842824] 'read index received' (duration: 321.430276ms)","trace[1782842824] 'applied index is now lower than readState.Index' (duration: 410.536244ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T15:50:16.305839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.073372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T15:50:16.305867Z","caller":"traceutil/trace.go:171","msg":"trace[835807452] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1101; }","duration":"732.157234ms","start":"2026-07-28T15:50:15.573702Z","end":"2026-07-28T15:50:16.305859Z","steps":["trace[835807452] 'agreement among raft nodes before linearized reading' (duration: 732.110753ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:16.306335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:15.573682Z","time spent":"732.630465ms","remote":"127.0.0.1:33288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-28T15:50:16.366921Z","caller":"traceutil/trace.go:171","msg":"trace[326319250] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"122.6612ms","start":"2026-07-28T15:50:16.244228Z","end":"2026-07-28T15:50:16.366889Z","steps":["trace[326319250] 'process raft request' (duration: 122.492126ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:16.367106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.092194ms","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-07-28T15:50:16.367185Z","caller":"traceutil/trace.go:171","msg":"trace[1863076800] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1102; }","duration":"281.186028ms","start":"2026-07-28T15:50:16.085981Z","end":"2026-07-28T15:50:16.367167Z","steps":["trace[1863076800] 'agreement among raft nodes before linearized reading' (duration: 281.051693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:16.367621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.060781ms","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-07-28T15:50:16.367663Z","caller":"traceutil/trace.go:171","msg":"trace[820417796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1102; }","duration":"111.103562ms","start":"2026-07-28T15:50:16.256551Z","end":"2026-07-28T15:50:16.367654Z","steps":["trace[820417796] 'agreement among raft nodes before linearized reading' (duration: 110.996189ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:16.367919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.348208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-28T15:50:16.36804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.370517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-28T15:50:16.368044Z","caller":"traceutil/trace.go:171","msg":"trace[1406099835] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1102; }","duration":"768.531123ms","start":"2026-07-28T15:50:15.599483Z","end":"2026-07-28T15:50:16.368014Z","steps":["trace[1406099835] 'agreement among raft nodes before linearized reading' (duration: 768.241976ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:50:16.368078Z","caller":"traceutil/trace.go:171","msg":"trace[93454231] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1102; }","duration":"626.446239ms","start":"2026-07-28T15:50:15.741619Z","end":"2026-07-28T15:50:16.368065Z","steps":["trace[93454231] 'agreement among raft nodes before linearized reading' (duration: 626.355367ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:16.368106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:15.599471Z","time spent":"768.623775ms","remote":"127.0.0.1:33336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-07-28T15:50:16.368109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:15.741598Z","time spent":"626.5036ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-28T15:50:16.561857Z","caller":"traceutil/trace.go:171","msg":"trace[1229882368] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"188.094106ms","start":"2026-07-28T15:50:16.37373Z","end":"2026-07-28T15:50:16.561824Z","steps":["trace[1229882368] 'process raft request' (duration: 118.772489ms)","trace[1229882368] 'compare' (duration: 69.148363ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T15:50:16.58908Z","caller":"traceutil/trace.go:171","msg":"trace[725217162] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"215.052005ms","start":"2026-07-28T15:50:16.374004Z","end":"2026-07-28T15:50:16.589056Z","steps":["trace[725217162] 'process raft request' (duration: 214.878641ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:50:16.589465Z","caller":"traceutil/trace.go:171","msg":"trace[1203033958] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"215.132047ms","start":"2026-07-28T15:50:16.374313Z","end":"2026-07-28T15:50:16.589445Z","steps":["trace[1203033958] 'process raft request' (duration: 214.704428ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:50:16.589488Z","caller":"traceutil/trace.go:171","msg":"trace[200981917] linearizableReadLoop","detail":"{readStateIndex:1147; appliedIndex:1142; }","duration":"163.589406ms","start":"2026-07-28T15:50:16.425874Z","end":"2026-07-28T15:50:16.589463Z","steps":["trace[200981917] 'read index received' (duration: 66.649525ms)","trace[200981917] 'applied index is now lower than readState.Index' (duration: 96.938511ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T15:50:16.589551Z","caller":"traceutil/trace.go:171","msg":"trace[1063528213] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"215.176478ms","start":"2026-07-28T15:50:16.374356Z","end":"2026-07-28T15:50:16.589533Z","steps":["trace[1063528213] 'process raft request' (duration: 214.926453ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:50:16.58967Z","caller":"traceutil/trace.go:171","msg":"trace[1664423651] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"215.229919ms","start":"2026-07-28T15:50:16.374431Z","end":"2026-07-28T15:50:16.589661Z","steps":["trace[1664423651] 'process raft request' (duration: 214.964033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:16.589678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.756679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T15:50:16.589734Z","caller":"traceutil/trace.go:171","msg":"trace[650932770] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1107; }","duration":"163.902863ms","start":"2026-07-28T15:50:16.425819Z","end":"2026-07-28T15:50:16.589722Z","steps":["trace[650932770] 'agreement among raft nodes before linearized reading' (duration: 163.740209ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:50:18.626901Z","caller":"traceutil/trace.go:171","msg":"trace[1632588439] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1147; }","duration":"136.609395ms","start":"2026-07-28T15:50:18.490268Z","end":"2026-07-28T15:50:18.626878Z","steps":["trace[1632588439] 'read index received' (duration: 136.341079ms)","trace[1632588439] 'applied index is now lower than readState.Index' (duration: 267.226µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T15:50:18.627073Z","caller":"traceutil/trace.go:171","msg":"trace[2627789] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"252.063447ms","start":"2026-07-28T15:50:18.374998Z","end":"2026-07-28T15:50:18.627062Z","steps":["trace[2627789] 'process raft request' (duration: 251.627768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:18.627441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.154628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T15:50:18.628368Z","caller":"traceutil/trace.go:171","msg":"trace[107860903] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1108; }","duration":"137.301412ms","start":"2026-07-28T15:50:18.490208Z","end":"2026-07-28T15:50:18.627509Z","steps":["trace[107860903] 'agreement among raft nodes before linearized reading' (duration: 137.144378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:18.944501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.135571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T15:50:18.944653Z","caller":"traceutil/trace.go:171","msg":"trace[1328829191] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"312.219302ms","start":"2026-07-28T15:50:18.632408Z","end":"2026-07-28T15:50:18.944627Z","steps":["trace[1328829191] 'process raft request' (duration: 167.799473ms)","trace[1328829191] 'compare' (duration: 143.258131ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T15:50:18.945065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:18.632382Z","time spent":"312.531238ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T15:50:18.963538Z","caller":"traceutil/trace.go:171","msg":"trace[1220162375] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"328.382537ms","start":"2026-07-28T15:50:18.635133Z","end":"2026-07-28T15:50:18.963516Z","steps":["trace[1220162375] 'process raft request' (duration: 328.349717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:18.96368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:18.635128Z","time spent":"328.48226ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T15:50:18.963763Z","caller":"traceutil/trace.go:171","msg":"trace[2122943572] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"329.942493ms","start":"2026-07-28T15:50:18.633795Z","end":"2026-07-28T15:50:18.963737Z","steps":["trace[2122943572] 'process raft request' (duration: 329.621746ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:50:18.963828Z","caller":"traceutil/trace.go:171","msg":"trace[1273438521] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"329.938893ms","start":"2026-07-28T15:50:18.63382Z","end":"2026-07-28T15:50:18.963758Z","steps":["trace[1273438521] 'process raft request' (duration: 329.631226ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:50:18.963527Z","caller":"traceutil/trace.go:171","msg":"trace[942122973] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"330.803194ms","start":"2026-07-28T15:50:18.632698Z","end":"2026-07-28T15:50:18.963501Z","steps":["trace[942122973] 'process raft request' (duration: 330.593619ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:18.963879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:18.633788Z","time spent":"330.031275ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T15:50:18.963989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:18.633811Z","time spent":"330.093936ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T15:50:18.964226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:18.632674Z","time spent":"331.235524ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T15:50:35.354397Z","caller":"traceutil/trace.go:171","msg":"trace[1495642661] linearizableReadLoop","detail":"{readStateIndex:1257; appliedIndex:1256; }","duration":"322.764792ms","start":"2026-07-28T15:50:35.03161Z","end":"2026-07-28T15:50:35.354375Z","steps":["trace[1495642661] 'read index received' (duration: 322.561537ms)","trace[1495642661] 'applied index is now lower than readState.Index' (duration: 202.425µs)"],"step_count":2} {"level":"warn","ts":"2026-07-28T15:50:35.354823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.519539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-28T15:50:35.354866Z","caller":"traceutil/trace.go:171","msg":"trace[1321556636] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:1211; }","duration":"283.5975ms","start":"2026-07-28T15:50:35.071253Z","end":"2026-07-28T15:50:35.354851Z","steps":["trace[1321556636] 'agreement among raft nodes before linearized reading' (duration: 283.455077ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:50:35.355067Z","caller":"traceutil/trace.go:171","msg":"trace[584736012] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"642.973844ms","start":"2026-07-28T15:50:34.712082Z","end":"2026-07-28T15:50:35.355056Z","steps":["trace[584736012] 'process raft request' (duration: 642.148354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:35.355031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.263814ms","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-07-28T15:50:35.355159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:34.712062Z","time spent":"643.038405ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T15:50:35.355262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.551425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-28T15:50:35.355321Z","caller":"traceutil/trace.go:171","msg":"trace[719480857] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1211; }","duration":"315.623146ms","start":"2026-07-28T15:50:35.03969Z","end":"2026-07-28T15:50:35.355313Z","steps":["trace[719480857] 'agreement among raft nodes before linearized reading' (duration: 315.506593ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:35.355373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:35.039681Z","time spent":"315.682217ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-28T15:50:35.355431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.816947ms","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-07-28T15:50:35.355474Z","caller":"traceutil/trace.go:171","msg":"trace[1576141092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1211; }","duration":"323.889968ms","start":"2026-07-28T15:50:35.031575Z","end":"2026-07-28T15:50:35.355465Z","steps":["trace[1576141092] 'agreement among raft nodes before linearized reading' (duration: 323.763656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:35.355509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:35.031549Z","time spent":"323.954199ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-28T15:50:35.355163Z","caller":"traceutil/trace.go:171","msg":"trace[1760683670] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1211; }","duration":"323.441278ms","start":"2026-07-28T15:50:35.031692Z","end":"2026-07-28T15:50:35.355133Z","steps":["trace[1760683670] 'agreement among raft nodes before linearized reading' (duration: 323.016147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:35.355677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:35.031688Z","time spent":"323.976ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-28T15:50:35.355889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.883159ms","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-07-28T15:50:35.355913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.886482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-28T15:50:35.355934Z","caller":"traceutil/trace.go:171","msg":"trace[953076272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1211; }","duration":"317.94003ms","start":"2026-07-28T15:50:35.037984Z","end":"2026-07-28T15:50:35.355924Z","steps":["trace[953076272] 'agreement among raft nodes before linearized reading' (duration: 317.831868ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:50:35.355947Z","caller":"traceutil/trace.go:171","msg":"trace[1890371818] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1211; }","duration":"318.930552ms","start":"2026-07-28T15:50:35.037007Z","end":"2026-07-28T15:50:35.355937Z","steps":["trace[1890371818] 'agreement among raft nodes before linearized reading' (duration: 318.84287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:35.355972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:35.037978Z","time spent":"317.987281ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-28T15:50:35.355977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:35.037Z","time spent":"318.970573ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-28T15:50:47.534905Z","caller":"traceutil/trace.go:171","msg":"trace[2002278164] linearizableReadLoop","detail":"{readStateIndex:1301; appliedIndex:1300; }","duration":"107.011927ms","start":"2026-07-28T15:50:47.42787Z","end":"2026-07-28T15:50:47.534882Z","steps":["trace[2002278164] 'read index received' (duration: 106.891204ms)","trace[2002278164] 'applied index is now lower than readState.Index' (duration: 119.733µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T15:50:47.534967Z","caller":"traceutil/trace.go:171","msg":"trace[928943254] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"131.044805ms","start":"2026-07-28T15:50:47.403889Z","end":"2026-07-28T15:50:47.534934Z","steps":["trace[928943254] 'process raft request' (duration: 130.861181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:47.535112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.218042ms","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-07-28T15:50:47.535139Z","caller":"traceutil/trace.go:171","msg":"trace[908357327] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1252; }","duration":"107.291043ms","start":"2026-07-28T15:50:47.42784Z","end":"2026-07-28T15:50:47.535131Z","steps":["trace[908357327] 'agreement among raft nodes before linearized reading' (duration: 107.16186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:47.535245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.060599ms","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-07-28T15:50:47.535336Z","caller":"traceutil/trace.go:171","msg":"trace[853174314] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1252; }","duration":"104.173142ms","start":"2026-07-28T15:50:47.431141Z","end":"2026-07-28T15:50:47.535314Z","steps":["trace[853174314] 'agreement among raft nodes before linearized reading' (duration: 103.917885ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:50:51.69013Z","caller":"traceutil/trace.go:171","msg":"trace[301242639] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"123.536371ms","start":"2026-07-28T15:50:51.566547Z","end":"2026-07-28T15:50:51.690083Z","steps":["trace[301242639] 'process raft request' (duration: 123.381957ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:50:51.690675Z","caller":"traceutil/trace.go:171","msg":"trace[1485092165] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1335; }","duration":"123.802947ms","start":"2026-07-28T15:50:51.566852Z","end":"2026-07-28T15:50:51.690655Z","steps":["trace[1485092165] 'read index received' (duration: 123.796827ms)","trace[1485092165] 'applied index is now lower than readState.Index' (duration: 4.67µs)"],"step_count":2} {"level":"warn","ts":"2026-07-28T15:50:51.69085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.976291ms","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-07-28T15:50:51.690887Z","caller":"traceutil/trace.go:171","msg":"trace[1870698564] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1285; }","duration":"124.041113ms","start":"2026-07-28T15:50:51.566833Z","end":"2026-07-28T15:50:51.690874Z","steps":["trace[1870698564] 'agreement among raft nodes before linearized reading' (duration: 123.91065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:52.591036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.127836ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T15:50:52.591355Z","caller":"traceutil/trace.go:171","msg":"trace[1184031419] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"1.020359244s","start":"2026-07-28T15:50:51.570973Z","end":"2026-07-28T15:50:52.591333Z","steps":["trace[1184031419] 'process raft request' (duration: 589.85996ms)","trace[1184031419] 'compare' (duration: 429.89749ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T15:50:52.591438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:51.57095Z","time spent":"1.020450257s","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T15:50:52.591475Z","caller":"traceutil/trace.go:171","msg":"trace[1463444398] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1335; }","duration":"900.719014ms","start":"2026-07-28T15:50:51.690717Z","end":"2026-07-28T15:50:52.591436Z","steps":["trace[1463444398] 'read index received' (duration: 470.062415ms)","trace[1463444398] 'applied index is now lower than readState.Index' (duration: 430.654749ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T15:50:52.591691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.018819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T15:50:52.591779Z","caller":"traceutil/trace.go:171","msg":"trace[1831924915] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1286; }","duration":"919.210934ms","start":"2026-07-28T15:50:51.672554Z","end":"2026-07-28T15:50:52.591765Z","steps":["trace[1831924915] 'agreement among raft nodes before linearized reading' (duration: 919.05243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:52.591786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.957027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-28T15:50:52.591845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02489253s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-28T15:50:52.591895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.255752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-28T15:50:52.591899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023444817s","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-07-28T15:50:52.591937Z","caller":"traceutil/trace.go:171","msg":"trace[1341849380] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1287; }","duration":"739.325843ms","start":"2026-07-28T15:50:51.852598Z","end":"2026-07-28T15:50:52.591924Z","steps":["trace[1341849380] 'agreement among raft nodes before linearized reading' (duration: 739.233861ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:50:52.591947Z","caller":"traceutil/trace.go:171","msg":"trace[910055247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1286; }","duration":"1.025028413s","start":"2026-07-28T15:50:51.566897Z","end":"2026-07-28T15:50:52.591926Z","steps":["trace[910055247] 'agreement among raft nodes before linearized reading' (duration: 1.024746955s)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:50:52.59196Z","caller":"traceutil/trace.go:171","msg":"trace[1625141692] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"896.855715ms","start":"2026-07-28T15:50:51.695089Z","end":"2026-07-28T15:50:52.591945Z","steps":["trace[1625141692] 'process raft request' (duration: 896.661051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:52.591971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:51.85255Z","time spent":"739.412255ms","remote":"127.0.0.1:58078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-07-28T15:50:52.591968Z","caller":"traceutil/trace.go:171","msg":"trace[1506283884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1286; }","duration":"1.02357993s","start":"2026-07-28T15:50:51.56837Z","end":"2026-07-28T15:50:52.59195Z","steps":["trace[1506283884] 'agreement among raft nodes before linearized reading' (duration: 1.023344574s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:52.592006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:51.566889Z","time spent":"1.025105395s","remote":"127.0.0.1:33492","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-07-28T15:50:52.592009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:51.568359Z","time spent":"1.023642832s","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-28T15:50:52.591855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:51.672536Z","time spent":"919.306897ms","remote":"127.0.0.1:33630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-07-28T15:50:52.592052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:51.69507Z","time spent":"896.925176ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T15:50:52.59188Z","caller":"traceutil/trace.go:171","msg":"trace[1939706224] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1286; }","duration":"956.093931ms","start":"2026-07-28T15:50:51.635767Z","end":"2026-07-28T15:50:52.591861Z","steps":["trace[1939706224] 'agreement among raft nodes before linearized reading' (duration: 955.800074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:52.592135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.258106ms","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":"warn","ts":"2026-07-28T15:50:52.592148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:51.635733Z","time spent":"956.402279ms","remote":"127.0.0.1:33410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-07-28T15:50:52.592176Z","caller":"traceutil/trace.go:171","msg":"trace[1193224054] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1287; }","duration":"537.300687ms","start":"2026-07-28T15:50:52.054866Z","end":"2026-07-28T15:50:52.592166Z","steps":["trace[1193224054] 'agreement among raft nodes before linearized reading' (duration: 537.239006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:52.591703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019612206s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-28T15:50:52.59221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:52.054825Z","time spent":"537.376889ms","remote":"127.0.0.1:36134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-07-28T15:50:52.592245Z","caller":"traceutil/trace.go:171","msg":"trace[103556787] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1286; }","duration":"1.020190031s","start":"2026-07-28T15:50:51.572045Z","end":"2026-07-28T15:50:52.592235Z","steps":["trace[103556787] 'agreement among raft nodes before linearized reading' (duration: 1.019540395s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:52.592283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:51.572032Z","time spent":"1.020238373s","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-28T15:50:52.592522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.438305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T15:50:52.592593Z","caller":"traceutil/trace.go:171","msg":"trace[1181116165] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1287; }","duration":"918.555578ms","start":"2026-07-28T15:50:51.674Z","end":"2026-07-28T15:50:52.592556Z","steps":["trace[1181116165] 'agreement among raft nodes before linearized reading' (duration: 918.472856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:52.592699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:51.673967Z","time spent":"918.718712ms","remote":"127.0.0.1:33418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":13,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-07-28T15:50:52.754265Z","caller":"traceutil/trace.go:171","msg":"trace[1938902937] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"156.536558ms","start":"2026-07-28T15:50:52.597706Z","end":"2026-07-28T15:50:52.754243Z","steps":["trace[1938902937] 'process raft request' (duration: 149.586946ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:50:52.754423Z","caller":"traceutil/trace.go:171","msg":"trace[285952568] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"156.499817ms","start":"2026-07-28T15:50:52.597911Z","end":"2026-07-28T15:50:52.754411Z","steps":["trace[285952568] 'process raft request' (duration: 156.26007ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:50:54.04486Z","caller":"traceutil/trace.go:171","msg":"trace[2093609326] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1341; }","duration":"306.043202ms","start":"2026-07-28T15:50:53.738795Z","end":"2026-07-28T15:50:54.044838Z","steps":["trace[2093609326] 'read index received' (duration: 305.821127ms)","trace[2093609326] 'applied index is now lower than readState.Index' (duration: 220.935µs)"],"step_count":2} {"level":"warn","ts":"2026-07-28T15:50:54.045016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.199316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T15:50:54.045064Z","caller":"traceutil/trace.go:171","msg":"trace[1918225455] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1292; }","duration":"306.312309ms","start":"2026-07-28T15:50:53.738739Z","end":"2026-07-28T15:50:54.045051Z","steps":["trace[1918225455] 'agreement among raft nodes before linearized reading' (duration: 306.195606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:54.045113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:53.738714Z","time spent":"306.38884ms","remote":"127.0.0.1:55130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-07-28T15:50:54.045103Z","caller":"traceutil/trace.go:171","msg":"trace[1811424488] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"346.960823ms","start":"2026-07-28T15:50:53.698113Z","end":"2026-07-28T15:50:54.045074Z","steps":["trace[1811424488] 'process raft request' (duration: 346.577734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:54.045272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:53.698079Z","time spent":"347.108617ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T15:50:54.78106Z","caller":"traceutil/trace.go:171","msg":"trace[1646250372] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"173.667976ms","start":"2026-07-28T15:50:54.607362Z","end":"2026-07-28T15:50:54.78103Z","steps":["trace[1646250372] 'process raft request' (duration: 99.902472ms)","trace[1646250372] 'compare' (duration: 73.642101ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T15:50:54.860674Z","caller":"traceutil/trace.go:171","msg":"trace[1088084811] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1343; }","duration":"190.691341ms","start":"2026-07-28T15:50:54.66996Z","end":"2026-07-28T15:50:54.860652Z","steps":["trace[1088084811] 'read index received' (duration: 37.42652ms)","trace[1088084811] 'applied index is now lower than readState.Index' (duration: 153.263631ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T15:50:54.860986Z","caller":"traceutil/trace.go:171","msg":"trace[1371803603] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"253.034461ms","start":"2026-07-28T15:50:54.607938Z","end":"2026-07-28T15:50:54.860973Z","steps":["trace[1371803603] 'process raft request' (duration: 252.56186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:54.861284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.295677ms","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-07-28T15:50:54.86135Z","caller":"traceutil/trace.go:171","msg":"trace[576595282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1295; }","duration":"191.422379ms","start":"2026-07-28T15:50:54.669916Z","end":"2026-07-28T15:50:54.861338Z","steps":["trace[576595282] 'agreement among raft nodes before linearized reading' (duration: 191.233284ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:50:54.920477Z","caller":"traceutil/trace.go:171","msg":"trace[984198159] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"108.764668ms","start":"2026-07-28T15:50:54.811692Z","end":"2026-07-28T15:50:54.920457Z","steps":["trace[984198159] 'process raft request' (duration: 77.317397ms)","trace[984198159] 'compare' (duration: 31.114963ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T15:50:54.920762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.015735ms","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-07-28T15:50:54.920812Z","caller":"traceutil/trace.go:171","msg":"trace[1959367801] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1297; }","duration":"236.121178ms","start":"2026-07-28T15:50:54.68468Z","end":"2026-07-28T15:50:54.920801Z","steps":["trace[1959367801] 'agreement among raft nodes before linearized reading' (duration: 236.000175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:54.920994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.997565ms","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-07-28T15:50:54.921023Z","caller":"traceutil/trace.go:171","msg":"trace[717608810] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1297; }","duration":"162.064207ms","start":"2026-07-28T15:50:54.758951Z","end":"2026-07-28T15:50:54.921015Z","steps":["trace[717608810] 'agreement among raft nodes before linearized reading' (duration: 161.923803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:54.92106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.980994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-28T15:50:54.921174Z","caller":"traceutil/trace.go:171","msg":"trace[142297398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1297; }","duration":"162.155969ms","start":"2026-07-28T15:50:54.758993Z","end":"2026-07-28T15:50:54.921148Z","steps":["trace[142297398] 'agreement among raft nodes before linearized reading' (duration: 161.77773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:55.846101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.348433ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-28T15:50:55.846586Z","caller":"traceutil/trace.go:171","msg":"trace[819886563] linearizableReadLoop","detail":"{readStateIndex:1353; appliedIndex:1351; }","duration":"775.308798ms","start":"2026-07-28T15:50:55.071267Z","end":"2026-07-28T15:50:55.846575Z","steps":["trace[819886563] 'read index received' (duration: 248.448924ms)","trace[819886563] 'applied index is now lower than readState.Index' (duration: 526.859254ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T15:50:55.84687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.585484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-28T15:50:55.846908Z","caller":"traceutil/trace.go:171","msg":"trace[349563552] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:1303; }","duration":"775.684257ms","start":"2026-07-28T15:50:55.071216Z","end":"2026-07-28T15:50:55.8469Z","steps":["trace[349563552] 'agreement among raft nodes before linearized reading' (duration: 775.490112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:55.846931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:55.071195Z","time spent":"775.730908ms","remote":"127.0.0.1:33300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.144\" "} {"level":"info","ts":"2026-07-28T15:50:55.846587Z","caller":"traceutil/trace.go:171","msg":"trace[1642638470] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"876.90257ms","start":"2026-07-28T15:50:54.96966Z","end":"2026-07-28T15:50:55.846562Z","steps":["trace[1642638470] 'process raft request' (duration: 350.046115ms)","trace[1642638470] 'compare' (duration: 525.558305ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T15:50:55.847464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:54.969641Z","time spent":"877.797492ms","remote":"127.0.0.1:33330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T15:50:55.846651Z","caller":"traceutil/trace.go:171","msg":"trace[633730144] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"873.995752ms","start":"2026-07-28T15:50:54.972634Z","end":"2026-07-28T15:50:55.84663Z","steps":["trace[633730144] 'process raft request' (duration: 873.542332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:55.847619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:54.972623Z","time spent":"874.924475ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T15:50:55.847698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.77651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T15:50:55.84779Z","caller":"traceutil/trace.go:171","msg":"trace[1774920202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1303; }","duration":"312.896823ms","start":"2026-07-28T15:50:55.534848Z","end":"2026-07-28T15:50:55.847745Z","steps":["trace[1774920202] 'agreement among raft nodes before linearized reading' (duration: 312.045723ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:50:55.847812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:50:55.534826Z","time spent":"312.981934ms","remote":"127.0.0.1:33288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-28T15:50:56.18652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.230996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T15:50:56.18702Z","caller":"traceutil/trace.go:171","msg":"trace[604211913] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1304; }","duration":"213.42198ms","start":"2026-07-28T15:50:55.973177Z","end":"2026-07-28T15:50:56.186599Z","steps":["trace[604211913] 'count revisions from in-memory index tree' (duration: 213.161663ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:50:56.187184Z","caller":"traceutil/trace.go:171","msg":"trace[847537324] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"132.237563ms","start":"2026-07-28T15:50:56.054927Z","end":"2026-07-28T15:50:56.187165Z","steps":["trace[847537324] 'process raft request' (duration: 55.609473ms)","trace[847537324] 'compare' (duration: 76.303813ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T15:51:05.396641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.634054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T15:51:05.396801Z","caller":"traceutil/trace.go:171","msg":"trace[1998758288] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"424.467705ms","start":"2026-07-28T15:51:04.972306Z","end":"2026-07-28T15:51:05.396774Z","steps":["trace[1998758288] 'process raft request' (duration: 19.529904ms)","trace[1998758288] 'compare' (duration: 404.4547ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T15:51:05.396883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:51:04.972255Z","time spent":"424.594578ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T15:51:05.404817Z","caller":"traceutil/trace.go:171","msg":"trace[1260871158] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"430.151147ms","start":"2026-07-28T15:51:04.974631Z","end":"2026-07-28T15:51:05.404782Z","steps":["trace[1260871158] 'process raft request' (duration: 429.979653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:51:05.405066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.034494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-28T15:51:05.405086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:51:04.974611Z","time spent":"430.29582ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T15:51:05.405119Z","caller":"traceutil/trace.go:171","msg":"trace[197788817] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1373; }","duration":"393.133836ms","start":"2026-07-28T15:51:05.011973Z","end":"2026-07-28T15:51:05.405107Z","steps":["trace[197788817] 'agreement among raft nodes before linearized reading' (duration: 392.957132ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:51:05.405152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:51:05.01195Z","time spent":"393.194888ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-28T15:51:05.405322Z","caller":"traceutil/trace.go:171","msg":"trace[33181950] linearizableReadLoop","detail":"{readStateIndex:1425; appliedIndex:1424; }","duration":"392.676906ms","start":"2026-07-28T15:51:05.012029Z","end":"2026-07-28T15:51:05.404706Z","steps":["trace[33181950] 'read index received' (duration: 392.457001ms)","trace[33181950] 'applied index is now lower than readState.Index' (duration: 218.355µs)"],"step_count":2} {"level":"warn","ts":"2026-07-28T15:51:05.40556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.615423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-28T15:51:05.405594Z","caller":"traceutil/trace.go:171","msg":"trace[210528803] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:1373; }","duration":"333.704845ms","start":"2026-07-28T15:51:05.071879Z","end":"2026-07-28T15:51:05.405584Z","steps":["trace[210528803] 'agreement among raft nodes before linearized reading' (duration: 333.552622ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:51:05.405619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:51:05.071853Z","time spent":"333.760638ms","remote":"127.0.0.1:33300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.144\" "} {"level":"info","ts":"2026-07-28T15:51:05.661939Z","caller":"traceutil/trace.go:171","msg":"trace[183498389] linearizableReadLoop","detail":"{readStateIndex:1427; appliedIndex:1425; }","duration":"127.660016ms","start":"2026-07-28T15:51:05.534254Z","end":"2026-07-28T15:51:05.661914Z","steps":["trace[183498389] 'read index received' (duration: 124.075473ms)","trace[183498389] 'applied index is now lower than readState.Index' (duration: 3.583413ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T15:51:05.662135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.865812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T15:51:05.662199Z","caller":"traceutil/trace.go:171","msg":"trace[427074462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1374; }","duration":"127.999504ms","start":"2026-07-28T15:51:05.534188Z","end":"2026-07-28T15:51:05.662188Z","steps":["trace[427074462] 'agreement among raft nodes before linearized reading' (duration: 127.893352ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:51:05.662235Z","caller":"traceutil/trace.go:171","msg":"trace[641416463] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"252.336575ms","start":"2026-07-28T15:51:05.409865Z","end":"2026-07-28T15:51:05.662202Z","steps":["trace[641416463] 'process raft request' (duration: 251.845123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:51:06.391164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.356333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T15:51:06.391247Z","caller":"traceutil/trace.go:171","msg":"trace[1159180516] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1376; }","duration":"356.510506ms","start":"2026-07-28T15:51:06.034717Z","end":"2026-07-28T15:51:06.391228Z","steps":["trace[1159180516] 'count revisions from in-memory index tree' (duration: 356.26957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:51:06.391315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.595591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-07-28T15:51:06.391291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:51:06.034694Z","time spent":"356.585977ms","remote":"127.0.0.1:33544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-07-28T15:51:06.39149Z","caller":"traceutil/trace.go:171","msg":"trace[786153918] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1376; }","duration":"530.763106ms","start":"2026-07-28T15:51:05.860596Z","end":"2026-07-28T15:51:06.391359Z","steps":["trace[786153918] 'range keys from in-memory index tree' (duration: 530.408967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T15:51:06.391533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:51:05.860536Z","time spent":"530.981721ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-28T15:51:06.546581Z","caller":"traceutil/trace.go:171","msg":"trace[2130227787] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"280.810245ms","start":"2026-07-28T15:51:06.265749Z","end":"2026-07-28T15:51:06.546559Z","steps":["trace[2130227787] 'process raft request' (duration: 280.663862ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:51:06.548494Z","caller":"traceutil/trace.go:171","msg":"trace[2034558235] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"152.652638ms","start":"2026-07-28T15:51:06.395819Z","end":"2026-07-28T15:51:06.548472Z","steps":["trace[2034558235] 'process raft request' (duration: 152.534555ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:55:06.368391Z","caller":"traceutil/trace.go:171","msg":"trace[1109748607] transaction","detail":"{read_only:false; response_revision:2499; number_of_response:1; }","duration":"105.99471ms","start":"2026-07-28T15:55:06.262368Z","end":"2026-07-28T15:55:06.368363Z","steps":["trace[1109748607] 'process raft request' (duration: 105.855656ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T15:55:15.021154Z","caller":"traceutil/trace.go:171","msg":"trace[1084425059] linearizableReadLoop","detail":"{readStateIndex:2647; appliedIndex:2646; }","duration":"165.928543ms","start":"2026-07-28T15:55:14.855186Z","end":"2026-07-28T15:55:15.021115Z","steps":["trace[1084425059] 'read index received' (duration: 155.091485ms)","trace[1084425059] 'applied index is now lower than readState.Index' (duration: 10.835808ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T15:55:15.02122Z","caller":"traceutil/trace.go:171","msg":"trace[818800956] transaction","detail":"{read_only:false; response_revision:2544; number_of_response:1; }","duration":"418.683887ms","start":"2026-07-28T15:55:14.602478Z","end":"2026-07-28T15:55:15.021162Z","steps":["trace[818800956] 'process raft request' (duration: 407.783757ms)","trace[818800956] 'compare' (duration: 10.659035ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T15:55:15.021409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T15:55:14.602465Z","time spent":"418.83806ms","remote":"127.0.0.1:33492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T15:55:15.021438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.246491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-28T15:55:15.021552Z","caller":"traceutil/trace.go:171","msg":"trace[1997950580] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2544; }","duration":"166.410635ms","start":"2026-07-28T15:55:14.855127Z","end":"2026-07-28T15:55:15.021537Z","steps":["trace[1997950580] 'agreement among raft nodes before linearized reading' (duration: 166.092068ms)"],"step_count":1}