{"level":"warn","ts":"2026-03-09T06:15:25.906105Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-09T06:15:25.906226Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.52:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.52:2380","--initial-cluster=instance=https://162.253.55.52:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.52:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.52: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-03-09T06:15:25.906315Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-09T06:15:25.906332Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-03-09T06:15:25.906357Z","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-03-09T06:15:25.907384Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"]} {"level":"info","ts":"2026-03-09T06:15:25.907511Z","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://162.253.55.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.52: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-03-09T06:15:25.913658Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.72083ms"} {"level":"info","ts":"2026-03-09T06:15:25.929118Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b11e7fc5714be64b","cluster-id":"9349e4f32a52a90a"} {"level":"info","ts":"2026-03-09T06:15:25.929503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=()"} {"level":"info","ts":"2026-03-09T06:15:25.929549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 0"} {"level":"info","ts":"2026-03-09T06:15:25.929563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b11e7fc5714be64b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-09T06:15:25.929583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 1"} {"level":"info","ts":"2026-03-09T06:15:25.929663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"warn","ts":"2026-03-09T06:15:25.938644Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-09T06:15:25.940106Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-09T06:15:25.941424Z","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-03-09T06:15:25.943728Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b11e7fc5714be64b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-09T06:15:25.944335Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b11e7fc5714be64b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-09T06:15:25.944468Z","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-03-09T06:15:25.944666Z","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-03-09T06:15:25.944686Z","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-03-09T06:15:25.94814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"info","ts":"2026-03-09T06:15:25.948688Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","added-peer-id":"b11e7fc5714be64b","added-peer-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-03-09T06:15:25.951112Z","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-03-09T06:15:25.951404Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b11e7fc5714be64b","initial-advertise-peer-urls":["https://162.253.55.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-09T06:15:25.951439Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-09T06:15:25.951539Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-03-09T06:15:25.951557Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-03-09T06:15:26.03048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b is starting a new election at term 1"} {"level":"info","ts":"2026-03-09T06:15:26.030542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became pre-candidate at term 1"} {"level":"info","ts":"2026-03-09T06:15:26.030569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgPreVoteResp from b11e7fc5714be64b at term 1"} {"level":"info","ts":"2026-03-09T06:15:26.030585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became candidate at term 2"} {"level":"info","ts":"2026-03-09T06:15:26.030597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgVoteResp from b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-03-09T06:15:26.030645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became leader at term 2"} {"level":"info","ts":"2026-03-09T06:15:26.030658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b11e7fc5714be64b elected leader b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-03-09T06:15:26.031893Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T06:15:26.032922Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-09T06:15:26.032888Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b11e7fc5714be64b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.52:2379]}","request-path":"/0/members/b11e7fc5714be64b/attributes","cluster-id":"9349e4f32a52a90a","publish-timeout":"7s"} {"level":"info","ts":"2026-03-09T06:15:26.033172Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T06:15:26.033257Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T06:15:26.033284Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T06:15:26.033301Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-09T06:15:26.034025Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-09T06:15:26.034066Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-09T06:15:26.035285Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-09T06:15:26.035285Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.52:2379"} {"level":"info","ts":"2026-03-09T06:15:54.056692Z","caller":"traceutil/trace.go:171","msg":"trace[790092251] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"132.132016ms","start":"2026-03-09T06:15:53.924531Z","end":"2026-03-09T06:15:54.056663Z","steps":["trace[790092251] 'process raft request' (duration: 61.968006ms)","trace[790092251] 'compare' (duration: 70.043657ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:15:55.03471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.716329ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-09T06:15:55.900993Z","caller":"traceutil/trace.go:171","msg":"trace[2112264963] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:488; }","duration":"117.343409ms","start":"2026-03-09T06:15:55.783626Z","end":"2026-03-09T06:15:55.900969Z","steps":["trace[2112264963] 'read index received' (duration: 117.164975ms)","trace[2112264963] 'applied index is now lower than readState.Index' (duration: 177.404µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:15:55.90107Z","caller":"traceutil/trace.go:171","msg":"trace[1805638824] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"148.801163ms","start":"2026-03-09T06:15:55.752239Z","end":"2026-03-09T06:15:55.90104Z","steps":["trace[1805638824] 'process raft request' (duration: 148.575868ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:15:55.901308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.654167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-09T06:15:55.901358Z","caller":"traceutil/trace.go:171","msg":"trace[1756319111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:473; }","duration":"117.743619ms","start":"2026-03-09T06:15:55.783601Z","end":"2026-03-09T06:15:55.901345Z","steps":["trace[1756319111] 'agreement among raft nodes before linearized reading' (duration: 117.509243ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:15:58.564544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.209365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:15:58.564637Z","caller":"traceutil/trace.go:171","msg":"trace[732199431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:481; }","duration":"379.37911ms","start":"2026-03-09T06:15:58.18524Z","end":"2026-03-09T06:15:58.564619Z","steps":["trace[732199431] 'range keys from in-memory index tree' (duration: 379.118124ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:15:58.564694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:15:58.185225Z","time spent":"379.449611ms","remote":"127.0.0.1:58578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-09T06:16:02.121911Z","caller":"traceutil/trace.go:171","msg":"trace[240190115] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:507; }","duration":"193.901606ms","start":"2026-03-09T06:16:01.927984Z","end":"2026-03-09T06:16:02.121886Z","steps":["trace[240190115] 'read index received' (duration: 193.66414ms)","trace[240190115] 'applied index is now lower than readState.Index' (duration: 236.256µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:16:02.122086Z","caller":"traceutil/trace.go:171","msg":"trace[60894899] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"233.031651ms","start":"2026-03-09T06:16:01.88897Z","end":"2026-03-09T06:16:02.122002Z","steps":["trace[60894899] 'process raft request' (duration: 232.762255ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:16:02.122149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.183832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-09T06:16:02.12235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.448751ms","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-03-09T06:16:02.122421Z","caller":"traceutil/trace.go:171","msg":"trace[1922070170] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:491; }","duration":"180.548333ms","start":"2026-03-09T06:16:01.94185Z","end":"2026-03-09T06:16:02.122398Z","steps":["trace[1922070170] 'agreement among raft nodes before linearized reading' (duration: 180.387849ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:16:02.122361Z","caller":"traceutil/trace.go:171","msg":"trace[1265753794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:491; }","duration":"194.434518ms","start":"2026-03-09T06:16:01.927901Z","end":"2026-03-09T06:16:02.122335Z","steps":["trace[1265753794] 'agreement among raft nodes before linearized reading' (duration: 194.116341ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:16:02.122906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.545967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-09T06:16:02.122944Z","caller":"traceutil/trace.go:171","msg":"trace[846210524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:491; }","duration":"182.618739ms","start":"2026-03-09T06:16:01.940315Z","end":"2026-03-09T06:16:02.122934Z","steps":["trace[846210524] 'agreement among raft nodes before linearized reading' (duration: 181.875532ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:16:02.428812Z","caller":"traceutil/trace.go:171","msg":"trace[791267312] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"301.952744ms","start":"2026-03-09T06:16:02.12683Z","end":"2026-03-09T06:16:02.428783Z","steps":["trace[791267312] 'process raft request' (duration: 242.63689ms)","trace[791267312] 'compare' (duration: 58.907005ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:16:02.42884Z","caller":"traceutil/trace.go:171","msg":"trace[254568672] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"301.917204ms","start":"2026-03-09T06:16:02.126892Z","end":"2026-03-09T06:16:02.428809Z","steps":["trace[254568672] 'process raft request' (duration: 301.787381ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:16:02.428955Z","caller":"traceutil/trace.go:171","msg":"trace[25620130] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:508; }","duration":"296.519291ms","start":"2026-03-09T06:16:02.132374Z","end":"2026-03-09T06:16:02.428893Z","steps":["trace[25620130] 'read index received' (duration: 237.107225ms)","trace[25620130] 'applied index is now lower than readState.Index' (duration: 59.410856ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:16:02.428972Z","caller":"traceutil/trace.go:171","msg":"trace[1618669587] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"301.979535ms","start":"2026-03-09T06:16:02.126973Z","end":"2026-03-09T06:16:02.428952Z","steps":["trace[1618669587] 'process raft request' (duration: 301.786421ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:16:02.429098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.715936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-09T06:16:02.429107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.808756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:16:02.429142Z","caller":"traceutil/trace.go:171","msg":"trace[1195848215] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:494; }","duration":"296.791947ms","start":"2026-03-09T06:16:02.13234Z","end":"2026-03-09T06:16:02.429132Z","steps":["trace[1195848215] 'agreement among raft nodes before linearized reading' (duration: 296.692265ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:16:02.429147Z","caller":"traceutil/trace.go:171","msg":"trace[988518668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:494; }","duration":"243.908948ms","start":"2026-03-09T06:16:02.185227Z","end":"2026-03-09T06:16:02.429136Z","steps":["trace[988518668] 'agreement among raft nodes before linearized reading' (duration: 243.831577ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:16:02.429409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:16:02.126808Z","time spent":"302.070867ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:16:02.429462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:16:02.126965Z","time spent":"302.094738ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:16:02.429451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:16:02.126869Z","time spent":"302.066677ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T06:16:05.314327Z","caller":"traceutil/trace.go:171","msg":"trace[467866634] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"310.888737ms","start":"2026-03-09T06:16:05.003408Z","end":"2026-03-09T06:16:05.314296Z","steps":["trace[467866634] 'process raft request' (duration: 271.599236ms)","trace[467866634] 'compare' (duration: 38.81721ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:16:05.31448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:16:05.003391Z","time spent":"311.012229ms","remote":"127.0.0.1:58626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4318,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T06:16:05.331791Z","caller":"traceutil/trace.go:171","msg":"trace[1167213945] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"324.438454ms","start":"2026-03-09T06:16:05.007324Z","end":"2026-03-09T06:16:05.331763Z","steps":["trace[1167213945] 'process raft request' (duration: 324.217798ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:16:05.331909Z","caller":"traceutil/trace.go:171","msg":"trace[235878956] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:538; }","duration":"145.849807ms","start":"2026-03-09T06:16:05.186036Z","end":"2026-03-09T06:16:05.331886Z","steps":["trace[235878956] 'read index received' (duration: 88.985047ms)","trace[235878956] 'applied index is now lower than readState.Index' (duration: 56.86366ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:16:05.332003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:16:05.007309Z","time spent":"324.597897ms","remote":"127.0.0.1:58626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5562,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T06:16:05.332062Z","caller":"traceutil/trace.go:171","msg":"trace[1506640985] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"153.874458ms","start":"2026-03-09T06:16:05.178177Z","end":"2026-03-09T06:16:05.332051Z","steps":["trace[1506640985] 'process raft request' (duration: 153.488718ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:16:05.332021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.97696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:16:05.332215Z","caller":"traceutil/trace.go:171","msg":"trace[107355770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"146.088162ms","start":"2026-03-09T06:16:05.186029Z","end":"2026-03-09T06:16:05.332117Z","steps":["trace[107355770] 'agreement among raft nodes before linearized reading' (duration: 145.934789ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:16:23.412394Z","caller":"traceutil/trace.go:171","msg":"trace[50038749] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"823.614168ms","start":"2026-03-09T06:16:22.58875Z","end":"2026-03-09T06:16:23.412364Z","steps":["trace[50038749] 'process raft request' (duration: 823.459895ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:16:23.412621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:16:22.588735Z","time spent":"823.752171ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:16:24.354352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.24855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T06:16:24.354469Z","caller":"traceutil/trace.go:171","msg":"trace[1835152317] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:700; }","duration":"1.27414278s","start":"2026-03-09T06:16:23.080305Z","end":"2026-03-09T06:16:24.354447Z","steps":["trace[1835152317] 'read index received' (duration: 331.936154ms)","trace[1835152317] 'applied index is now lower than readState.Index' (duration: 942.205466ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:16:24.354704Z","caller":"traceutil/trace.go:171","msg":"trace[977160842] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"1.76520871s","start":"2026-03-09T06:16:22.58948Z","end":"2026-03-09T06:16:24.354688Z","steps":["trace[977160842] 'process raft request' (duration: 1.050543802s)","trace[977160842] 'compare' (duration: 714.147717ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:16:24.354764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:16:22.589462Z","time spent":"1.765275552s","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:16:24.35481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.274493338s","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-03-09T06:16:24.354827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170350098s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:16:24.35493Z","caller":"traceutil/trace.go:171","msg":"trace[1703888543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:679; }","duration":"1.170489041s","start":"2026-03-09T06:16:23.184416Z","end":"2026-03-09T06:16:24.354905Z","steps":["trace[1703888543] 'agreement among raft nodes before linearized reading' (duration: 1.170341298s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:16:24.354986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:16:23.184398Z","time spent":"1.170580453s","remote":"127.0.0.1:58578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-09T06:16:24.354864Z","caller":"traceutil/trace.go:171","msg":"trace[1944570767] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:679; }","duration":"1.27458444s","start":"2026-03-09T06:16:23.080265Z","end":"2026-03-09T06:16:24.354849Z","steps":["trace[1944570767] 'agreement among raft nodes before linearized reading' (duration: 1.274420336s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:16:24.355198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:16:23.080244Z","time spent":"1.274943438s","remote":"127.0.0.1:58858","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-03-09T06:16:24.93331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.476127ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-09T06:16:24.933418Z","caller":"traceutil/trace.go:171","msg":"trace[1678085649] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:703; }","duration":"361.476943ms","start":"2026-03-09T06:16:24.571925Z","end":"2026-03-09T06:16:24.933402Z","steps":["trace[1678085649] 'read index received' (duration: 65.152µs)","trace[1678085649] 'applied index is now lower than readState.Index' (duration: 361.410231ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:16:24.933784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.92539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-09T06:16:24.93376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.815611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-09T06:16:24.933905Z","caller":"traceutil/trace.go:171","msg":"trace[1204942373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:680; }","duration":"346.044983ms","start":"2026-03-09T06:16:24.587788Z","end":"2026-03-09T06:16:24.933833Z","steps":["trace[1204942373] 'agreement among raft nodes before linearized reading' (duration: 345.835328ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:16:24.933929Z","caller":"traceutil/trace.go:171","msg":"trace[597899075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:680; }","duration":"362.044975ms","start":"2026-03-09T06:16:24.571843Z","end":"2026-03-09T06:16:24.933888Z","steps":["trace[597899075] 'agreement among raft nodes before linearized reading' (duration: 361.699308ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:16:24.933954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:16:24.587776Z","time spent":"346.167477ms","remote":"127.0.0.1:58858","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-03-09T06:16:24.933992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:16:24.571815Z","time spent":"362.165688ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-09T06:16:26.007516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.908378ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T06:16:26.007628Z","caller":"traceutil/trace.go:171","msg":"trace[487551486] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:705; }","duration":"589.789077ms","start":"2026-03-09T06:16:25.417822Z","end":"2026-03-09T06:16:26.007611Z","steps":["trace[487551486] 'read index received' (duration: 26.64µs)","trace[487551486] 'applied index is now lower than readState.Index' (duration: 589.761547ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:16:26.007705Z","caller":"traceutil/trace.go:171","msg":"trace[160311627] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"1.069602094s","start":"2026-03-09T06:16:24.938074Z","end":"2026-03-09T06:16:26.007676Z","steps":["trace[160311627] 'process raft request' (duration: 165.4503ms)","trace[160311627] 'compare' (duration: 903.630462ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:16:26.007868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:16:24.938055Z","time spent":"1.069725646s","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:16:26.007746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.91413ms","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-03-09T06:16:26.008048Z","caller":"traceutil/trace.go:171","msg":"trace[109090475] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:682; }","duration":"590.254718ms","start":"2026-03-09T06:16:25.417783Z","end":"2026-03-09T06:16:26.008038Z","steps":["trace[109090475] 'agreement among raft nodes before linearized reading' (duration: 589.867679ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:16:26.008077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:16:25.417743Z","time spent":"590.32485ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-09T06:16:26.274393Z","caller":"traceutil/trace.go:171","msg":"trace[337983129] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"260.496254ms","start":"2026-03-09T06:16:26.013862Z","end":"2026-03-09T06:16:26.274358Z","steps":["trace[337983129] 'process raft request' (duration: 260.31555ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:16:26.650372Z","caller":"traceutil/trace.go:171","msg":"trace[101424130] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"248.85427ms","start":"2026-03-09T06:16:26.401487Z","end":"2026-03-09T06:16:26.650342Z","steps":["trace[101424130] 'process raft request' (duration: 240.384088ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:16:27.425601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.136039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-09T06:16:27.425699Z","caller":"traceutil/trace.go:171","msg":"trace[440865241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:685; }","duration":"426.291193ms","start":"2026-03-09T06:16:26.999385Z","end":"2026-03-09T06:16:27.425676Z","steps":["trace[440865241] 'range keys from in-memory index tree' (duration: 425.907723ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:16:27.425768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:16:26.999364Z","time spent":"426.392074ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-09T06:16:27.425798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.553523ms","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-03-09T06:16:27.425879Z","caller":"traceutil/trace.go:171","msg":"trace[438835834] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:685; }","duration":"383.671527ms","start":"2026-03-09T06:16:27.042185Z","end":"2026-03-09T06:16:27.425857Z","steps":["trace[438835834] 'count revisions from in-memory index tree' (duration: 383.494682ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:16:27.425927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:16:27.042174Z","time spent":"383.739968ms","remote":"127.0.0.1:58694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":12,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-03-09T06:16:27.425873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.134323ms","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-03-09T06:16:27.425985Z","caller":"traceutil/trace.go:171","msg":"trace[1356320910] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:685; }","duration":"229.303727ms","start":"2026-03-09T06:16:27.196657Z","end":"2026-03-09T06:16:27.425961Z","steps":["trace[1356320910] 'count revisions from in-memory index tree' (duration: 229.046491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:16:27.425842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.59369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:16:27.426064Z","caller":"traceutil/trace.go:171","msg":"trace[389376688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:685; }","duration":"239.902587ms","start":"2026-03-09T06:16:27.186145Z","end":"2026-03-09T06:16:27.426048Z","steps":["trace[389376688] 'range keys from in-memory index tree' (duration: 239.446207ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:16:27.541437Z","caller":"traceutil/trace.go:171","msg":"trace[1788582193] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"111.114819ms","start":"2026-03-09T06:16:27.430293Z","end":"2026-03-09T06:16:27.541408Z","steps":["trace[1788582193] 'process raft request' (duration: 110.984216ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:16:28.338485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.120166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-09T06:16:28.338599Z","caller":"traceutil/trace.go:171","msg":"trace[304145775] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:687; }","duration":"155.28652ms","start":"2026-03-09T06:16:28.18328Z","end":"2026-03-09T06:16:28.338567Z","steps":["trace[304145775] 'count revisions from in-memory index tree' (duration: 155.039084ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:16:28.523508Z","caller":"traceutil/trace.go:171","msg":"trace[2122054821] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"120.081962ms","start":"2026-03-09T06:16:28.403383Z","end":"2026-03-09T06:16:28.523465Z","steps":["trace[2122054821] 'process raft request' (duration: 119.468958ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:16:46.586935Z","caller":"traceutil/trace.go:171","msg":"trace[545591771] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"101.141969ms","start":"2026-03-09T06:16:46.485778Z","end":"2026-03-09T06:16:46.58692Z","steps":["trace[545591771] 'process raft request' (duration: 101.067927ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:16:46.946371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.073773ms","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-03-09T06:16:46.946486Z","caller":"traceutil/trace.go:171","msg":"trace[836167125] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:746; }","duration":"202.227497ms","start":"2026-03-09T06:16:46.744237Z","end":"2026-03-09T06:16:46.946465Z","steps":["trace[836167125] 'range keys from in-memory index tree' (duration: 201.883998ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:16:47.204671Z","caller":"traceutil/trace.go:171","msg":"trace[454159042] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"254.034794ms","start":"2026-03-09T06:16:46.950596Z","end":"2026-03-09T06:16:47.204631Z","steps":["trace[454159042] 'process raft request' (duration: 253.744828ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:16:48.850751Z","caller":"traceutil/trace.go:171","msg":"trace[1408968567] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:778; }","duration":"217.061452ms","start":"2026-03-09T06:16:48.633657Z","end":"2026-03-09T06:16:48.850719Z","steps":["trace[1408968567] 'read index received' (duration: 216.944259ms)","trace[1408968567] 'applied index is now lower than readState.Index' (duration: 116.053µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:16:48.850936Z","caller":"traceutil/trace.go:171","msg":"trace[1063031221] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"255.59007ms","start":"2026-03-09T06:16:48.595304Z","end":"2026-03-09T06:16:48.850894Z","steps":["trace[1063031221] 'process raft request' (duration: 255.279582ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:16:48.851173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.420639ms","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-03-09T06:16:48.851221Z","caller":"traceutil/trace.go:171","msg":"trace[567352911] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:750; }","duration":"217.599505ms","start":"2026-03-09T06:16:48.63361Z","end":"2026-03-09T06:16:48.851209Z","steps":["trace[567352911] 'agreement among raft nodes before linearized reading' (duration: 217.219275ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:16:49.039172Z","caller":"traceutil/trace.go:171","msg":"trace[1724429374] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"242.572227ms","start":"2026-03-09T06:16:48.796567Z","end":"2026-03-09T06:16:49.03914Z","steps":["trace[1724429374] 'process raft request' (duration: 233.808513ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:16:49.049392Z","caller":"traceutil/trace.go:171","msg":"trace[1054621376] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"193.756538ms","start":"2026-03-09T06:16:48.855617Z","end":"2026-03-09T06:16:49.049373Z","steps":["trace[1054621376] 'process raft request' (duration: 193.641786ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:16:49.356671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.590448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.52\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-09T06:16:49.356747Z","caller":"traceutil/trace.go:171","msg":"trace[1155459587] range","detail":"{range_begin:/registry/masterleases/162.253.55.52; range_end:; response_count:1; response_revision:752; }","duration":"176.71036ms","start":"2026-03-09T06:16:49.180017Z","end":"2026-03-09T06:16:49.356728Z","steps":["trace[1155459587] 'range keys from in-memory index tree' (duration: 176.397614ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:16:49.356751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.137035ms","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-03-09T06:16:49.356809Z","caller":"traceutil/trace.go:171","msg":"trace[549975633] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:752; }","duration":"148.241837ms","start":"2026-03-09T06:16:49.208551Z","end":"2026-03-09T06:16:49.356793Z","steps":["trace[549975633] 'range keys from in-memory index tree' (duration: 147.95941ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:16:49.35665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.543044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:16:49.356988Z","caller":"traceutil/trace.go:171","msg":"trace[1352191114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:752; }","duration":"172.900883ms","start":"2026-03-09T06:16:49.18405Z","end":"2026-03-09T06:16:49.35695Z","steps":["trace[1352191114] 'range keys from in-memory index tree' (duration: 172.474032ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:03.685459Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529674453053527,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-09T06:17:04.186092Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529674453053527,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-09T06:17:04.394412Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.278156846s","expected-duration":"1s"} {"level":"info","ts":"2026-03-09T06:17:04.394759Z","caller":"traceutil/trace.go:171","msg":"trace[1308054709] linearizableReadLoop","detail":"{readStateIndex:877; appliedIndex:876; }","duration":"1.209631968s","start":"2026-03-09T06:17:03.185102Z","end":"2026-03-09T06:17:04.394734Z","steps":["trace[1308054709] 'read index received' (duration: 1.209452284s)","trace[1308054709] 'applied index is now lower than readState.Index' (duration: 178.814µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:17:04.395024Z","caller":"traceutil/trace.go:171","msg":"trace[1230439063] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"1.27883448s","start":"2026-03-09T06:17:03.116178Z","end":"2026-03-09T06:17:04.395012Z","steps":["trace[1230439063] 'process raft request' (duration: 1.278446432s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:04.395126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:03.11616Z","time spent":"1.278901483s","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:17:04.395373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.210265893s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:17:04.395414Z","caller":"traceutil/trace.go:171","msg":"trace[950858825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:843; }","duration":"1.210343604s","start":"2026-03-09T06:17:03.18506Z","end":"2026-03-09T06:17:04.395403Z","steps":["trace[950858825] 'agreement among raft nodes before linearized reading' (duration: 1.210279743s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:04.395442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:03.185038Z","time spent":"1.210397525s","remote":"127.0.0.1:58592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-09T06:17:04.395676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.771346ms","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-03-09T06:17:04.395713Z","caller":"traceutil/trace.go:171","msg":"trace[338138741] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:843; }","duration":"873.836397ms","start":"2026-03-09T06:17:03.521868Z","end":"2026-03-09T06:17:04.395704Z","steps":["trace[338138741] 'agreement among raft nodes before linearized reading' (duration: 873.696984ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:04.395742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:03.521825Z","time spent":"873.908439ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-09T06:17:04.395998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.649365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-09T06:17:04.396028Z","caller":"traceutil/trace.go:171","msg":"trace[1347757840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:843; }","duration":"295.712677ms","start":"2026-03-09T06:17:04.100304Z","end":"2026-03-09T06:17:04.396017Z","steps":["trace[1347757840] 'agreement among raft nodes before linearized reading' (duration: 295.647325ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:04.396113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.376956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:17:04.396145Z","caller":"traceutil/trace.go:171","msg":"trace[1959932303] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:843; }","duration":"487.433847ms","start":"2026-03-09T06:17:03.908701Z","end":"2026-03-09T06:17:04.396135Z","steps":["trace[1959932303] 'agreement among raft nodes before linearized reading' (duration: 487.384306ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:04.396184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:03.908689Z","time spent":"487.480218ms","remote":"127.0.0.1:58784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-03-09T06:17:04.830761Z","caller":"traceutil/trace.go:171","msg":"trace[716023733] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"428.765169ms","start":"2026-03-09T06:17:04.401967Z","end":"2026-03-09T06:17:04.830732Z","steps":["trace[716023733] 'process raft request' (duration: 426.670649ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:04.830913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:04.401955Z","time spent":"428.889761ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T06:17:04.830939Z","caller":"traceutil/trace.go:171","msg":"trace[89468788] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"428.86217ms","start":"2026-03-09T06:17:04.402026Z","end":"2026-03-09T06:17:04.830888Z","steps":["trace[89468788] 'process raft request' (duration: 428.476701ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:17:04.830963Z","caller":"traceutil/trace.go:171","msg":"trace[927881879] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"428.885651ms","start":"2026-03-09T06:17:04.402048Z","end":"2026-03-09T06:17:04.830934Z","steps":["trace[927881879] 'process raft request' (duration: 428.500112ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:04.831098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:04.402001Z","time spent":"429.012204ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:17:04.831113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:04.402026Z","time spent":"428.995253ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T06:17:04.845363Z","caller":"traceutil/trace.go:171","msg":"trace[857364854] linearizableReadLoop","detail":"{readStateIndex:880; appliedIndex:877; }","duration":"439.872437ms","start":"2026-03-09T06:17:04.405469Z","end":"2026-03-09T06:17:04.845341Z","steps":["trace[857364854] 'read index received' (duration: 423.119686ms)","trace[857364854] 'applied index is now lower than readState.Index' (duration: 16.751481ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:17:04.845679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.198905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:17:04.84572Z","caller":"traceutil/trace.go:171","msg":"trace[342154692] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:846; }","duration":"440.258486ms","start":"2026-03-09T06:17:04.40545Z","end":"2026-03-09T06:17:04.845709Z","steps":["trace[342154692] 'agreement among raft nodes before linearized reading' (duration: 440.184995ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:04.845751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:04.405444Z","time spent":"440.299357ms","remote":"127.0.0.1:58578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-09T06:17:18.570926Z","caller":"traceutil/trace.go:171","msg":"trace[203616050] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"164.785593ms","start":"2026-03-09T06:17:18.406118Z","end":"2026-03-09T06:17:18.570904Z","steps":["trace[203616050] 'process raft request' (duration: 164.27906ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:17:18.80081Z","caller":"traceutil/trace.go:171","msg":"trace[645550450] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"136.763709ms","start":"2026-03-09T06:17:18.66401Z","end":"2026-03-09T06:17:18.800773Z","steps":["trace[645550450] 'process raft request' (duration: 109.011452ms)","trace[645550450] 'compare' (duration: 27.570183ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:17:21.099949Z","caller":"traceutil/trace.go:171","msg":"trace[1033348978] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"149.551847ms","start":"2026-03-09T06:17:20.950371Z","end":"2026-03-09T06:17:21.099922Z","steps":["trace[1033348978] 'process raft request' (duration: 108.551691ms)","trace[1033348978] 'compare' (duration: 40.546935ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:17:23.165263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.272395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-09T06:17:23.165351Z","caller":"traceutil/trace.go:171","msg":"trace[798053008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:914; }","duration":"213.437668ms","start":"2026-03-09T06:17:22.951886Z","end":"2026-03-09T06:17:23.165324Z","steps":["trace[798053008] 'range keys from in-memory index tree' (duration: 213.104111ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:23.191161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.357057ms","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-03-09T06:17:23.191223Z","caller":"traceutil/trace.go:171","msg":"trace[1741296834] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:914; }","duration":"150.49088ms","start":"2026-03-09T06:17:23.040718Z","end":"2026-03-09T06:17:23.191209Z","steps":["trace[1741296834] 'count revisions from in-memory index tree' (duration: 150.242513ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:17:23.437075Z","caller":"traceutil/trace.go:171","msg":"trace[1309571465] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"243.94178ms","start":"2026-03-09T06:17:23.193108Z","end":"2026-03-09T06:17:23.43705Z","steps":["trace[1309571465] 'process raft request' (duration: 243.792516ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:24.085112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.206058ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T06:17:24.085383Z","caller":"traceutil/trace.go:171","msg":"trace[1686482876] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:952; }","duration":"891.378707ms","start":"2026-03-09T06:17:23.193969Z","end":"2026-03-09T06:17:24.085348Z","steps":["trace[1686482876] 'read index received' (duration: 242.910665ms)","trace[1686482876] 'applied index is now lower than readState.Index' (duration: 648.466772ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:17:24.085466Z","caller":"traceutil/trace.go:171","msg":"trace[865346350] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"892.069072ms","start":"2026-03-09T06:17:23.193383Z","end":"2026-03-09T06:17:24.085452Z","steps":["trace[865346350] 'process raft request' (duration: 891.909829ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:17:24.085466Z","caller":"traceutil/trace.go:171","msg":"trace[1117482101] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"892.198556ms","start":"2026-03-09T06:17:23.193224Z","end":"2026-03-09T06:17:24.085423Z","steps":["trace[1117482101] 'process raft request' (duration: 345.590648ms)","trace[1117482101] 'compare' (duration: 546.057505ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:17:24.085552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.572611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-09T06:17:24.085568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:23.193206Z","time spent":"892.313559ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T06:17:24.0856Z","caller":"traceutil/trace.go:171","msg":"trace[215988606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:918; }","duration":"891.633242ms","start":"2026-03-09T06:17:23.193952Z","end":"2026-03-09T06:17:24.085586Z","steps":["trace[215988606] 'agreement among raft nodes before linearized reading' (duration: 891.5304ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:24.085617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.418079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:17:24.085637Z","caller":"traceutil/trace.go:171","msg":"trace[499795162] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:918; }","duration":"795.437529ms","start":"2026-03-09T06:17:23.290193Z","end":"2026-03-09T06:17:24.08563Z","steps":["trace[499795162] 'agreement among raft nodes before linearized reading' (duration: 795.399088ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:17:24.085619Z","caller":"traceutil/trace.go:171","msg":"trace[1858550411] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"892.258917ms","start":"2026-03-09T06:17:23.193334Z","end":"2026-03-09T06:17:24.085592Z","steps":["trace[1858550411] 'process raft request' (duration: 891.888219ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:24.085655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:23.290167Z","time spent":"795.484841ms","remote":"127.0.0.1:58738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-03-09T06:17:24.085567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:23.193378Z","time spent":"892.130615ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:17:24.08577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:23.193328Z","time spent":"892.351579ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:17:24.085643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:23.193946Z","time spent":"891.686043ms","remote":"127.0.0.1:58578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-09T06:17:24.910477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.141941ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-09T06:17:24.910724Z","caller":"traceutil/trace.go:171","msg":"trace[1146425653] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:956; }","duration":"129.939261ms","start":"2026-03-09T06:17:24.780766Z","end":"2026-03-09T06:17:24.910705Z","steps":["trace[1146425653] 'read index received' (duration: 121.75145ms)","trace[1146425653] 'applied index is now lower than readState.Index' (duration: 8.186941ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:17:24.910781Z","caller":"traceutil/trace.go:171","msg":"trace[1665964037] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"169.33054ms","start":"2026-03-09T06:17:24.741402Z","end":"2026-03-09T06:17:24.910733Z","steps":["trace[1665964037] 'process raft request' (duration: 169.192436ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:24.910915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.153755ms","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-03-09T06:17:24.911002Z","caller":"traceutil/trace.go:171","msg":"trace[1089831679] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:919; }","duration":"130.247628ms","start":"2026-03-09T06:17:24.780709Z","end":"2026-03-09T06:17:24.910956Z","steps":["trace[1089831679] 'agreement among raft nodes before linearized reading' (duration: 130.046263ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:17:25.70466Z","caller":"traceutil/trace.go:171","msg":"trace[1860773786] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"259.625964ms","start":"2026-03-09T06:17:25.445009Z","end":"2026-03-09T06:17:25.704635Z","steps":["trace[1860773786] 'process raft request' (duration: 259.267936ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:27.104109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014372334s","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-03-09T06:17:27.104187Z","caller":"traceutil/trace.go:171","msg":"trace[1833233125] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:921; }","duration":"1.014498297s","start":"2026-03-09T06:17:26.089669Z","end":"2026-03-09T06:17:27.104167Z","steps":["trace[1833233125] 'range keys from in-memory index tree' (duration: 1.014163919s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:27.104225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:26.08965Z","time spent":"1.014566898s","remote":"127.0.0.1:58858","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-03-09T06:17:27.104615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014701661s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-09T06:17:27.104708Z","caller":"traceutil/trace.go:171","msg":"trace[89599625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:921; }","duration":"1.014845574s","start":"2026-03-09T06:17:26.089836Z","end":"2026-03-09T06:17:27.104681Z","steps":["trace[89599625] 'range keys from in-memory index tree' (duration: 1.014448595s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:27.104748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:26.08972Z","time spent":"1.015020109s","remote":"127.0.0.1:58858","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-03-09T06:17:27.104758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010277989s","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-03-09T06:17:27.104796Z","caller":"traceutil/trace.go:171","msg":"trace[435550138] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:921; }","duration":"1.010314369s","start":"2026-03-09T06:17:26.094471Z","end":"2026-03-09T06:17:27.104785Z","steps":["trace[435550138] 'range keys from in-memory index tree' (duration: 1.010197166s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:27.104824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:26.094449Z","time spent":"1.01036891s","remote":"127.0.0.1:58858","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-03-09T06:17:27.104897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.279489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:17:27.10498Z","caller":"traceutil/trace.go:171","msg":"trace[1572313842] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:921; }","duration":"920.404152ms","start":"2026-03-09T06:17:26.184554Z","end":"2026-03-09T06:17:27.104958Z","steps":["trace[1572313842] 'range keys from in-memory index tree' (duration: 920.203938ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:27.105021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:26.184534Z","time spent":"920.476585ms","remote":"127.0.0.1:58578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-09T06:17:27.614136Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529674453053735,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-09T06:17:28.114431Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529674453053735,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-09T06:17:28.264109Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.151359278s","expected-duration":"1s"} {"level":"info","ts":"2026-03-09T06:17:28.264544Z","caller":"traceutil/trace.go:171","msg":"trace[58796575] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"1.151826908s","start":"2026-03-09T06:17:27.112657Z","end":"2026-03-09T06:17:28.264484Z","steps":["trace[58796575] 'process raft request' (duration: 1.151673835s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:28.2647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:27.11263Z","time spent":"1.151987272s","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T06:17:28.842964Z","caller":"traceutil/trace.go:171","msg":"trace[123611553] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:960; }","duration":"1.729248033s","start":"2026-03-09T06:17:27.113692Z","end":"2026-03-09T06:17:28.84294Z","steps":["trace[123611553] 'read index received' (duration: 1.150637531s)","trace[123611553] 'applied index is now lower than readState.Index' (duration: 578.609462ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:17:28.843244Z","caller":"traceutil/trace.go:171","msg":"trace[1042069433] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"1.724079262s","start":"2026-03-09T06:17:27.119129Z","end":"2026-03-09T06:17:28.843208Z","steps":["trace[1042069433] 'process raft request' (duration: 1.723776425s)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:17:28.843359Z","caller":"traceutil/trace.go:171","msg":"trace[364296736] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"1.730188085s","start":"2026-03-09T06:17:27.113139Z","end":"2026-03-09T06:17:28.843327Z","steps":["trace[364296736] 'process raft request' (duration: 1.656563468s)","trace[364296736] 'compare' (duration: 73.047603ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:17:28.843393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:27.119113Z","time spent":"1.724204485s","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T06:17:28.843373Z","caller":"traceutil/trace.go:171","msg":"trace[1062420166] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"1.72955343s","start":"2026-03-09T06:17:27.113783Z","end":"2026-03-09T06:17:28.843336Z","steps":["trace[1062420166] 'process raft request' (duration: 1.729083719s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:28.843445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.729715343s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:17:28.843511Z","caller":"traceutil/trace.go:171","msg":"trace[456739730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:925; }","duration":"1.729841826s","start":"2026-03-09T06:17:27.113653Z","end":"2026-03-09T06:17:28.843495Z","steps":["trace[456739730] 'agreement among raft nodes before linearized reading' (duration: 1.729712743s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:28.843513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:27.113128Z","time spent":"1.730300687s","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:17:28.843561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:27.113634Z","time spent":"1.729919559s","remote":"127.0.0.1:58592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-09T06:17:28.843533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:27.113771Z","time spent":"1.729681692s","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:17:28.843676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.614928157s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-09T06:17:28.843708Z","caller":"traceutil/trace.go:171","msg":"trace[78025644] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:925; }","duration":"1.61501843s","start":"2026-03-09T06:17:27.228683Z","end":"2026-03-09T06:17:28.843701Z","steps":["trace[78025644] 'agreement among raft nodes before linearized reading' (duration: 1.614958589s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:28.843797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135426417s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-09T06:17:28.843875Z","caller":"traceutil/trace.go:171","msg":"trace[317404153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:925; }","duration":"1.13555516s","start":"2026-03-09T06:17:27.708301Z","end":"2026-03-09T06:17:28.843856Z","steps":["trace[317404153] 'agreement among raft nodes before linearized reading' (duration: 1.135350085s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:28.843908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:27.228656Z","time spent":"1.615067031s","remote":"127.0.0.1:59056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-03-09T06:17:28.843921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:27.708285Z","time spent":"1.135627221s","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-09T06:17:29.350414Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529674453053741,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-09T06:17:29.851285Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529674453053741,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-09T06:17:29.922172Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.07380587s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-09T06:17:30.041783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.22662ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T06:17:30.042005Z","caller":"traceutil/trace.go:171","msg":"trace[121469245] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:964; }","duration":"1.191876993s","start":"2026-03-09T06:17:28.85011Z","end":"2026-03-09T06:17:30.041987Z","steps":["trace[121469245] 'read index received' (duration: 1.072339636s)","trace[121469245] 'applied index is now lower than readState.Index' (duration: 119.535887ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:17:30.042099Z","caller":"traceutil/trace.go:171","msg":"trace[763716175] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"388.869587ms","start":"2026-03-09T06:17:29.653217Z","end":"2026-03-09T06:17:30.042087Z","steps":["trace[763716175] 'process raft request' (duration: 388.699173ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:30.042165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:29.653189Z","time spent":"388.941389ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T06:17:30.042213Z","caller":"traceutil/trace.go:171","msg":"trace[236843004] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"1.19391103s","start":"2026-03-09T06:17:28.848264Z","end":"2026-03-09T06:17:30.042175Z","steps":["trace[236843004] 'process raft request' (duration: 1.074199919s)","trace[236843004] 'compare' (duration: 119.053786ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:17:30.042376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:28.848248Z","time spent":"1.194035932s","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:17:30.042515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.723761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.52\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-09T06:17:30.04259Z","caller":"traceutil/trace.go:171","msg":"trace[2012741188] range","detail":"{range_begin:/registry/masterleases/162.253.55.52; range_end:; response_count:1; response_revision:927; }","duration":"857.858955ms","start":"2026-03-09T06:17:29.184711Z","end":"2026-03-09T06:17:30.042569Z","steps":["trace[2012741188] 'agreement among raft nodes before linearized reading' (duration: 857.601678ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:30.042632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:29.184688Z","time spent":"857.936456ms","remote":"127.0.0.1:58610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.52\" "} {"level":"warn","ts":"2026-03-09T06:17:30.042837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.192722893s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:17:30.042912Z","caller":"traceutil/trace.go:171","msg":"trace[1067359032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:927; }","duration":"1.192841455s","start":"2026-03-09T06:17:28.850054Z","end":"2026-03-09T06:17:30.042896Z","steps":["trace[1067359032] 'agreement among raft nodes before linearized reading' (duration: 1.192561619s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:30.042953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:28.850042Z","time spent":"1.192901217s","remote":"127.0.0.1:58578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-09T06:17:30.042983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.442854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-09T06:17:30.043063Z","caller":"traceutil/trace.go:171","msg":"trace[725563713] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:927; }","duration":"709.582747ms","start":"2026-03-09T06:17:29.33346Z","end":"2026-03-09T06:17:30.043043Z","steps":["trace[725563713] 'agreement among raft nodes before linearized reading' (duration: 709.353722ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:30.043116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:29.333405Z","time spent":"709.695949ms","remote":"127.0.0.1:59102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":102,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-03-09T06:17:30.494755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.558355ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-03-09T06:17:30.494856Z","caller":"traceutil/trace.go:171","msg":"trace[1272961518] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:966; }","duration":"450.874064ms","start":"2026-03-09T06:17:30.043963Z","end":"2026-03-09T06:17:30.494837Z","steps":["trace[1272961518] 'read index received' (duration: 154.262167ms)","trace[1272961518] 'applied index is now lower than readState.Index' (duration: 296.610457ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:17:30.494966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.994087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-09T06:17:30.494929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:30.043813Z","time spent":"451.108299ms","remote":"127.0.0.1:58610","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-09T06:17:30.495015Z","caller":"traceutil/trace.go:171","msg":"trace[924638335] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:927; }","duration":"451.069629ms","start":"2026-03-09T06:17:30.043924Z","end":"2026-03-09T06:17:30.494994Z","steps":["trace[924638335] 'agreement among raft nodes before linearized reading' (duration: 450.984087ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:30.495046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:30.043908Z","time spent":"451.13143ms","remote":"127.0.0.1:58592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-09T06:17:30.565889Z","caller":"traceutil/trace.go:171","msg":"trace[955808029] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"159.500789ms","start":"2026-03-09T06:17:30.406362Z","end":"2026-03-09T06:17:30.565863Z","steps":["trace[955808029] 'process raft request' (duration: 159.216572ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:30.565974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.122839ms","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-03-09T06:17:30.565986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.659762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-09T06:17:30.566038Z","caller":"traceutil/trace.go:171","msg":"trace[1446758647] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:928; }","duration":"297.197901ms","start":"2026-03-09T06:17:30.268825Z","end":"2026-03-09T06:17:30.566023Z","steps":["trace[1446758647] 'agreement among raft nodes before linearized reading' (duration: 296.994346ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:17:30.566049Z","caller":"traceutil/trace.go:171","msg":"trace[957591982] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:928; }","duration":"261.784524ms","start":"2026-03-09T06:17:30.304248Z","end":"2026-03-09T06:17:30.566033Z","steps":["trace[957591982] 'agreement among raft nodes before linearized reading' (duration: 261.577459ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:31.066365Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529674453053751,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-09T06:17:32.094722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.243208ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T06:17:32.094864Z","caller":"traceutil/trace.go:171","msg":"trace[1452660926] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:968; }","duration":"1.529050245s","start":"2026-03-09T06:17:30.565802Z","end":"2026-03-09T06:17:32.094852Z","steps":["trace[1452660926] 'read index received' (duration: 780.610323ms)","trace[1452660926] 'applied index is now lower than readState.Index' (duration: 748.439252ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:17:32.094914Z","caller":"traceutil/trace.go:171","msg":"trace[1261565950] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"1.598538776s","start":"2026-03-09T06:17:30.496367Z","end":"2026-03-09T06:17:32.094906Z","steps":["trace[1261565950] 'process raft request' (duration: 850.036522ms)","trace[1261565950] 'compare' (duration: 747.810368ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:17:32.09495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:30.496311Z","time spent":"1.598619018s","remote":"127.0.0.1:58610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T06:17:32.095159Z","caller":"traceutil/trace.go:171","msg":"trace[614706482] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"1.525146354s","start":"2026-03-09T06:17:30.570003Z","end":"2026-03-09T06:17:32.095149Z","steps":["trace[614706482] 'process raft request' (duration: 1.524792376s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:32.095195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:30.569987Z","time spent":"1.525187355s","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:17:32.095315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.593356784s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:17:32.095334Z","caller":"traceutil/trace.go:171","msg":"trace[1011596390] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:930; }","duration":"1.593391495s","start":"2026-03-09T06:17:30.501936Z","end":"2026-03-09T06:17:32.095327Z","steps":["trace[1011596390] 'agreement among raft nodes before linearized reading' (duration: 1.593361325s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:32.095351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:30.501928Z","time spent":"1.593420126s","remote":"127.0.0.1:58578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-09T06:17:32.595866Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529674453053754,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-09T06:17:32.721494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.196679ms","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-03-09T06:17:32.721614Z","caller":"traceutil/trace.go:171","msg":"trace[1312998161] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:930; }","duration":"393.325862ms","start":"2026-03-09T06:17:32.328269Z","end":"2026-03-09T06:17:32.721595Z","steps":["trace[1312998161] 'range keys from in-memory index tree' (duration: 393.181149ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:17:33.072588Z","caller":"traceutil/trace.go:171","msg":"trace[1753314727] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:970; }","duration":"977.686358ms","start":"2026-03-09T06:17:32.094883Z","end":"2026-03-09T06:17:33.072569Z","steps":["trace[1753314727] 'read index received' (duration: 977.669188ms)","trace[1753314727] 'applied index is now lower than readState.Index' (duration: 16.46µs)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:17:33.072756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.223924439s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-03-09T06:17:33.072782Z","caller":"traceutil/trace.go:171","msg":"trace[388320358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:931; }","duration":"2.224008361s","start":"2026-03-09T06:17:30.848768Z","end":"2026-03-09T06:17:33.072776Z","steps":["trace[388320358] 'agreement among raft nodes before linearized reading' (duration: 2.223887618s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:33.072807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:30.848746Z","time spent":"2.224055181s","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-09T06:17:33.072971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012591221s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-09T06:17:33.073033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.224744288s","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-03-09T06:17:33.073057Z","caller":"traceutil/trace.go:171","msg":"trace[496883255] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:931; }","duration":"2.224830259s","start":"2026-03-09T06:17:30.848221Z","end":"2026-03-09T06:17:33.073051Z","steps":["trace[496883255] 'agreement among raft nodes before linearized reading' (duration: 2.224762858s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:33.073076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:30.848215Z","time spent":"2.22485618s","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-09T06:17:33.073134Z","caller":"traceutil/trace.go:171","msg":"trace[360283282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:931; }","duration":"1.012705154s","start":"2026-03-09T06:17:32.060336Z","end":"2026-03-09T06:17:33.073041Z","steps":["trace[360283282] 'agreement among raft nodes before linearized reading' (duration: 1.01255358s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:33.073214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:32.060319Z","time spent":"1.012882058s","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-09T06:17:33.073182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.430973217s","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":"warn","ts":"2026-03-09T06:17:33.073253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.496899324s","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-03-09T06:17:33.073325Z","caller":"traceutil/trace.go:171","msg":"trace[1062692494] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:931; }","duration":"2.497007036s","start":"2026-03-09T06:17:30.5763Z","end":"2026-03-09T06:17:33.073307Z","steps":["trace[1062692494] 'agreement among raft nodes before linearized reading' (duration: 2.496848652s)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:17:33.073327Z","caller":"traceutil/trace.go:171","msg":"trace[1754628022] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:931; }","duration":"1.431193792s","start":"2026-03-09T06:17:31.642105Z","end":"2026-03-09T06:17:33.073299Z","steps":["trace[1754628022] 'agreement among raft nodes before linearized reading' (duration: 1.430871874s)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:17:33.073353Z","caller":"traceutil/trace.go:171","msg":"trace[22677527] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"1.597402499s","start":"2026-03-09T06:17:31.47594Z","end":"2026-03-09T06:17:33.073342Z","steps":["trace[22677527] 'process raft request' (duration: 1.596514608s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:33.073403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:31.642088Z","time spent":"1.431292724s","remote":"127.0.0.1:58694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-03-09T06:17:33.07382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:31.475919Z","time spent":"1.59746356s","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-09T06:17:33.073358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:30.576292Z","time spent":"2.497060407s","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-09T06:17:33.073439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.22529241s","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-03-09T06:17:33.074291Z","caller":"traceutil/trace.go:171","msg":"trace[834664808] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:931; }","duration":"2.226183041s","start":"2026-03-09T06:17:30.848096Z","end":"2026-03-09T06:17:33.074279Z","steps":["trace[834664808] 'agreement among raft nodes before linearized reading' (duration: 2.22527505s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:33.074358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:30.848073Z","time spent":"2.226276524s","remote":"127.0.0.1:58858","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-03-09T06:17:33.074545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.65494459s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-09T06:17:33.074581Z","caller":"traceutil/trace.go:171","msg":"trace[1860359073] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:931; }","duration":"1.654972612s","start":"2026-03-09T06:17:31.41959Z","end":"2026-03-09T06:17:33.074563Z","steps":["trace[1860359073] 'agreement among raft nodes before linearized reading' (duration: 1.653002916s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:33.074602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:31.419534Z","time spent":"1.655062414s","remote":"127.0.0.1:59042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-03-09T06:17:33.57325Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529674453053757,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-09T06:17:33.787949Z","caller":"traceutil/trace.go:171","msg":"trace[227565340] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"1.134872793s","start":"2026-03-09T06:17:32.653052Z","end":"2026-03-09T06:17:33.787925Z","steps":["trace[227565340] 'process raft request' (duration: 1.134692489s)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:17:33.788159Z","caller":"traceutil/trace.go:171","msg":"trace[1568542190] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"821.132988ms","start":"2026-03-09T06:17:32.966992Z","end":"2026-03-09T06:17:33.788125Z","steps":["trace[1568542190] 'process raft request' (duration: 820.898382ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:33.788323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:32.966967Z","time spent":"821.260022ms","remote":"127.0.0.1:58742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5957,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:17:33.790578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:32.653032Z","time spent":"1.134977747s","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T06:17:33.802967Z","caller":"traceutil/trace.go:171","msg":"trace[39325486] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"726.501711ms","start":"2026-03-09T06:17:33.076444Z","end":"2026-03-09T06:17:33.802946Z","steps":["trace[39325486] 'process raft request' (duration: 726.379098ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:33.803099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:33.07642Z","time spent":"726.608683ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T06:17:33.803278Z","caller":"traceutil/trace.go:171","msg":"trace[1852435980] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"726.721916ms","start":"2026-03-09T06:17:33.076533Z","end":"2026-03-09T06:17:33.803255Z","steps":["trace[1852435980] 'process raft request' (duration: 726.390378ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:17:33.803358Z","caller":"traceutil/trace.go:171","msg":"trace[1279913110] linearizableReadLoop","detail":"{readStateIndex:978; appliedIndex:971; }","duration":"730.704309ms","start":"2026-03-09T06:17:33.072641Z","end":"2026-03-09T06:17:33.803345Z","steps":["trace[1279913110] 'read index received' (duration: 715.099235ms)","trace[1279913110] 'applied index is now lower than readState.Index' (duration: 15.604154ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:17:33.803387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:33.076528Z","time spent":"726.796168ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:17:33.803401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.803244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:17:33.803393Z","caller":"traceutil/trace.go:171","msg":"trace[396999451] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"725.457167ms","start":"2026-03-09T06:17:33.077921Z","end":"2026-03-09T06:17:33.803378Z","steps":["trace[396999451] 'process raft request' (duration: 725.17359ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:17:33.803452Z","caller":"traceutil/trace.go:171","msg":"trace[1291484558] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"721.643378ms","start":"2026-03-09T06:17:33.081803Z","end":"2026-03-09T06:17:33.803446Z","steps":["trace[1291484558] 'process raft request' (duration: 721.462104ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:33.803482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.060736565s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-09T06:17:33.803488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:33.081787Z","time spent":"721.679929ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:17:33.80349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:33.077898Z","time spent":"725.556259ms","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":835,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-09T06:17:33.803507Z","caller":"traceutil/trace.go:171","msg":"trace[60788107] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:938; }","duration":"1.060759736s","start":"2026-03-09T06:17:32.742739Z","end":"2026-03-09T06:17:33.803499Z","steps":["trace[60788107] 'agreement among raft nodes before linearized reading' (duration: 1.060719165s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:33.803487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.601940535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-09T06:17:33.803525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:32.742698Z","time spent":"1.060823167s","remote":"127.0.0.1:59098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-03-09T06:17:33.803539Z","caller":"traceutil/trace.go:171","msg":"trace[59419938] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"720.239355ms","start":"2026-03-09T06:17:33.083294Z","end":"2026-03-09T06:17:33.803533Z","steps":["trace[59419938] 'process raft request' (duration: 719.99846ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:17:33.803545Z","caller":"traceutil/trace.go:171","msg":"trace[1935974667] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:938; }","duration":"1.602055497s","start":"2026-03-09T06:17:32.201476Z","end":"2026-03-09T06:17:33.803531Z","steps":["trace[1935974667] 'agreement among raft nodes before linearized reading' (duration: 1.601925354s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:33.803569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:33.08328Z","time spent":"720.275306ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:17:33.803592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:32.201455Z","time spent":"1.6021304s","remote":"127.0.0.1:58966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-09T06:17:33.803631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.007309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:17:33.803646Z","caller":"traceutil/trace.go:171","msg":"trace[331518068] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:938; }","duration":"720.046051ms","start":"2026-03-09T06:17:33.083594Z","end":"2026-03-09T06:17:33.80364Z","steps":["trace[331518068] 'agreement among raft nodes before linearized reading' (duration: 720.02284ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:33.80366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:33.083583Z","time spent":"720.074041ms","remote":"127.0.0.1:58666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-03-09T06:17:33.803659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.807363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-09T06:17:33.80343Z","caller":"traceutil/trace.go:171","msg":"trace[547020736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:938; }","duration":"788.871216ms","start":"2026-03-09T06:17:33.014549Z","end":"2026-03-09T06:17:33.80342Z","steps":["trace[547020736] 'agreement among raft nodes before linearized reading' (duration: 788.832125ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:17:33.803689Z","caller":"traceutil/trace.go:171","msg":"trace[1132383704] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:938; }","duration":"177.003738ms","start":"2026-03-09T06:17:33.626676Z","end":"2026-03-09T06:17:33.80368Z","steps":["trace[1132383704] 'agreement among raft nodes before linearized reading' (duration: 176.947616ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:33.803696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.706985164s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:17:33.803712Z","caller":"traceutil/trace.go:171","msg":"trace[1711110983] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:938; }","duration":"1.707022004s","start":"2026-03-09T06:17:32.096685Z","end":"2026-03-09T06:17:33.803707Z","steps":["trace[1711110983] 'agreement among raft nodes before linearized reading' (duration: 1.706996394s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:33.803725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:32.09667Z","time spent":"1.707051726s","remote":"127.0.0.1:58592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-09T06:17:33.803768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.662975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-09T06:17:33.803749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:33.014528Z","time spent":"789.179653ms","remote":"127.0.0.1:58578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-09T06:17:33.803784Z","caller":"traceutil/trace.go:171","msg":"trace[2094895876] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:938; }","duration":"515.733756ms","start":"2026-03-09T06:17:33.288045Z","end":"2026-03-09T06:17:33.803779Z","steps":["trace[2094895876] 'agreement among raft nodes before linearized reading' (duration: 515.704635ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:33.803799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:33.288018Z","time spent":"515.776467ms","remote":"127.0.0.1:56282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-03-09T06:17:33.803994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.706593095s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-03-09T06:17:33.804791Z","caller":"traceutil/trace.go:171","msg":"trace[2042125515] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:938; }","duration":"1.707426255s","start":"2026-03-09T06:17:32.097354Z","end":"2026-03-09T06:17:33.804781Z","steps":["trace[2042125515] 'agreement among raft nodes before linearized reading' (duration: 1.706070453s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:33.804857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:32.097348Z","time spent":"1.707501187s","remote":"127.0.0.1:58740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-03-09T06:17:35.426401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.441077ms","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-03-09T06:17:35.426487Z","caller":"traceutil/trace.go:171","msg":"trace[1912155426] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:941; }","duration":"927.58401ms","start":"2026-03-09T06:17:34.498882Z","end":"2026-03-09T06:17:35.426466Z","steps":["trace[1912155426] 'count revisions from in-memory index tree' (duration: 927.348625ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:35.426531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:34.498859Z","time spent":"927.660902ms","remote":"127.0.0.1:56226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-03-09T06:17:35.426801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.328001478s","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-03-09T06:17:35.426868Z","caller":"traceutil/trace.go:171","msg":"trace[1032234500] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:941; }","duration":"1.328134571s","start":"2026-03-09T06:17:34.098714Z","end":"2026-03-09T06:17:35.426849Z","steps":["trace[1032234500] 'range keys from in-memory index tree' (duration: 1.327735039s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:35.426907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:34.098683Z","time spent":"1.328215193s","remote":"127.0.0.1:58858","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-03-09T06:17:35.42696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.241206084s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:17:35.42708Z","caller":"traceutil/trace.go:171","msg":"trace[1070871971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:941; }","duration":"1.241384828s","start":"2026-03-09T06:17:34.185656Z","end":"2026-03-09T06:17:35.427041Z","steps":["trace[1070871971] 'range keys from in-memory index tree' (duration: 1.241133661s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:35.427144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:34.18564Z","time spent":"1.24148905s","remote":"127.0.0.1:58578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-09T06:17:35.635239Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.034411791s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-09T06:17:35.736195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.450812ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-09T06:17:35.736544Z","caller":"traceutil/trace.go:171","msg":"trace[298025229] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"305.423162ms","start":"2026-03-09T06:17:35.431063Z","end":"2026-03-09T06:17:35.736486Z","steps":["trace[298025229] 'process raft request' (duration: 305.248518ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:17:35.736538Z","caller":"traceutil/trace.go:171","msg":"trace[244446095] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:981; }","duration":"307.820018ms","start":"2026-03-09T06:17:35.428642Z","end":"2026-03-09T06:17:35.736462Z","steps":["trace[244446095] 'read index received' (duration: 207.186112ms)","trace[244446095] 'applied index is now lower than readState.Index' (duration: 100.629876ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:17:35.736679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:35.43104Z","time spent":"305.563275ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T06:17:35.736938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.258238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:17:35.737131Z","caller":"traceutil/trace.go:171","msg":"trace[1961989798] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:942; }","duration":"308.485314ms","start":"2026-03-09T06:17:35.428613Z","end":"2026-03-09T06:17:35.737098Z","steps":["trace[1961989798] 'agreement among raft nodes before linearized reading' (duration: 307.934371ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:35.737338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:35.428594Z","time spent":"308.723929ms","remote":"127.0.0.1:58592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-09T06:17:35.940485Z","caller":"traceutil/trace.go:171","msg":"trace[1273112325] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"125.602369ms","start":"2026-03-09T06:17:35.814858Z","end":"2026-03-09T06:17:35.94046Z","steps":["trace[1273112325] 'process raft request' (duration: 125.539348ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:17:35.940547Z","caller":"traceutil/trace.go:171","msg":"trace[634469321] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"125.911996ms","start":"2026-03-09T06:17:35.814566Z","end":"2026-03-09T06:17:35.940478Z","steps":["trace[634469321] 'process raft request' (duration: 125.743302ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:17:35.940625Z","caller":"traceutil/trace.go:171","msg":"trace[274070856] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"125.685301ms","start":"2026-03-09T06:17:35.814912Z","end":"2026-03-09T06:17:35.940598Z","steps":["trace[274070856] 'process raft request' (duration: 125.519537ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:17:37.584023Z","caller":"traceutil/trace.go:171","msg":"trace[1174813301] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"300.311464ms","start":"2026-03-09T06:17:37.283687Z","end":"2026-03-09T06:17:37.583998Z","steps":["trace[1174813301] 'read index received' (duration: 298.548742ms)","trace[1174813301] 'applied index is now lower than readState.Index' (duration: 1.761742ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:17:37.584086Z","caller":"traceutil/trace.go:171","msg":"trace[612899172] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"326.855762ms","start":"2026-03-09T06:17:37.257206Z","end":"2026-03-09T06:17:37.584062Z","steps":["trace[612899172] 'process raft request' (duration: 325.06777ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:37.584193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:37.257185Z","time spent":"326.954004ms","remote":"127.0.0.1:58658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":842,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-09T06:17:37.584196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.779585ms","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":"warn","ts":"2026-03-09T06:17:37.58419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.491478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:17:37.584236Z","caller":"traceutil/trace.go:171","msg":"trace[225213879] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:950; }","duration":"273.868816ms","start":"2026-03-09T06:17:37.310357Z","end":"2026-03-09T06:17:37.584225Z","steps":["trace[225213879] 'agreement among raft nodes before linearized reading' (duration: 273.780084ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:17:37.584249Z","caller":"traceutil/trace.go:171","msg":"trace[15379] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:950; }","duration":"300.5849ms","start":"2026-03-09T06:17:37.283647Z","end":"2026-03-09T06:17:37.584231Z","steps":["trace[15379] 'agreement among raft nodes before linearized reading' (duration: 300.439117ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:37.584285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:37.283632Z","time spent":"300.646361ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-09T06:19:09.663604Z","caller":"traceutil/trace.go:171","msg":"trace[1212660624] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"150.039834ms","start":"2026-03-09T06:19:09.513533Z","end":"2026-03-09T06:19:09.663573Z","steps":["trace[1212660624] 'process raft request' (duration: 78.355915ms)","trace[1212660624] 'compare' (duration: 71.536575ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:19:12.972588Z","caller":"traceutil/trace.go:171","msg":"trace[1958489214] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"105.173649ms","start":"2026-03-09T06:19:12.867381Z","end":"2026-03-09T06:19:12.972554Z","steps":["trace[1958489214] 'process raft request' (duration: 43.212887ms)","trace[1958489214] 'compare' (duration: 61.802289ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:19:48.78105Z","caller":"traceutil/trace.go:171","msg":"trace[773331] transaction","detail":"{read_only:false; response_revision:1511; number_of_response:1; }","duration":"112.601327ms","start":"2026-03-09T06:19:48.668416Z","end":"2026-03-09T06:19:48.781018Z","steps":["trace[773331] 'process raft request' (duration: 79.194992ms)","trace[773331] 'compare' (duration: 33.271082ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:19:50.966734Z","caller":"traceutil/trace.go:171","msg":"trace[121984352] linearizableReadLoop","detail":"{readStateIndex:1588; appliedIndex:1587; }","duration":"142.863672ms","start":"2026-03-09T06:19:50.82384Z","end":"2026-03-09T06:19:50.966704Z","steps":["trace[121984352] 'read index received' (duration: 142.580416ms)","trace[121984352] 'applied index is now lower than readState.Index' (duration: 282.096µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:19:50.966829Z","caller":"traceutil/trace.go:171","msg":"trace[1332491703] transaction","detail":"{read_only:false; response_revision:1520; number_of_response:1; }","duration":"148.773062ms","start":"2026-03-09T06:19:50.817993Z","end":"2026-03-09T06:19:50.966766Z","steps":["trace[1332491703] 'process raft request' (duration: 148.553857ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:19:50.967065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.199699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4219"} {"level":"info","ts":"2026-03-09T06:19:50.967782Z","caller":"traceutil/trace.go:171","msg":"trace[157883797] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:1520; }","duration":"143.961006ms","start":"2026-03-09T06:19:50.823801Z","end":"2026-03-09T06:19:50.967762Z","steps":["trace[157883797] 'agreement among raft nodes before linearized reading' (duration: 143.052286ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:19:51.193214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.480214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T06:19:51.193304Z","caller":"traceutil/trace.go:171","msg":"trace[395711401] linearizableReadLoop","detail":"{readStateIndex:1590; appliedIndex:1589; }","duration":"102.859263ms","start":"2026-03-09T06:19:51.090431Z","end":"2026-03-09T06:19:51.19329Z","steps":["trace[395711401] 'read index received' (duration: 57.821µs)","trace[395711401] 'applied index is now lower than readState.Index' (duration: 102.800482ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:19:51.193329Z","caller":"traceutil/trace.go:171","msg":"trace[779031814] transaction","detail":"{read_only:false; response_revision:1522; number_of_response:1; }","duration":"220.47099ms","start":"2026-03-09T06:19:50.972829Z","end":"2026-03-09T06:19:51.1933Z","steps":["trace[779031814] 'process raft request' (duration: 102.689279ms)","trace[779031814] 'compare' (duration: 117.316711ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:19:51.193423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.002356ms","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-03-09T06:19:51.19345Z","caller":"traceutil/trace.go:171","msg":"trace[363309216] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1522; }","duration":"103.085848ms","start":"2026-03-09T06:19:51.090356Z","end":"2026-03-09T06:19:51.193441Z","steps":["trace[363309216] 'agreement among raft nodes before linearized reading' (duration: 102.970606ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:20:39.670304Z","caller":"traceutil/trace.go:171","msg":"trace[2063318996] transaction","detail":"{read_only:false; response_revision:1744; number_of_response:1; }","duration":"170.605352ms","start":"2026-03-09T06:20:39.49962Z","end":"2026-03-09T06:20:39.670225Z","steps":["trace[2063318996] 'process raft request' (duration: 169.60073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:20:39.670953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.311025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-03-09T06:20:39.669655Z","caller":"traceutil/trace.go:171","msg":"trace[408070288] linearizableReadLoop","detail":"{readStateIndex:1823; appliedIndex:1822; }","duration":"147.993315ms","start":"2026-03-09T06:20:39.521629Z","end":"2026-03-09T06:20:39.669623Z","steps":["trace[408070288] 'read index received' (duration: 147.468824ms)","trace[408070288] 'applied index is now lower than readState.Index' (duration: 523.181µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:20:39.671021Z","caller":"traceutil/trace.go:171","msg":"trace[1877367335] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1744; }","duration":"149.398547ms","start":"2026-03-09T06:20:39.521596Z","end":"2026-03-09T06:20:39.670994Z","steps":["trace[1877367335] 'agreement among raft nodes before linearized reading' (duration: 148.18105ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:20:39.671206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.411847ms","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-03-09T06:20:39.671307Z","caller":"traceutil/trace.go:171","msg":"trace[1291867976] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1744; }","duration":"109.5379ms","start":"2026-03-09T06:20:39.561723Z","end":"2026-03-09T06:20:39.671261Z","steps":["trace[1291867976] 'agreement among raft nodes before linearized reading' (duration: 109.356926ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:20:44.828296Z","caller":"traceutil/trace.go:171","msg":"trace[1002426732] transaction","detail":"{read_only:false; response_revision:1769; number_of_response:1; }","duration":"136.205786ms","start":"2026-03-09T06:20:44.692059Z","end":"2026-03-09T06:20:44.828265Z","steps":["trace[1002426732] 'process raft request' (duration: 99.029449ms)","trace[1002426732] 'compare' (duration: 36.82206ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:20:47.669788Z","caller":"traceutil/trace.go:171","msg":"trace[740544998] transaction","detail":"{read_only:false; response_revision:1782; number_of_response:1; }","duration":"140.02623ms","start":"2026-03-09T06:20:47.529728Z","end":"2026-03-09T06:20:47.669754Z","steps":["trace[740544998] 'process raft request' (duration: 125.841828ms)","trace[740544998] 'compare' (duration: 13.481017ms)"],"step_count":2}