{"level":"warn","ts":"2026-05-26T05:51:43.16569Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-26T05:51:43.165776Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.146:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.146:2380","--initial-cluster=instance=https://199.19.213.146:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.146:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.146:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-26T05:51:43.165843Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-26T05:51:43.165852Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.146:2380"]} {"level":"info","ts":"2026-05-26T05:51:43.16587Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-26T05:51:43.166485Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.146:2379"]} {"level":"info","ts":"2026-05-26T05:51:43.166568Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.146:2380"],"listen-peer-urls":["https://199.19.213.146:2380"],"advertise-client-urls":["https://199.19.213.146:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.146:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.146:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-26T05:51:43.172892Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.099672ms"} {"level":"info","ts":"2026-05-26T05:51:43.185602Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c8e9c1aa9bb537b7","cluster-id":"933c0a8774274c15"} {"level":"info","ts":"2026-05-26T05:51:43.185669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 switched to configuration voters=()"} {"level":"info","ts":"2026-05-26T05:51:43.185685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became follower at term 0"} {"level":"info","ts":"2026-05-26T05:51:43.185698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c8e9c1aa9bb537b7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-26T05:51:43.185727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became follower at term 1"} {"level":"info","ts":"2026-05-26T05:51:43.18576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 switched to configuration voters=(14477315415660115895)"} {"level":"warn","ts":"2026-05-26T05:51:43.20201Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-26T05:51:43.204926Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-26T05:51:43.207501Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-26T05:51:43.211667Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c8e9c1aa9bb537b7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-26T05:51:43.211776Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c8e9c1aa9bb537b7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-26T05:51:43.212064Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-26T05:51:43.212176Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-26T05:51:43.212191Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-26T05:51:43.214521Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-26T05:51:43.214644Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.146:2380"} {"level":"info","ts":"2026-05-26T05:51:43.214758Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c8e9c1aa9bb537b7","initial-advertise-peer-urls":["https://199.19.213.146:2380"],"listen-peer-urls":["https://199.19.213.146:2380"],"advertise-client-urls":["https://199.19.213.146:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.146:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-26T05:51:43.214793Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-26T05:51:43.214795Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.146:2380"} {"level":"info","ts":"2026-05-26T05:51:43.216159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 switched to configuration voters=(14477315415660115895)"} {"level":"info","ts":"2026-05-26T05:51:43.216296Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"933c0a8774274c15","local-member-id":"c8e9c1aa9bb537b7","added-peer-id":"c8e9c1aa9bb537b7","added-peer-peer-urls":["https://199.19.213.146:2380"]} {"level":"info","ts":"2026-05-26T05:51:43.286438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 is starting a new election at term 1"} {"level":"info","ts":"2026-05-26T05:51:43.2865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-26T05:51:43.28653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 received MsgPreVoteResp from c8e9c1aa9bb537b7 at term 1"} {"level":"info","ts":"2026-05-26T05:51:43.286549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became candidate at term 2"} {"level":"info","ts":"2026-05-26T05:51:43.28656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 received MsgVoteResp from c8e9c1aa9bb537b7 at term 2"} {"level":"info","ts":"2026-05-26T05:51:43.286577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became leader at term 2"} {"level":"info","ts":"2026-05-26T05:51:43.28659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c8e9c1aa9bb537b7 elected leader c8e9c1aa9bb537b7 at term 2"} {"level":"info","ts":"2026-05-26T05:51:43.287511Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c8e9c1aa9bb537b7","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.146:2379]}","request-path":"/0/members/c8e9c1aa9bb537b7/attributes","cluster-id":"933c0a8774274c15","publish-timeout":"7s"} {"level":"info","ts":"2026-05-26T05:51:43.287683Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T05:51:43.287829Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T05:51:43.287836Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T05:51:43.288761Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"933c0a8774274c15","local-member-id":"c8e9c1aa9bb537b7","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T05:51:43.288871Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T05:51:43.288908Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T05:51:43.290853Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-26T05:51:43.290934Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-26T05:51:43.292058Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.146:2379"} {"level":"info","ts":"2026-05-26T05:51:43.292613Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-26T05:52:06.473911Z","caller":"traceutil/trace.go:171","msg":"trace[1041063219] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"238.261037ms","start":"2026-05-26T05:52:06.235625Z","end":"2026-05-26T05:52:06.473886Z","steps":["trace[1041063219] 'process raft request' (duration: 238.038971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:11.96122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.272743ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-05-26T05:52:19.7334Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4014851740171392380,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-26T05:52:19.843313Z","caller":"traceutil/trace.go:171","msg":"trace[642494209] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"670.103061ms","start":"2026-05-26T05:52:19.173179Z","end":"2026-05-26T05:52:19.843283Z","steps":["trace[642494209] 'process raft request' (duration: 669.930167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:19.843905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:19.173148Z","time spent":"670.253334ms","remote":"127.0.0.1:49374","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-05-26T05:52:19.985031Z","caller":"traceutil/trace.go:171","msg":"trace[932128754] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:543; }","duration":"752.34877ms","start":"2026-05-26T05:52:19.23266Z","end":"2026-05-26T05:52:19.985008Z","steps":["trace[932128754] 'read index received' (duration: 610.520934ms)","trace[932128754] 'applied index is now lower than readState.Index' (duration: 141.826866ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:52:19.985159Z","caller":"traceutil/trace.go:171","msg":"trace[195319695] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"645.592245ms","start":"2026-05-26T05:52:19.339515Z","end":"2026-05-26T05:52:19.985107Z","steps":["trace[195319695] 'process raft request' (duration: 571.076577ms)","trace[195319695] 'compare' (duration: 74.326143ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:52:19.98575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:19.339493Z","time spent":"646.183578ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T05:52:19.985272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.562725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-26T05:52:19.985836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.139173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-26T05:52:19.986012Z","caller":"traceutil/trace.go:171","msg":"trace[1929317686] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:528; }","duration":"739.319837ms","start":"2026-05-26T05:52:19.246636Z","end":"2026-05-26T05:52:19.985956Z","steps":["trace[1929317686] 'agreement among raft nodes before linearized reading' (duration: 738.994199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:19.986081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:19.246615Z","time spent":"739.44937ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-26T05:52:19.986Z","caller":"traceutil/trace.go:171","msg":"trace[851015759] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:528; }","duration":"753.263452ms","start":"2026-05-26T05:52:19.232609Z","end":"2026-05-26T05:52:19.985872Z","steps":["trace[851015759] 'agreement among raft nodes before linearized reading' (duration: 752.557015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:19.98633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:19.232587Z","time spent":"753.663282ms","remote":"127.0.0.1:49214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-05-26T05:52:26.122529Z","caller":"traceutil/trace.go:171","msg":"trace[1960135948] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"172.856365ms","start":"2026-05-26T05:52:25.949645Z","end":"2026-05-26T05:52:26.122501Z","steps":["trace[1960135948] 'read index received' (duration: 139.100948ms)","trace[1960135948] 'applied index is now lower than readState.Index' (duration: 33.754147ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:52:26.122657Z","caller":"traceutil/trace.go:171","msg":"trace[1509720528] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"214.544748ms","start":"2026-05-26T05:52:25.908101Z","end":"2026-05-26T05:52:26.122646Z","steps":["trace[1509720528] 'process raft request' (duration: 180.592217ms)","trace[1509720528] 'compare' (duration: 33.440909ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:52:26.123019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.362488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-26T05:52:26.123099Z","caller":"traceutil/trace.go:171","msg":"trace[102094722] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"173.488501ms","start":"2026-05-26T05:52:25.949591Z","end":"2026-05-26T05:52:26.123079Z","steps":["trace[102094722] 'agreement among raft nodes before linearized reading' (duration: 173.334677ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:52:26.427664Z","caller":"traceutil/trace.go:171","msg":"trace[364035485] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"300.567371ms","start":"2026-05-26T05:52:26.127069Z","end":"2026-05-26T05:52:26.427636Z","steps":["trace[364035485] 'process raft request' (duration: 300.242553ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:52:26.428463Z","caller":"traceutil/trace.go:171","msg":"trace[394141042] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"277.239703ms","start":"2026-05-26T05:52:26.151177Z","end":"2026-05-26T05:52:26.428417Z","steps":["trace[394141042] 'process raft request' (duration: 276.427682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:26.428455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:26.127047Z","time spent":"300.687014ms","remote":"127.0.0.1:49374","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-05-26T05:52:27.254432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.480476ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-05-26T05:52:27.254604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:26.457874Z","time spent":"796.724442ms","remote":"127.0.0.1:49148","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-26T05:52:27.547273Z","caller":"traceutil/trace.go:171","msg":"trace[1798053184] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:658; }","duration":"982.978078ms","start":"2026-05-26T05:52:26.56427Z","end":"2026-05-26T05:52:27.547248Z","steps":["trace[1798053184] 'read index received' (duration: 352.678503ms)","trace[1798053184] 'applied index is now lower than readState.Index' (duration: 630.298275ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:52:27.547353Z","caller":"traceutil/trace.go:171","msg":"trace[590232381] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.065301059s","start":"2026-05-26T05:52:26.482015Z","end":"2026-05-26T05:52:27.547316Z","steps":["trace[590232381] 'process raft request' (duration: 1.065080504s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:27.547558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.270366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-w5tv5\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-26T05:52:27.547609Z","caller":"traceutil/trace.go:171","msg":"trace[1941491555] range","detail":"{range_begin:/registry/pods/kube-system/cilium-w5tv5; range_end:; response_count:1; response_revision:636; }","duration":"983.381569ms","start":"2026-05-26T05:52:26.564215Z","end":"2026-05-26T05:52:27.547596Z","steps":["trace[1941491555] 'agreement among raft nodes before linearized reading' (duration: 983.147053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:27.547645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:26.564193Z","time spent":"983.44405ms","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-w5tv5\" "} {"level":"warn","ts":"2026-05-26T05:52:27.547771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:26.481983Z","time spent":"1.065617477s","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T05:52:27.614862Z","caller":"traceutil/trace.go:171","msg":"trace[1700114935] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"359.101052ms","start":"2026-05-26T05:52:27.255738Z","end":"2026-05-26T05:52:27.614839Z","steps":["trace[1700114935] 'process raft request' (duration: 358.960929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:27.615016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:27.25569Z","time spent":"359.240025ms","remote":"127.0.0.1:49148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T05:52:27.615244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.578922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-26T05:52:27.615348Z","caller":"traceutil/trace.go:171","msg":"trace[517281583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:637; }","duration":"678.731096ms","start":"2026-05-26T05:52:26.936589Z","end":"2026-05-26T05:52:27.61532Z","steps":["trace[517281583] 'agreement among raft nodes before linearized reading' (duration: 678.417798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:27.615408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:26.936563Z","time spent":"678.832679ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-26T05:52:27.615259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.104663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:52:27.615576Z","caller":"traceutil/trace.go:171","msg":"trace[2015952429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"549.575024ms","start":"2026-05-26T05:52:27.06597Z","end":"2026-05-26T05:52:27.615545Z","steps":["trace[2015952429] 'agreement among raft nodes before linearized reading' (duration: 549.072912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:27.615706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:27.065949Z","time spent":"549.725428ms","remote":"127.0.0.1:49112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T05:52:27.615915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022953781s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-26T05:52:27.615989Z","caller":"traceutil/trace.go:171","msg":"trace[1347164390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:637; }","duration":"1.023066564s","start":"2026-05-26T05:52:26.592904Z","end":"2026-05-26T05:52:27.615971Z","steps":["trace[1347164390] 'agreement among raft nodes before linearized reading' (duration: 1.022096939s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:27.616044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:26.592882Z","time spent":"1.023148715s","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-26T05:52:28.780317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.134268118s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T05:52:28.78051Z","caller":"traceutil/trace.go:171","msg":"trace[1408007761] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.160395086s","start":"2026-05-26T05:52:27.620102Z","end":"2026-05-26T05:52:28.780497Z","steps":["trace[1408007761] 'process raft request' (duration: 1.160365675s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:28.780559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:27.620086Z","time spent":"1.160450638s","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:52:28.780699Z","caller":"traceutil/trace.go:171","msg":"trace[915735453] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.214873536s","start":"2026-05-26T05:52:27.565818Z","end":"2026-05-26T05:52:28.780691Z","steps":["trace[915735453] 'process raft request' (duration: 80.152677ms)","trace[915735453] 'compare' (duration: 1.134096934s)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:52:28.780732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:27.565808Z","time spent":"1.214906507s","remote":"127.0.0.1:49300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:52:28.780823Z","caller":"traceutil/trace.go:171","msg":"trace[1319769347] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.160774636s","start":"2026-05-26T05:52:27.620043Z","end":"2026-05-26T05:52:28.780818Z","steps":["trace[1319769347] 'process raft request' (duration: 1.160372256s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:28.780859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:27.620014Z","time spent":"1.160822617s","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:52:28.780899Z","caller":"traceutil/trace.go:171","msg":"trace[1226040426] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"1.165897093s","start":"2026-05-26T05:52:27.614974Z","end":"2026-05-26T05:52:28.780871Z","steps":["trace[1226040426] 'read index received' (duration: 30.924627ms)","trace[1226040426] 'applied index is now lower than readState.Index' (duration: 1.134970436s)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:52:28.781094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.217594324s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-26T05:52:28.781143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.164413865s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:52:28.781152Z","caller":"traceutil/trace.go:171","msg":"trace[498248468] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:640; }","duration":"1.217689656s","start":"2026-05-26T05:52:27.563444Z","end":"2026-05-26T05:52:28.781133Z","steps":["trace[498248468] 'agreement among raft nodes before linearized reading' (duration: 1.217588114s)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:52:28.781166Z","caller":"traceutil/trace.go:171","msg":"trace[1765899538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"1.164466907s","start":"2026-05-26T05:52:27.616693Z","end":"2026-05-26T05:52:28.78116Z","steps":["trace[1765899538] 'agreement among raft nodes before linearized reading' (duration: 1.164427766s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:28.781191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:27.616676Z","time spent":"1.164511508s","remote":"127.0.0.1:49122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T05:52:28.781203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:27.563417Z","time spent":"1.217773469s","remote":"127.0.0.1:49342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-05-26T05:52:28.781345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.820033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-26T05:52:28.781363Z","caller":"traceutil/trace.go:171","msg":"trace[1436553710] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:640; }","duration":"345.874415ms","start":"2026-05-26T05:52:28.435483Z","end":"2026-05-26T05:52:28.781358Z","steps":["trace[1436553710] 'agreement among raft nodes before linearized reading' (duration: 345.787873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:28.78138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:28.435462Z","time spent":"345.914686ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-26T05:52:28.781486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.440563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-26T05:52:28.781503Z","caller":"traceutil/trace.go:171","msg":"trace[1689673638] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:640; }","duration":"610.493554ms","start":"2026-05-26T05:52:28.171004Z","end":"2026-05-26T05:52:28.781498Z","steps":["trace[1689673638] 'agreement among raft nodes before linearized reading' (duration: 610.439903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:28.781522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:28.170984Z","time spent":"610.534055ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-26T05:52:28.781683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.164734834s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-26T05:52:28.78173Z","caller":"traceutil/trace.go:171","msg":"trace[419566652] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:640; }","duration":"1.164796226s","start":"2026-05-26T05:52:27.61692Z","end":"2026-05-26T05:52:28.781716Z","steps":["trace[419566652] 'agreement among raft nodes before linearized reading' (duration: 1.164709063s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:28.781768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:27.616912Z","time spent":"1.164847067s","remote":"127.0.0.1:49274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-05-26T05:52:29.28673Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4014851740171392636,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-26T05:52:29.487508Z","caller":"traceutil/trace.go:171","msg":"trace[501967777] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"701.584893ms","start":"2026-05-26T05:52:28.785897Z","end":"2026-05-26T05:52:29.487482Z","steps":["trace[501967777] 'process raft request' (duration: 701.367298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:29.487645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:28.785882Z","time spent":"701.692336ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:52:29.504083Z","caller":"traceutil/trace.go:171","msg":"trace[619555051] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"717.282863ms","start":"2026-05-26T05:52:28.786769Z","end":"2026-05-26T05:52:29.504052Z","steps":["trace[619555051] 'process raft request' (duration: 716.749919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:29.504252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:28.786759Z","time spent":"717.399046ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:52:29.504334Z","caller":"traceutil/trace.go:171","msg":"trace[968003158] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:664; }","duration":"717.753054ms","start":"2026-05-26T05:52:28.786559Z","end":"2026-05-26T05:52:29.504312Z","steps":["trace[968003158] 'read index received' (duration: 700.807724ms)","trace[968003158] 'applied index is now lower than readState.Index' (duration: 16.94422ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:52:29.504561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.99486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-26T05:52:29.504607Z","caller":"traceutil/trace.go:171","msg":"trace[777480323] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:643; }","duration":"718.053422ms","start":"2026-05-26T05:52:28.786542Z","end":"2026-05-26T05:52:29.504595Z","steps":["trace[777480323] 'agreement among raft nodes before linearized reading' (duration: 717.96678ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:29.504685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.669343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:52:29.504738Z","caller":"traceutil/trace.go:171","msg":"trace[43182491] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"329.5219ms","start":"2026-05-26T05:52:29.175206Z","end":"2026-05-26T05:52:29.504728Z","steps":["trace[43182491] 'process raft request' (duration: 328.657477ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:52:29.504799Z","caller":"traceutil/trace.go:171","msg":"trace[512877508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"711.786155ms","start":"2026-05-26T05:52:28.792979Z","end":"2026-05-26T05:52:29.504765Z","steps":["trace[512877508] 'agreement among raft nodes before linearized reading' (duration: 711.588871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:29.504899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:28.792972Z","time spent":"711.919128ms","remote":"127.0.0.1:49112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T05:52:29.504644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:28.786534Z","time spent":"718.100703ms","remote":"127.0.0.1:49380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-05-26T05:52:29.504814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:29.175173Z","time spent":"329.606172ms","remote":"127.0.0.1:49214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T05:52:30.982113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.921697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-26T05:52:30.98218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.885175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-26T05:52:30.98223Z","caller":"traceutil/trace.go:171","msg":"trace[1010784087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:669; }","duration":"197.959807ms","start":"2026-05-26T05:52:30.784245Z","end":"2026-05-26T05:52:30.982205Z","steps":["trace[1010784087] 'range keys from in-memory index tree' (duration: 197.751652ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:52:30.982243Z","caller":"traceutil/trace.go:171","msg":"trace[1095827775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:669; }","duration":"198.072721ms","start":"2026-05-26T05:52:30.78412Z","end":"2026-05-26T05:52:30.982193Z","steps":["trace[1095827775] 'range keys from in-memory index tree' (duration: 197.759632ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:52:30.987672Z","caller":"traceutil/trace.go:171","msg":"trace[1784850704] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"147.25206ms","start":"2026-05-26T05:52:30.840401Z","end":"2026-05-26T05:52:30.987653Z","steps":["trace[1784850704] 'process raft request' (duration: 147.119437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:45.214084Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4014851740171392941,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-26T05:52:45.538319Z","caller":"traceutil/trace.go:171","msg":"trace[1780271541] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"854.76705ms","start":"2026-05-26T05:52:44.683525Z","end":"2026-05-26T05:52:45.538292Z","steps":["trace[1780271541] 'process raft request' (duration: 854.598636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:45.538489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:44.683498Z","time spent":"854.896713ms","remote":"127.0.0.1:49342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:52:45.538743Z","caller":"traceutil/trace.go:171","msg":"trace[74407506] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:827; }","duration":"825.251268ms","start":"2026-05-26T05:52:44.71345Z","end":"2026-05-26T05:52:45.538701Z","steps":["trace[74407506] 'read index received' (duration: 825.239797ms)","trace[74407506] 'applied index is now lower than readState.Index' (duration: 9.971µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:52:45.539115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.645528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-26T05:52:45.53916Z","caller":"traceutil/trace.go:171","msg":"trace[204261039] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:799; }","duration":"825.740171ms","start":"2026-05-26T05:52:44.713409Z","end":"2026-05-26T05:52:45.539149Z","steps":["trace[204261039] 'agreement among raft nodes before linearized reading' (duration: 825.484933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:45.539239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:44.713387Z","time spent":"825.840044ms","remote":"127.0.0.1:49374","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-05-26T05:52:45.539606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.26269ms","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-05-26T05:52:45.539648Z","caller":"traceutil/trace.go:171","msg":"trace[1841283687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:799; }","duration":"489.339732ms","start":"2026-05-26T05:52:45.050299Z","end":"2026-05-26T05:52:45.539639Z","steps":["trace[1841283687] 'agreement among raft nodes before linearized reading' (duration: 489.233869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:45.53972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:45.050276Z","time spent":"489.432493ms","remote":"127.0.0.1:49374","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-05-26T05:52:45.539979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.131939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:52:45.540209Z","caller":"traceutil/trace.go:171","msg":"trace[135462458] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:799; }","duration":"373.436667ms","start":"2026-05-26T05:52:45.166754Z","end":"2026-05-26T05:52:45.540191Z","steps":["trace[135462458] 'agreement among raft nodes before linearized reading' (duration: 373.122529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:45.540358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:45.166733Z","time spent":"373.582051ms","remote":"127.0.0.1:49416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-05-26T05:52:45.53999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.552252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-26T05:52:45.540594Z","caller":"traceutil/trace.go:171","msg":"trace[1658037467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:799; }","duration":"489.114646ms","start":"2026-05-26T05:52:45.051388Z","end":"2026-05-26T05:52:45.540502Z","steps":["trace[1658037467] 'agreement among raft nodes before linearized reading' (duration: 488.526171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:45.540711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:52:45.051365Z","time spent":"489.329031ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-26T05:52:49.395567Z","caller":"traceutil/trace.go:171","msg":"trace[1077471624] linearizableReadLoop","detail":"{readStateIndex:880; appliedIndex:879; }","duration":"160.054398ms","start":"2026-05-26T05:52:49.235486Z","end":"2026-05-26T05:52:49.395541Z","steps":["trace[1077471624] 'read index received' (duration: 157.760371ms)","trace[1077471624] 'applied index is now lower than readState.Index' (duration: 2.293127ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:52:49.395776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.255552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T05:52:49.395845Z","caller":"traceutil/trace.go:171","msg":"trace[1510114617] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:850; }","duration":"160.352255ms","start":"2026-05-26T05:52:49.235474Z","end":"2026-05-26T05:52:49.395826Z","steps":["trace[1510114617] 'agreement among raft nodes before linearized reading' (duration: 160.15579ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:52:49.395695Z","caller":"traceutil/trace.go:171","msg":"trace[66108912] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"223.741687ms","start":"2026-05-26T05:52:49.171907Z","end":"2026-05-26T05:52:49.395648Z","steps":["trace[66108912] 'process raft request' (duration: 222.114238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:49.763691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.117382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-26T05:52:49.763772Z","caller":"traceutil/trace.go:171","msg":"trace[1340094122] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:850; }","duration":"198.235264ms","start":"2026-05-26T05:52:49.565519Z","end":"2026-05-26T05:52:49.763754Z","steps":["trace[1340094122] 'range keys from in-memory index tree' (duration: 197.962757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:49.763761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.312081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-26T05:52:49.763861Z","caller":"traceutil/trace.go:171","msg":"trace[406606577] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:850; }","duration":"182.444104ms","start":"2026-05-26T05:52:49.581397Z","end":"2026-05-26T05:52:49.763841Z","steps":["trace[406606577] 'range keys from in-memory index tree' (duration: 182.161768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:49.763905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.712408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-26T05:52:49.764002Z","caller":"traceutil/trace.go:171","msg":"trace[1273230342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:850; }","duration":"196.847691ms","start":"2026-05-26T05:52:49.567132Z","end":"2026-05-26T05:52:49.76398Z","steps":["trace[1273230342] 'range keys from in-memory index tree' (duration: 196.482902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:49.764056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.097502ms","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-05-26T05:52:49.764088Z","caller":"traceutil/trace.go:171","msg":"trace[1088901978] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:850; }","duration":"194.164074ms","start":"2026-05-26T05:52:49.569914Z","end":"2026-05-26T05:52:49.764078Z","steps":["trace[1088901978] 'range keys from in-memory index tree' (duration: 193.958079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:49.764135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.158807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-26T05:52:49.76417Z","caller":"traceutil/trace.go:171","msg":"trace[1458961389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:850; }","duration":"203.234839ms","start":"2026-05-26T05:52:49.560925Z","end":"2026-05-26T05:52:49.76416Z","steps":["trace[1458961389] 'range keys from in-memory index tree' (duration: 202.990393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:49.76457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.854042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050260"} {"level":"info","ts":"2026-05-26T05:52:49.764624Z","caller":"traceutil/trace.go:171","msg":"trace[369803110] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:850; }","duration":"255.946664ms","start":"2026-05-26T05:52:49.508663Z","end":"2026-05-26T05:52:49.76461Z","steps":["trace[369803110] 'range keys from in-memory index tree' (duration: 254.998811ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:52:49.986352Z","caller":"traceutil/trace.go:171","msg":"trace[528371003] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"128.591848ms","start":"2026-05-26T05:52:49.85771Z","end":"2026-05-26T05:52:49.986302Z","steps":["trace[528371003] 'process raft request' (duration: 35.216904ms)","trace[528371003] 'compare' (duration: 92.766599ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:52:54.074989Z","caller":"traceutil/trace.go:171","msg":"trace[1247818649] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"191.265503ms","start":"2026-05-26T05:52:53.883699Z","end":"2026-05-26T05:52:54.074964Z","steps":["trace[1247818649] 'process raft request' (duration: 189.932109ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:52:54.143249Z","caller":"traceutil/trace.go:171","msg":"trace[2045358255] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"210.129939ms","start":"2026-05-26T05:52:53.933089Z","end":"2026-05-26T05:52:54.143219Z","steps":["trace[2045358255] 'process raft request' (duration: 209.899574ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:52:54.26331Z","caller":"traceutil/trace.go:171","msg":"trace[1731766875] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"182.794432ms","start":"2026-05-26T05:52:54.080487Z","end":"2026-05-26T05:52:54.263281Z","steps":["trace[1731766875] 'process raft request' (duration: 130.614028ms)","trace[1731766875] 'compare' (duration: 52.00357ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:52:54.263369Z","caller":"traceutil/trace.go:171","msg":"trace[513596607] linearizableReadLoop","detail":"{readStateIndex:949; appliedIndex:944; }","duration":"120.19697ms","start":"2026-05-26T05:52:54.143154Z","end":"2026-05-26T05:52:54.263351Z","steps":["trace[513596607] 'read index received' (duration: 67.922514ms)","trace[513596607] 'applied index is now lower than readState.Index' (duration: 52.273756ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:52:54.26348Z","caller":"traceutil/trace.go:171","msg":"trace[415915786] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"180.992498ms","start":"2026-05-26T05:52:54.082471Z","end":"2026-05-26T05:52:54.263464Z","steps":["trace[415915786] 'process raft request' (duration: 180.788013ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:52:54.263535Z","caller":"traceutil/trace.go:171","msg":"trace[2012419901] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"180.25446ms","start":"2026-05-26T05:52:54.083275Z","end":"2026-05-26T05:52:54.263529Z","steps":["trace[2012419901] 'process raft request' (duration: 180.044744ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:52:54.263567Z","caller":"traceutil/trace.go:171","msg":"trace[1730951276] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"180.547826ms","start":"2026-05-26T05:52:54.083013Z","end":"2026-05-26T05:52:54.263561Z","steps":["trace[1730951276] 'process raft request' (duration: 180.27239ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:52:54.263656Z","caller":"traceutil/trace.go:171","msg":"trace[1757744405] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"181.745106ms","start":"2026-05-26T05:52:54.081906Z","end":"2026-05-26T05:52:54.263651Z","steps":["trace[1757744405] 'process raft request' (duration: 181.312695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:54.263647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.102488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:52:54.263706Z","caller":"traceutil/trace.go:171","msg":"trace[419482987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:918; }","duration":"118.209651ms","start":"2026-05-26T05:52:54.145483Z","end":"2026-05-26T05:52:54.263693Z","steps":["trace[419482987] 'agreement among raft nodes before linearized reading' (duration: 118.086468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:52:54.263841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.931091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-xb42z\" ","response":"range_response_count:1 size:4881"} {"level":"info","ts":"2026-05-26T05:52:54.263865Z","caller":"traceutil/trace.go:171","msg":"trace[716362576] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-xb42z; range_end:; response_count:1; response_revision:918; }","duration":"181.991242ms","start":"2026-05-26T05:52:54.081867Z","end":"2026-05-26T05:52:54.263858Z","steps":["trace[716362576] 'agreement among raft nodes before linearized reading' (duration: 181.886359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:53:21.568211Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4014851740171393411,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-26T05:53:21.569197Z","caller":"traceutil/trace.go:171","msg":"trace[1400407575] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"687.936209ms","start":"2026-05-26T05:53:20.881241Z","end":"2026-05-26T05:53:21.569177Z","steps":["trace[1400407575] 'process raft request' (duration: 687.818106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:53:21.569297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:53:20.881212Z","time spent":"688.02062ms","remote":"127.0.0.1:49374","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-05-26T05:53:22.238576Z","caller":"traceutil/trace.go:171","msg":"trace[530227677] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1059; }","duration":"1.17114183s","start":"2026-05-26T05:53:21.067412Z","end":"2026-05-26T05:53:22.238553Z","steps":["trace[530227677] 'read index received' (duration: 502.214001ms)","trace[530227677] 'applied index is now lower than readState.Index' (duration: 668.926889ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:53:22.238612Z","caller":"traceutil/trace.go:171","msg":"trace[292317764] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"1.318617032s","start":"2026-05-26T05:53:20.91998Z","end":"2026-05-26T05:53:22.238597Z","steps":["trace[292317764] 'process raft request' (duration: 1.318436738s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:53:22.238716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:53:20.919955Z","time spent":"1.318701165s","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T05:53:22.238711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171294174s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:53:22.238755Z","caller":"traceutil/trace.go:171","msg":"trace[1871901127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1023; }","duration":"1.171377846s","start":"2026-05-26T05:53:21.067367Z","end":"2026-05-26T05:53:22.238745Z","steps":["trace[1871901127] 'agreement among raft nodes before linearized reading' (duration: 1.171301484s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:53:22.238793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:53:21.067339Z","time spent":"1.171447348s","remote":"127.0.0.1:49112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-26T05:53:22.47219Z","caller":"traceutil/trace.go:171","msg":"trace[437993104] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1060; }","duration":"233.532786ms","start":"2026-05-26T05:53:22.238644Z","end":"2026-05-26T05:53:22.472177Z","steps":["trace[437993104] 'read index received' (duration: 230.45556ms)","trace[437993104] 'applied index is now lower than readState.Index' (duration: 3.076306ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:53:22.472321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.906076ms","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-05-26T05:53:22.472325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.205052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-26T05:53:22.472342Z","caller":"traceutil/trace.go:171","msg":"trace[755116640] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1023; }","duration":"410.973388ms","start":"2026-05-26T05:53:22.061363Z","end":"2026-05-26T05:53:22.472337Z","steps":["trace[755116640] 'agreement among raft nodes before linearized reading' (duration: 410.884116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:53:22.472359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:53:22.061343Z","time spent":"411.011938ms","remote":"127.0.0.1:49374","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-05-26T05:53:22.472358Z","caller":"traceutil/trace.go:171","msg":"trace[811530983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1023; }","duration":"270.263873ms","start":"2026-05-26T05:53:22.202086Z","end":"2026-05-26T05:53:22.472349Z","steps":["trace[811530983] 'agreement among raft nodes before linearized reading' (duration: 270.13962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:53:22.472346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.491311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-26T05:53:22.472346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.241083ms","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-05-26T05:53:22.472399Z","caller":"traceutil/trace.go:171","msg":"trace[2063370973] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1023; }","duration":"298.570232ms","start":"2026-05-26T05:53:22.173816Z","end":"2026-05-26T05:53:22.472387Z","steps":["trace[2063370973] 'agreement among raft nodes before linearized reading' (duration: 298.425939ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:53:22.472417Z","caller":"traceutil/trace.go:171","msg":"trace[14049040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1023; }","duration":"270.339785ms","start":"2026-05-26T05:53:22.202062Z","end":"2026-05-26T05:53:22.472402Z","steps":["trace[14049040] 'agreement among raft nodes before linearized reading' (duration: 270.13308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:53:22.472435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.481661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:53:22.472453Z","caller":"traceutil/trace.go:171","msg":"trace[1750567143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1023; }","duration":"232.535903ms","start":"2026-05-26T05:53:22.239912Z","end":"2026-05-26T05:53:22.472447Z","steps":["trace[1750567143] 'agreement among raft nodes before linearized reading' (duration: 232.507812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:53:22.472553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.93759ms","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-05-26T05:53:22.472582Z","caller":"traceutil/trace.go:171","msg":"trace[2027086647] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1023; }","duration":"290.003812ms","start":"2026-05-26T05:53:22.182572Z","end":"2026-05-26T05:53:22.472576Z","steps":["trace[2027086647] 'agreement among raft nodes before linearized reading' (duration: 289.91837ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:53:26.772442Z","caller":"traceutil/trace.go:171","msg":"trace[319916966] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"209.233816ms","start":"2026-05-26T05:53:26.563179Z","end":"2026-05-26T05:53:26.772413Z","steps":["trace[319916966] 'process raft request' (duration: 118.003444ms)","trace[319916966] 'compare' (duration: 90.862283ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:53:26.772534Z","caller":"traceutil/trace.go:171","msg":"trace[2047981840] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"205.709049ms","start":"2026-05-26T05:53:26.566819Z","end":"2026-05-26T05:53:26.772528Z","steps":["trace[2047981840] 'process raft request' (duration: 205.592076ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:53:26.772514Z","caller":"traceutil/trace.go:171","msg":"trace[943193062] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"206.853837ms","start":"2026-05-26T05:53:26.565627Z","end":"2026-05-26T05:53:26.772481Z","steps":["trace[943193062] 'process raft request' (duration: 206.683733ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:53:26.772454Z","caller":"traceutil/trace.go:171","msg":"trace[1049071310] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"205.691909ms","start":"2026-05-26T05:53:26.566742Z","end":"2026-05-26T05:53:26.772434Z","steps":["trace[1049071310] 'process raft request' (duration: 205.645927ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:53:26.772831Z","caller":"traceutil/trace.go:171","msg":"trace[694645388] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"205.347751ms","start":"2026-05-26T05:53:26.567476Z","end":"2026-05-26T05:53:26.772824Z","steps":["trace[694645388] 'process raft request' (duration: 205.029722ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:53:26.772896Z","caller":"traceutil/trace.go:171","msg":"trace[73617864] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"205.2806ms","start":"2026-05-26T05:53:26.567605Z","end":"2026-05-26T05:53:26.772885Z","steps":["trace[73617864] 'process raft request' (duration: 205.188957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:53:43.359915Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4014851740171393590,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T05:53:43.860158Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4014851740171393590,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T05:53:43.86264Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.002947716s","expected-duration":"1s"} {"level":"info","ts":"2026-05-26T05:53:43.863913Z","caller":"traceutil/trace.go:171","msg":"trace[1385349684] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1131; }","duration":"1.004047344s","start":"2026-05-26T05:53:42.859813Z","end":"2026-05-26T05:53:43.863861Z","steps":["trace[1385349684] 'read index received' (duration: 1.002974127s)","trace[1385349684] 'applied index is now lower than readState.Index' (duration: 1.072107ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:53:43.864286Z","caller":"traceutil/trace.go:171","msg":"trace[1763292406] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"1.00470719s","start":"2026-05-26T05:53:42.859566Z","end":"2026-05-26T05:53:43.864273Z","steps":["trace[1763292406] 'process raft request' (duration: 1.003353846s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:53:43.864476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:53:42.859526Z","time spent":"1.004803052s","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T05:53:43.864865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005032628s","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-05-26T05:53:43.864911Z","caller":"traceutil/trace.go:171","msg":"trace[2043486044] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1090; }","duration":"1.00511298s","start":"2026-05-26T05:53:42.859787Z","end":"2026-05-26T05:53:43.8649Z","steps":["trace[2043486044] 'agreement among raft nodes before linearized reading' (duration: 1.004967047s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:53:43.864986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:53:42.859771Z","time spent":"1.005205402s","remote":"127.0.0.1:49374","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-05-26T05:53:43.865212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0050758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-26T05:53:43.86525Z","caller":"traceutil/trace.go:171","msg":"trace[1515422086] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1090; }","duration":"1.005221393s","start":"2026-05-26T05:53:42.86002Z","end":"2026-05-26T05:53:43.865241Z","steps":["trace[1515422086] 'agreement among raft nodes before linearized reading' (duration: 1.00513082s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:53:43.865277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:53:42.860001Z","time spent":"1.005268964s","remote":"127.0.0.1:49374","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-05-26T05:53:43.865471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.265654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:53:43.865502Z","caller":"traceutil/trace.go:171","msg":"trace[491653365] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1090; }","duration":"268.307826ms","start":"2026-05-26T05:53:43.597183Z","end":"2026-05-26T05:53:43.865491Z","steps":["trace[491653365] 'agreement among raft nodes before linearized reading' (duration: 268.258925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:53:43.865972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.007977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:53:43.866016Z","caller":"traceutil/trace.go:171","msg":"trace[1730992746] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1090; }","duration":"349.091639ms","start":"2026-05-26T05:53:43.516914Z","end":"2026-05-26T05:53:43.866006Z","steps":["trace[1730992746] 'agreement among raft nodes before linearized reading' (duration: 349.020028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:53:43.866085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:53:43.516896Z","time spent":"349.178102ms","remote":"127.0.0.1:47648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-26T05:53:43.866573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.651746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-26T05:53:43.866553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004928185s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-26T05:53:43.866626Z","caller":"traceutil/trace.go:171","msg":"trace[202190154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1090; }","duration":"799.750089ms","start":"2026-05-26T05:53:43.066865Z","end":"2026-05-26T05:53:43.866615Z","steps":["trace[202190154] 'agreement among raft nodes before linearized reading' (duration: 799.675116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:53:43.866664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:53:43.066845Z","time spent":"799.8114ms","remote":"127.0.0.1:49112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-26T05:53:43.866668Z","caller":"traceutil/trace.go:171","msg":"trace[120988886] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1090; }","duration":"1.00509556s","start":"2026-05-26T05:53:42.861552Z","end":"2026-05-26T05:53:43.866648Z","steps":["trace[120988886] 'agreement among raft nodes before linearized reading' (duration: 1.004182927s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:53:43.866719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:53:42.861543Z","time spent":"1.005164271s","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-26T05:53:43.866568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.390602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T05:53:43.866847Z","caller":"traceutil/trace.go:171","msg":"trace[1297144231] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1090; }","duration":"269.70696ms","start":"2026-05-26T05:53:43.597121Z","end":"2026-05-26T05:53:43.866828Z","steps":["trace[1297144231] 'agreement among raft nodes before linearized reading' (duration: 269.32581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:53:43.867162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.925211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T05:53:43.867267Z","caller":"traceutil/trace.go:171","msg":"trace[1474468892] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1090; }","duration":"283.289846ms","start":"2026-05-26T05:53:43.583958Z","end":"2026-05-26T05:53:43.867247Z","steps":["trace[1474468892] 'agreement among raft nodes before linearized reading' (duration: 281.89245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:53:43.867293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005691484s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-26T05:53:43.867341Z","caller":"traceutil/trace.go:171","msg":"trace[1498366687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1090; }","duration":"1.005767937s","start":"2026-05-26T05:53:42.861559Z","end":"2026-05-26T05:53:43.867327Z","steps":["trace[1498366687] 'agreement among raft nodes before linearized reading' (duration: 1.005576982s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:53:43.867372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:53:42.861544Z","time spent":"1.005822037s","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-26T05:53:44.458815Z","caller":"traceutil/trace.go:171","msg":"trace[375991999] linearizableReadLoop","detail":"{readStateIndex:1136; appliedIndex:1132; }","duration":"391.629931ms","start":"2026-05-26T05:53:44.06715Z","end":"2026-05-26T05:53:44.45878Z","steps":["trace[375991999] 'read index received' (duration: 304.309025ms)","trace[375991999] 'applied index is now lower than readState.Index' (duration: 87.319206ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:53:44.459101Z","caller":"traceutil/trace.go:171","msg":"trace[1020107486] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"586.83855ms","start":"2026-05-26T05:53:43.872216Z","end":"2026-05-26T05:53:44.459055Z","steps":["trace[1020107486] 'process raft request' (duration: 586.301947ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:53:44.459177Z","caller":"traceutil/trace.go:171","msg":"trace[2036461066] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"586.316538ms","start":"2026-05-26T05:53:43.872836Z","end":"2026-05-26T05:53:44.459153Z","steps":["trace[2036461066] 'process raft request' (duration: 585.807825ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:53:44.459128Z","caller":"traceutil/trace.go:171","msg":"trace[766080069] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"586.220096ms","start":"2026-05-26T05:53:43.87288Z","end":"2026-05-26T05:53:44.4591Z","steps":["trace[766080069] 'process raft request' (duration: 585.811376ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:53:44.459207Z","caller":"traceutil/trace.go:171","msg":"trace[1135276154] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"588.597785ms","start":"2026-05-26T05:53:43.870543Z","end":"2026-05-26T05:53:44.459141Z","steps":["trace[1135276154] 'process raft request' (duration: 500.798317ms)","trace[1135276154] 'compare' (duration: 86.736392ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:53:44.459342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:53:43.872201Z","time spent":"587.000085ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T05:53:44.459408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.058172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:53:44.459562Z","caller":"traceutil/trace.go:171","msg":"trace[1589727060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1095; }","duration":"392.367339ms","start":"2026-05-26T05:53:44.067108Z","end":"2026-05-26T05:53:44.459475Z","steps":["trace[1589727060] 'agreement among raft nodes before linearized reading' (duration: 391.805735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:53:44.459494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:53:43.87052Z","time spent":"588.83913ms","remote":"127.0.0.1:49374","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-05-26T05:53:44.45913Z","caller":"traceutil/trace.go:171","msg":"trace[199772757] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"101.592949ms","start":"2026-05-26T05:53:44.357517Z","end":"2026-05-26T05:53:44.45911Z","steps":["trace[199772757] 'process raft request' (duration: 101.21714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:53:44.459665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:53:43.872858Z","time spent":"586.426871ms","remote":"127.0.0.1:49374","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-05-26T05:53:44.459628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:53:44.067062Z","time spent":"392.550444ms","remote":"127.0.0.1:49112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T05:53:44.459777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:53:43.872819Z","time spent":"586.410301ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:53:46.089388Z","caller":"traceutil/trace.go:171","msg":"trace[1717692746] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"211.301567ms","start":"2026-05-26T05:53:45.878051Z","end":"2026-05-26T05:53:46.089353Z","steps":["trace[1717692746] 'process raft request' (duration: 211.033581ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:53:46.599783Z","caller":"traceutil/trace.go:171","msg":"trace[331504170] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"130.935703ms","start":"2026-05-26T05:53:46.468819Z","end":"2026-05-26T05:53:46.599755Z","steps":["trace[331504170] 'process raft request' (duration: 130.837181ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:53:46.5998Z","caller":"traceutil/trace.go:171","msg":"trace[1869899056] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"132.724667ms","start":"2026-05-26T05:53:46.467041Z","end":"2026-05-26T05:53:46.599766Z","steps":["trace[1869899056] 'process raft request' (duration: 83.656156ms)","trace[1869899056] 'compare' (duration: 48.767944ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:53:46.600092Z","caller":"traceutil/trace.go:171","msg":"trace[392057169] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"130.302317ms","start":"2026-05-26T05:53:46.469769Z","end":"2026-05-26T05:53:46.600072Z","steps":["trace[392057169] 'process raft request' (duration: 129.946108ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:53:46.600355Z","caller":"traceutil/trace.go:171","msg":"trace[1309230787] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"130.205755ms","start":"2026-05-26T05:53:46.470128Z","end":"2026-05-26T05:53:46.600333Z","steps":["trace[1309230787] 'process raft request' (duration: 129.940019ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:53:46.600693Z","caller":"traceutil/trace.go:171","msg":"trace[1425665661] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"101.794294ms","start":"2026-05-26T05:53:46.498638Z","end":"2026-05-26T05:53:46.600432Z","steps":["trace[1425665661] 'process raft request' (duration: 101.746342ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:53:46.600772Z","caller":"traceutil/trace.go:171","msg":"trace[2059629140] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"105.721731ms","start":"2026-05-26T05:53:46.495018Z","end":"2026-05-26T05:53:46.600739Z","steps":["trace[2059629140] 'process raft request' (duration: 105.265679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:53:49.126757Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4014851740171393638,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-26T05:53:49.510353Z","caller":"traceutil/trace.go:171","msg":"trace[2116939968] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"904.148407ms","start":"2026-05-26T05:53:48.606182Z","end":"2026-05-26T05:53:49.51033Z","steps":["trace[2116939968] 'process raft request' (duration: 903.975573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:53:49.51052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:53:48.606166Z","time spent":"904.260599ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:53:49.515847Z","caller":"traceutil/trace.go:171","msg":"trace[820543572] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"908.145236ms","start":"2026-05-26T05:53:48.607683Z","end":"2026-05-26T05:53:49.515828Z","steps":["trace[820543572] 'process raft request' (duration: 907.88659ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:53:49.515902Z","caller":"traceutil/trace.go:171","msg":"trace[193846224] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"908.116814ms","start":"2026-05-26T05:53:48.60777Z","end":"2026-05-26T05:53:49.515887Z","steps":["trace[193846224] 'process raft request' (duration: 907.92992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:53:49.51595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:53:48.607669Z","time spent":"908.231828ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:53:49.515959Z","caller":"traceutil/trace.go:171","msg":"trace[1489302967] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"907.296225ms","start":"2026-05-26T05:53:48.608627Z","end":"2026-05-26T05:53:49.515923Z","steps":["trace[1489302967] 'process raft request' (duration: 907.149622ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:53:49.51594Z","caller":"traceutil/trace.go:171","msg":"trace[2007546488] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1147; }","duration":"890.212923ms","start":"2026-05-26T05:53:48.625699Z","end":"2026-05-26T05:53:49.515912Z","steps":["trace[2007546488] 'read index received' (duration: 884.42424ms)","trace[2007546488] 'applied index is now lower than readState.Index' (duration: 5.787223ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:53:49.515834Z","caller":"traceutil/trace.go:171","msg":"trace[773515247] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"907.678345ms","start":"2026-05-26T05:53:48.608131Z","end":"2026-05-26T05:53:49.51581Z","steps":["trace[773515247] 'process raft request' (duration: 907.614343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:53:49.516052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:53:48.607753Z","time spent":"908.187567ms","remote":"127.0.0.1:49374","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-05-26T05:53:49.516053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.338926ms","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-05-26T05:53:49.516098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:53:48.60856Z","time spent":"907.461549ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:53:49.516108Z","caller":"traceutil/trace.go:171","msg":"trace[1400491866] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1109; }","duration":"890.419188ms","start":"2026-05-26T05:53:48.625673Z","end":"2026-05-26T05:53:49.516092Z","steps":["trace[1400491866] 'agreement among raft nodes before linearized reading' (duration: 890.329236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:53:49.516128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.930607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-26T05:53:49.516145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:53:48.625663Z","time spent":"890.472809ms","remote":"127.0.0.1:45822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":3,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-05-26T05:53:49.516168Z","caller":"traceutil/trace.go:171","msg":"trace[1482531904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1109; }","duration":"444.995169ms","start":"2026-05-26T05:53:49.07116Z","end":"2026-05-26T05:53:49.516155Z","steps":["trace[1482531904] 'agreement among raft nodes before linearized reading' (duration: 444.937898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:53:49.516197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:53:49.071146Z","time spent":"445.04539ms","remote":"127.0.0.1:49112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T05:53:49.51651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:53:48.608121Z","time spent":"907.937631ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:54:03.693121Z","caller":"traceutil/trace.go:171","msg":"trace[557512830] linearizableReadLoop","detail":"{readStateIndex:1250; appliedIndex:1249; }","duration":"124.35145ms","start":"2026-05-26T05:54:03.568749Z","end":"2026-05-26T05:54:03.693101Z","steps":["trace[557512830] 'read index received' (duration: 124.165276ms)","trace[557512830] 'applied index is now lower than readState.Index' (duration: 184.994µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:54:03.693391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.807813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-26T05:54:03.693412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.625317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-26T05:54:03.693504Z","caller":"traceutil/trace.go:171","msg":"trace[1015509637] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1202; }","duration":"124.74384ms","start":"2026-05-26T05:54:03.568739Z","end":"2026-05-26T05:54:03.693483Z","steps":["trace[1015509637] 'agreement among raft nodes before linearized reading' (duration: 124.475573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:03.693584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.217535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-26T05:54:03.69344Z","caller":"traceutil/trace.go:171","msg":"trace[1610978932] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1202; }","duration":"118.889975ms","start":"2026-05-26T05:54:03.574535Z","end":"2026-05-26T05:54:03.693425Z","steps":["trace[1610978932] 'agreement among raft nodes before linearized reading' (duration: 118.723571ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:54:03.693675Z","caller":"traceutil/trace.go:171","msg":"trace[412159789] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1202; }","duration":"115.336808ms","start":"2026-05-26T05:54:03.57832Z","end":"2026-05-26T05:54:03.693657Z","steps":["trace[412159789] 'agreement among raft nodes before linearized reading' (duration: 115.055521ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:54:03.693806Z","caller":"traceutil/trace.go:171","msg":"trace[348350235] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"135.517936ms","start":"2026-05-26T05:54:03.557638Z","end":"2026-05-26T05:54:03.693156Z","steps":["trace[348350235] 'process raft request' (duration: 135.29845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:05.3601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.444767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:54:05.360173Z","caller":"traceutil/trace.go:171","msg":"trace[1584442402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1207; }","duration":"293.56107ms","start":"2026-05-26T05:54:05.066596Z","end":"2026-05-26T05:54:05.360157Z","steps":["trace[1584442402] 'range keys from in-memory index tree' (duration: 293.369375ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:54:05.854954Z","caller":"traceutil/trace.go:171","msg":"trace[211563070] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"155.631554ms","start":"2026-05-26T05:54:05.699301Z","end":"2026-05-26T05:54:05.854933Z","steps":["trace[211563070] 'process raft request' (duration: 155.004598ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:54:06.069757Z","caller":"traceutil/trace.go:171","msg":"trace[357233543] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"210.59184ms","start":"2026-05-26T05:54:05.859138Z","end":"2026-05-26T05:54:06.06973Z","steps":["trace[357233543] 'process raft request' (duration: 207.3479ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:54:06.074976Z","caller":"traceutil/trace.go:171","msg":"trace[1271781077] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"215.238825ms","start":"2026-05-26T05:54:05.859719Z","end":"2026-05-26T05:54:06.074957Z","steps":["trace[1271781077] 'process raft request' (duration: 215.116592ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:54:06.075173Z","caller":"traceutil/trace.go:171","msg":"trace[2141078622] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"215.245695ms","start":"2026-05-26T05:54:05.859915Z","end":"2026-05-26T05:54:06.075161Z","steps":["trace[2141078622] 'process raft request' (duration: 215.016629ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:54:06.075348Z","caller":"traceutil/trace.go:171","msg":"trace[1345408985] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"214.860465ms","start":"2026-05-26T05:54:05.860478Z","end":"2026-05-26T05:54:06.075338Z","steps":["trace[1345408985] 'process raft request' (duration: 214.627999ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:54:18.605076Z","caller":"traceutil/trace.go:171","msg":"trace[168278162] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"204.811798ms","start":"2026-05-26T05:54:18.400234Z","end":"2026-05-26T05:54:18.605046Z","steps":["trace[168278162] 'process raft request' (duration: 204.626803ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:54:20.500567Z","caller":"traceutil/trace.go:171","msg":"trace[2103165365] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"222.30734ms","start":"2026-05-26T05:54:20.278245Z","end":"2026-05-26T05:54:20.500552Z","steps":["trace[2103165365] 'process raft request' (duration: 222.213748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:20.717593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.920162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T05:54:20.717657Z","caller":"traceutil/trace.go:171","msg":"trace[194560582] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1308; }","duration":"247.184684ms","start":"2026-05-26T05:54:20.470463Z","end":"2026-05-26T05:54:20.717648Z","steps":["trace[194560582] 'read index received' (duration: 29.995031ms)","trace[194560582] 'applied index is now lower than readState.Index' (duration: 217.188873ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:54:20.717844Z","caller":"traceutil/trace.go:171","msg":"trace[845840158] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"439.563005ms","start":"2026-05-26T05:54:20.278274Z","end":"2026-05-26T05:54:20.717837Z","steps":["trace[845840158] 'process raft request' (duration: 304.344756ms)","trace[845840158] 'compare' (duration: 134.83821ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:54:20.717879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:20.278259Z","time spent":"439.603986ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T05:54:20.718052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.579123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:54:20.718083Z","caller":"traceutil/trace.go:171","msg":"trace[2025648144] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1259; }","duration":"247.639245ms","start":"2026-05-26T05:54:20.470434Z","end":"2026-05-26T05:54:20.718073Z","steps":["trace[2025648144] 'agreement among raft nodes before linearized reading' (duration: 247.493582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:20.718179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.246208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-26T05:54:20.718327Z","caller":"traceutil/trace.go:171","msg":"trace[1591500475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1259; }","duration":"109.465484ms","start":"2026-05-26T05:54:20.608832Z","end":"2026-05-26T05:54:20.718298Z","steps":["trace[1591500475] 'agreement among raft nodes before linearized reading' (duration: 109.072944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:27.955161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.155212628s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-26T05:54:27.955243Z","caller":"traceutil/trace.go:171","msg":"trace[1861197829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1308; }","duration":"1.1553125s","start":"2026-05-26T05:54:26.799911Z","end":"2026-05-26T05:54:27.955224Z","steps":["trace[1861197829] 'range keys from in-memory index tree' (duration: 1.155065944s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:27.955308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:26.799897Z","time spent":"1.155392492s","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-26T05:54:27.955435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.430836973s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-26T05:54:27.955801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.662035922s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-26T05:54:27.955851Z","caller":"traceutil/trace.go:171","msg":"trace[1637604671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1308; }","duration":"1.662120854s","start":"2026-05-26T05:54:26.293717Z","end":"2026-05-26T05:54:27.955838Z","steps":["trace[1637604671] 'range keys from in-memory index tree' (duration: 1.661920789s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:27.955892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:26.293699Z","time spent":"1.662184345s","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-26T05:54:27.956312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.215157357s","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-05-26T05:54:27.956377Z","caller":"traceutil/trace.go:171","msg":"trace[1249855550] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1308; }","duration":"1.21525831s","start":"2026-05-26T05:54:26.741106Z","end":"2026-05-26T05:54:27.956364Z","steps":["trace[1249855550] 'range keys from in-memory index tree' (duration: 1.215011064s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:27.956413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:26.741083Z","time spent":"1.215320621s","remote":"127.0.0.1:49374","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-05-26T05:54:27.956442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.890767531s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:54:27.956526Z","caller":"traceutil/trace.go:171","msg":"trace[704758822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1308; }","duration":"1.890880183s","start":"2026-05-26T05:54:26.065622Z","end":"2026-05-26T05:54:27.956502Z","steps":["trace[704758822] 'range keys from in-memory index tree' (duration: 1.890709089s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:27.956571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:26.065607Z","time spent":"1.890953495s","remote":"127.0.0.1:49112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T05:54:27.956757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.843398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-26T05:54:27.956799Z","caller":"traceutil/trace.go:171","msg":"trace[943821096] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1308; }","duration":"155.925359ms","start":"2026-05-26T05:54:27.800862Z","end":"2026-05-26T05:54:27.956788Z","steps":["trace[943821096] 'count revisions from in-memory index tree' (duration: 155.753096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:27.956957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.489953683s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.146\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-26T05:54:27.957012Z","caller":"traceutil/trace.go:171","msg":"trace[1240679724] range","detail":"{range_begin:/registry/masterleases/199.19.213.146; range_end:; response_count:1; response_revision:1308; }","duration":"1.490004904s","start":"2026-05-26T05:54:26.46699Z","end":"2026-05-26T05:54:27.956995Z","steps":["trace[1240679724] 'range keys from in-memory index tree' (duration: 1.48983175s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:27.957061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:26.466948Z","time spent":"1.490098406s","remote":"127.0.0.1:49148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.146\" "} {"level":"warn","ts":"2026-05-26T05:54:27.958797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.724068874s","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-05-26T05:54:27.958846Z","caller":"traceutil/trace.go:171","msg":"trace[1144772848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1308; }","duration":"1.724150596s","start":"2026-05-26T05:54:26.234683Z","end":"2026-05-26T05:54:27.958834Z","steps":["trace[1144772848] 'range keys from in-memory index tree' (duration: 1.723956601s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:27.95888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:26.234662Z","time spent":"1.724210647s","remote":"127.0.0.1:49374","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-05-26T05:54:27.955538Z","caller":"traceutil/trace.go:171","msg":"trace[629313985] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1308; }","duration":"1.430977267s","start":"2026-05-26T05:54:26.524536Z","end":"2026-05-26T05:54:27.955513Z","steps":["trace[629313985] 'range keys from in-memory index tree' (duration: 1.430633798s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:27.959136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:26.52452Z","time spent":"1.434604016s","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-26T05:54:28.300618Z","caller":"traceutil/trace.go:171","msg":"trace[1707824987] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1360; }","duration":"339.643767ms","start":"2026-05-26T05:54:27.960946Z","end":"2026-05-26T05:54:28.300589Z","steps":["trace[1707824987] 'read index received' (duration: 339.627287ms)","trace[1707824987] 'applied index is now lower than readState.Index' (duration: 14.27µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:54:28.300645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:27.960049Z","time spent":"340.557501ms","remote":"127.0.0.1:49148","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-26T05:54:28.300758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.790421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:54:28.300796Z","caller":"traceutil/trace.go:171","msg":"trace[404876148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1308; }","duration":"339.864522ms","start":"2026-05-26T05:54:27.960923Z","end":"2026-05-26T05:54:28.300787Z","steps":["trace[404876148] 'agreement among raft nodes before linearized reading' (duration: 339.76897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:28.300834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:27.960906Z","time spent":"339.920954ms","remote":"127.0.0.1:49122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T05:54:28.809782Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4014851740171394083,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-26T05:54:29.165811Z","caller":"traceutil/trace.go:171","msg":"trace[233061609] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"1.14679359s","start":"2026-05-26T05:54:28.018962Z","end":"2026-05-26T05:54:29.165756Z","steps":["trace[233061609] 'process raft request' (duration: 1.146755449s)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:54:29.16593Z","caller":"traceutil/trace.go:171","msg":"trace[44520986] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"1.204469084s","start":"2026-05-26T05:54:27.961433Z","end":"2026-05-26T05:54:29.165902Z","steps":["trace[44520986] 'process raft request' (duration: 1.204120705s)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:54:29.165953Z","caller":"traceutil/trace.go:171","msg":"trace[1884120367] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"1.204898374s","start":"2026-05-26T05:54:27.961031Z","end":"2026-05-26T05:54:29.165929Z","steps":["trace[1884120367] 'process raft request' (duration: 1.150793239s)","trace[1884120367] 'compare' (duration: 53.610233ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:54:29.165962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:28.018942Z","time spent":"1.146935193s","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:54:29.165966Z","caller":"traceutil/trace.go:171","msg":"trace[1216696828] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"1.203528341s","start":"2026-05-26T05:54:27.962411Z","end":"2026-05-26T05:54:29.165939Z","steps":["trace[1216696828] 'process raft request' (duration: 1.203249564s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:29.16608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:27.961014Z","time spent":"1.204986506s","remote":"127.0.0.1:49374","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-05-26T05:54:29.166081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:27.961424Z","time spent":"1.204574816s","remote":"127.0.0.1:49374","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-05-26T05:54:29.166093Z","caller":"traceutil/trace.go:171","msg":"trace[1660471544] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"1.203477429s","start":"2026-05-26T05:54:27.962605Z","end":"2026-05-26T05:54:29.166082Z","steps":["trace[1660471544] 'process raft request' (duration: 1.203083299s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:29.166101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:27.962406Z","time spent":"1.203622523s","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T05:54:29.166149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:27.962599Z","time spent":"1.20352281s","remote":"127.0.0.1:49374","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-05-26T05:54:29.166243Z","caller":"traceutil/trace.go:171","msg":"trace[2030344688] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"1.20391578s","start":"2026-05-26T05:54:27.962285Z","end":"2026-05-26T05:54:29.166201Z","steps":["trace[2030344688] 'process raft request' (duration: 1.203345826s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:29.166297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:27.962276Z","time spent":"1.203993212s","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T05:54:29.616312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.945255ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T05:54:29.616492Z","caller":"traceutil/trace.go:171","msg":"trace[194735281] linearizableReadLoop","detail":"{readStateIndex:1368; appliedIndex:1361; }","duration":"1.307760934s","start":"2026-05-26T05:54:28.308714Z","end":"2026-05-26T05:54:29.616475Z","steps":["trace[194735281] 'read index received' (duration: 803.183454ms)","trace[194735281] 'applied index is now lower than readState.Index' (duration: 504.57647ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:54:29.616576Z","caller":"traceutil/trace.go:171","msg":"trace[1941166264] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"1.31486775s","start":"2026-05-26T05:54:28.301697Z","end":"2026-05-26T05:54:29.616565Z","steps":["trace[1941166264] 'process raft request' (duration: 1.038584248s)","trace[1941166264] 'compare' (duration: 275.834282ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:54:29.616638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:28.301676Z","time spent":"1.314931002s","remote":"127.0.0.1:49148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:54:29.61683Z","caller":"traceutil/trace.go:171","msg":"trace[211488509] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"790.965393ms","start":"2026-05-26T05:54:28.825831Z","end":"2026-05-26T05:54:29.616797Z","steps":["trace[211488509] 'process raft request' (duration: 790.569114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:29.616942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.367717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-05-26T05:54:29.616866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.308127503s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:54:29.616996Z","caller":"traceutil/trace.go:171","msg":"trace[70271726] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1316; }","duration":"792.46126ms","start":"2026-05-26T05:54:28.824521Z","end":"2026-05-26T05:54:29.616983Z","steps":["trace[70271726] 'agreement among raft nodes before linearized reading' (duration: 792.311996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:29.617005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:28.825818Z","time spent":"791.092426ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T05:54:29.617036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:28.824501Z","time spent":"792.527391ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-26T05:54:29.617028Z","caller":"traceutil/trace.go:171","msg":"trace[622127522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1316; }","duration":"1.308333098s","start":"2026-05-26T05:54:28.308673Z","end":"2026-05-26T05:54:29.617006Z","steps":["trace[622127522] 'agreement among raft nodes before linearized reading' (duration: 1.308089922s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:29.617108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:28.308653Z","time spent":"1.30844339s","remote":"127.0.0.1:49112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T05:54:29.616999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.650558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:54:29.617673Z","caller":"traceutil/trace.go:171","msg":"trace[474338104] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1316; }","duration":"138.332915ms","start":"2026-05-26T05:54:29.479319Z","end":"2026-05-26T05:54:29.617652Z","steps":["trace[474338104] 'agreement among raft nodes before linearized reading' (duration: 137.618368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:29.978933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.084252ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T05:54:29.979031Z","caller":"traceutil/trace.go:171","msg":"trace[914405055] linearizableReadLoop","detail":"{readStateIndex:1370; appliedIndex:1369; }","duration":"357.069318ms","start":"2026-05-26T05:54:29.621946Z","end":"2026-05-26T05:54:29.979016Z","steps":["trace[914405055] 'read index received' (duration: 109.851083ms)","trace[914405055] 'applied index is now lower than readState.Index' (duration: 247.216835ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:54:29.979145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.202471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-26T05:54:29.979111Z","caller":"traceutil/trace.go:171","msg":"trace[593572907] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"357.390365ms","start":"2026-05-26T05:54:29.621692Z","end":"2026-05-26T05:54:29.979083Z","steps":["trace[593572907] 'process raft request' (duration: 110.079388ms)","trace[593572907] 'compare' (duration: 246.907198ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:54:29.979179Z","caller":"traceutil/trace.go:171","msg":"trace[807565680] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1317; }","duration":"357.256992ms","start":"2026-05-26T05:54:29.621913Z","end":"2026-05-26T05:54:29.97917Z","steps":["trace[807565680] 'agreement among raft nodes before linearized reading' (duration: 357.18196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:29.979217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:29.621902Z","time spent":"357.307153ms","remote":"127.0.0.1:49380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-05-26T05:54:29.979263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:29.621681Z","time spent":"357.506828ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T05:54:29.979422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.813851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:54:29.979461Z","caller":"traceutil/trace.go:171","msg":"trace[1868405843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1317; }","duration":"354.879262ms","start":"2026-05-26T05:54:29.624573Z","end":"2026-05-26T05:54:29.979452Z","steps":["trace[1868405843] 'agreement among raft nodes before linearized reading' (duration: 354.820901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:29.979486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:29.624558Z","time spent":"354.923644ms","remote":"127.0.0.1:49112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T05:54:30.865841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.49166ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T05:54:30.865977Z","caller":"traceutil/trace.go:171","msg":"trace[993455079] linearizableReadLoop","detail":"{readStateIndex:1371; appliedIndex:1370; }","duration":"673.861711ms","start":"2026-05-26T05:54:30.192056Z","end":"2026-05-26T05:54:30.865918Z","steps":["trace[993455079] 'read index received' (duration: 354.182227ms)","trace[993455079] 'applied index is now lower than readState.Index' (duration: 319.678304ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:54:30.866081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.019835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:54:30.866146Z","caller":"traceutil/trace.go:171","msg":"trace[346754646] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1318; }","duration":"674.089457ms","start":"2026-05-26T05:54:30.192018Z","end":"2026-05-26T05:54:30.866108Z","steps":["trace[346754646] 'agreement among raft nodes before linearized reading' (duration: 673.995085ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:54:30.866127Z","caller":"traceutil/trace.go:171","msg":"trace[1160978100] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"795.968166ms","start":"2026-05-26T05:54:30.070129Z","end":"2026-05-26T05:54:30.866097Z","steps":["trace[1160978100] 'process raft request' (duration: 476.126748ms)","trace[1160978100] 'compare' (duration: 319.358676ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:54:30.866188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:30.192001Z","time spent":"674.175729ms","remote":"127.0.0.1:49128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-05-26T05:54:30.866331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:30.070112Z","time spent":"796.077088ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:54:31.761264Z","caller":"traceutil/trace.go:171","msg":"trace[1035825770] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"588.467072ms","start":"2026-05-26T05:54:31.172772Z","end":"2026-05-26T05:54:31.761239Z","steps":["trace[1035825770] 'process raft request' (duration: 588.286627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:31.761432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:31.172741Z","time spent":"588.606756ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:54:31.987317Z","caller":"traceutil/trace.go:171","msg":"trace[1106053148] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"813.911149ms","start":"2026-05-26T05:54:31.173376Z","end":"2026-05-26T05:54:31.987287Z","steps":["trace[1106053148] 'process raft request' (duration: 813.732425ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:54:31.987434Z","caller":"traceutil/trace.go:171","msg":"trace[501144149] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"812.359721ms","start":"2026-05-26T05:54:31.175064Z","end":"2026-05-26T05:54:31.987424Z","steps":["trace[501144149] 'process raft request' (duration: 812.204637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:31.987506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:31.173361Z","time spent":"814.072834ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:54:31.987549Z","caller":"traceutil/trace.go:171","msg":"trace[944048117] linearizableReadLoop","detail":"{readStateIndex:1377; appliedIndex:1372; }","duration":"155.184772ms","start":"2026-05-26T05:54:31.832142Z","end":"2026-05-26T05:54:31.987327Z","steps":["trace[944048117] 'read index received' (duration: 155.064549ms)","trace[944048117] 'applied index is now lower than readState.Index' (duration: 119.523µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:54:31.987357Z","caller":"traceutil/trace.go:171","msg":"trace[1835933] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"812.365701ms","start":"2026-05-26T05:54:31.174959Z","end":"2026-05-26T05:54:31.987325Z","steps":["trace[1835933] 'process raft request' (duration: 812.263439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:31.988031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:31.174941Z","time spent":"813.054518ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:54:31.987603Z","caller":"traceutil/trace.go:171","msg":"trace[1176455823] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"812.527175ms","start":"2026-05-26T05:54:31.175034Z","end":"2026-05-26T05:54:31.987562Z","steps":["trace[1176455823] 'process raft request' (duration: 812.213847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:31.988214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:31.175019Z","time spent":"813.121559ms","remote":"127.0.0.1:49374","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-05-26T05:54:31.987644Z","caller":"traceutil/trace.go:171","msg":"trace[1599319440] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"811.542131ms","start":"2026-05-26T05:54:31.176071Z","end":"2026-05-26T05:54:31.987614Z","steps":["trace[1599319440] 'process raft request' (duration: 811.220944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:31.988329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:31.176057Z","time spent":"812.243488ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T05:54:31.987686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:31.175045Z","time spent":"812.433523ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T05:54:31.987749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.50881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:54:31.988448Z","caller":"traceutil/trace.go:171","msg":"trace[299540939] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1324; }","duration":"156.295839ms","start":"2026-05-26T05:54:31.832137Z","end":"2026-05-26T05:54:31.988432Z","steps":["trace[299540939] 'agreement among raft nodes before linearized reading' (duration: 155.453879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:32.193554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.686734ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-26T05:54:32.193921Z","caller":"traceutil/trace.go:171","msg":"trace[827445201] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"120.889255ms","start":"2026-05-26T05:54:32.072997Z","end":"2026-05-26T05:54:32.193886Z","steps":["trace[827445201] 'process raft request' (duration: 120.64966ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:54:33.669093Z","caller":"traceutil/trace.go:171","msg":"trace[1734377503] linearizableReadLoop","detail":"{readStateIndex:1380; appliedIndex:1379; }","duration":"244.786254ms","start":"2026-05-26T05:54:33.424285Z","end":"2026-05-26T05:54:33.669071Z","steps":["trace[1734377503] 'read index received' (duration: 244.60219ms)","trace[1734377503] 'applied index is now lower than readState.Index' (duration: 182.694µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:54:33.669222Z","caller":"traceutil/trace.go:171","msg":"trace[869189806] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"517.422696ms","start":"2026-05-26T05:54:33.151769Z","end":"2026-05-26T05:54:33.669192Z","steps":["trace[869189806] 'process raft request' (duration: 517.135989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:33.669313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.822355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-26T05:54:33.669312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.997689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T05:54:33.669355Z","caller":"traceutil/trace.go:171","msg":"trace[2077072897] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1326; }","duration":"182.946158ms","start":"2026-05-26T05:54:33.486396Z","end":"2026-05-26T05:54:33.669342Z","steps":["trace[2077072897] 'agreement among raft nodes before linearized reading' (duration: 182.839075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:33.66937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:33.151753Z","time spent":"517.54244ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:54:33.669386Z","caller":"traceutil/trace.go:171","msg":"trace[729432967] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1326; }","duration":"245.129502ms","start":"2026-05-26T05:54:33.424239Z","end":"2026-05-26T05:54:33.669369Z","steps":["trace[729432967] 'agreement among raft nodes before linearized reading' (duration: 244.987369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:34.177279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.498611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:54:34.177367Z","caller":"traceutil/trace.go:171","msg":"trace[1710985109] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1326; }","duration":"430.653575ms","start":"2026-05-26T05:54:33.746696Z","end":"2026-05-26T05:54:34.17735Z","steps":["trace[1710985109] 'count revisions from in-memory index tree' (duration: 430.408449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:34.177417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:33.746676Z","time spent":"430.729876ms","remote":"127.0.0.1:49410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-05-26T05:54:34.177461Z","caller":"traceutil/trace.go:171","msg":"trace[1487556135] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1380; }","duration":"185.296955ms","start":"2026-05-26T05:54:33.99214Z","end":"2026-05-26T05:54:34.177437Z","steps":["trace[1487556135] 'read index received' (duration: 175.576414ms)","trace[1487556135] 'applied index is now lower than readState.Index' (duration: 9.719571ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T05:54:34.177877Z","caller":"traceutil/trace.go:171","msg":"trace[855353837] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"410.913348ms","start":"2026-05-26T05:54:33.766949Z","end":"2026-05-26T05:54:34.177863Z","steps":["trace[855353837] 'process raft request' (duration: 400.850329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:34.177967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.695105ms","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-05-26T05:54:34.177987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:33.766933Z","time spent":"410.988509ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:54:34.17803Z","caller":"traceutil/trace.go:171","msg":"trace[394357199] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1327; }","duration":"185.756807ms","start":"2026-05-26T05:54:33.992249Z","end":"2026-05-26T05:54:34.178006Z","steps":["trace[394357199] 'agreement among raft nodes before linearized reading' (duration: 185.662335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:34.17787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.688345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-26T05:54:34.178154Z","caller":"traceutil/trace.go:171","msg":"trace[1573976505] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1327; }","duration":"186.049534ms","start":"2026-05-26T05:54:33.992093Z","end":"2026-05-26T05:54:34.178143Z","steps":["trace[1573976505] 'agreement among raft nodes before linearized reading' (duration: 185.598293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:34.178197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.88822ms","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-05-26T05:54:34.178275Z","caller":"traceutil/trace.go:171","msg":"trace[830648938] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1327; }","duration":"185.994692ms","start":"2026-05-26T05:54:33.992269Z","end":"2026-05-26T05:54:34.178264Z","steps":["trace[830648938] 'agreement among raft nodes before linearized reading' (duration: 185.555872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:34.178275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.015073ms","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-05-26T05:54:34.178364Z","caller":"traceutil/trace.go:171","msg":"trace[837930842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1327; }","duration":"186.155516ms","start":"2026-05-26T05:54:33.992195Z","end":"2026-05-26T05:54:34.178351Z","steps":["trace[837930842] 'agreement among raft nodes before linearized reading' (duration: 185.8906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:34.178877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.729521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-26T05:54:34.179003Z","caller":"traceutil/trace.go:171","msg":"trace[691628327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1327; }","duration":"186.838083ms","start":"2026-05-26T05:54:33.992072Z","end":"2026-05-26T05:54:34.178911Z","steps":["trace[691628327] 'agreement among raft nodes before linearized reading' (duration: 185.788728ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:54:34.450211Z","caller":"traceutil/trace.go:171","msg":"trace[220388174] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"267.67689ms","start":"2026-05-26T05:54:34.182507Z","end":"2026-05-26T05:54:34.450184Z","steps":["trace[220388174] 'process raft request' (duration: 266.828349ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:54:34.45029Z","caller":"traceutil/trace.go:171","msg":"trace[243439311] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"267.406593ms","start":"2026-05-26T05:54:34.182846Z","end":"2026-05-26T05:54:34.450253Z","steps":["trace[243439311] 'process raft request' (duration: 267.2816ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:54:34.450287Z","caller":"traceutil/trace.go:171","msg":"trace[816792267] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"267.325731ms","start":"2026-05-26T05:54:34.182942Z","end":"2026-05-26T05:54:34.450268Z","steps":["trace[816792267] 'process raft request' (duration: 267.220709ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:54:34.451338Z","caller":"traceutil/trace.go:171","msg":"trace[1141315253] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"267.440584ms","start":"2026-05-26T05:54:34.182765Z","end":"2026-05-26T05:54:34.450206Z","steps":["trace[1141315253] 'process raft request' (duration: 267.326411ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:54:34.453039Z","caller":"traceutil/trace.go:171","msg":"trace[899875906] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"208.458807ms","start":"2026-05-26T05:54:34.244565Z","end":"2026-05-26T05:54:34.453024Z","steps":["trace[899875906] 'process raft request' (duration: 208.213831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:35.307767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.155676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-26T05:54:35.307907Z","caller":"traceutil/trace.go:171","msg":"trace[632214893] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"800.24388ms","start":"2026-05-26T05:54:34.507651Z","end":"2026-05-26T05:54:35.307895Z","steps":["trace[632214893] 'process raft request' (duration: 800.191929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:35.307951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:34.507635Z","time spent":"800.298702ms","remote":"127.0.0.1:49200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":929,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T05:54:35.308076Z","caller":"traceutil/trace.go:171","msg":"trace[11861928] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"816.858002ms","start":"2026-05-26T05:54:34.491211Z","end":"2026-05-26T05:54:35.308069Z","steps":["trace[11861928] 'process raft request' (duration: 334.347787ms)","trace[11861928] 'compare' (duration: 481.566482ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:54:35.308109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:34.491186Z","time spent":"816.909482ms","remote":"127.0.0.1:49200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":837,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T05:54:35.308189Z","caller":"traceutil/trace.go:171","msg":"trace[1588781701] linearizableReadLoop","detail":"{readStateIndex:1390; appliedIndex:1389; }","duration":"802.751722ms","start":"2026-05-26T05:54:34.505431Z","end":"2026-05-26T05:54:35.308183Z","steps":["trace[1588781701] 'read index received' (duration: 320.208947ms)","trace[1588781701] 'applied index is now lower than readState.Index' (duration: 482.542105ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:54:35.308386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.949849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-26T05:54:35.308409Z","caller":"traceutil/trace.go:171","msg":"trace[2114805350] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1337; }","duration":"803.01025ms","start":"2026-05-26T05:54:34.505392Z","end":"2026-05-26T05:54:35.308403Z","steps":["trace[2114805350] 'agreement among raft nodes before linearized reading' (duration: 802.888876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:35.308427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:34.505375Z","time spent":"803.047821ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-26T05:54:35.308554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.064857ms","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-05-26T05:54:35.308574Z","caller":"traceutil/trace.go:171","msg":"trace[1243037321] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1337; }","duration":"325.120649ms","start":"2026-05-26T05:54:34.983448Z","end":"2026-05-26T05:54:35.308569Z","steps":["trace[1243037321] 'agreement among raft nodes before linearized reading' (duration: 325.068448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:35.308591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:34.983404Z","time spent":"325.18345ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-26T05:54:35.308743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.614688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:54:35.308772Z","caller":"traceutil/trace.go:171","msg":"trace[485117382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1337; }","duration":"241.765191ms","start":"2026-05-26T05:54:35.066995Z","end":"2026-05-26T05:54:35.30876Z","steps":["trace[485117382] 'agreement among raft nodes before linearized reading' (duration: 241.71893ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:54:35.950448Z","caller":"traceutil/trace.go:171","msg":"trace[25725473] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"636.858478ms","start":"2026-05-26T05:54:35.313572Z","end":"2026-05-26T05:54:35.95043Z","steps":["trace[25725473] 'process raft request' (duration: 577.169833ms)","trace[25725473] 'compare' (duration: 59.404308ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:54:35.950549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:35.313554Z","time spent":"636.940079ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T05:54:36.254947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.567878ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T05:54:36.255081Z","caller":"traceutil/trace.go:171","msg":"trace[979386313] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"300.405858ms","start":"2026-05-26T05:54:35.954648Z","end":"2026-05-26T05:54:36.255054Z","steps":["trace[979386313] 'process raft request' (duration: 100.649175ms)","trace[979386313] 'compare' (duration: 199.394864ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:54:36.25518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:54:35.954617Z","time spent":"300.514201ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:54:36.345027Z","caller":"traceutil/trace.go:171","msg":"trace[963745907] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1394; }","duration":"138.311536ms","start":"2026-05-26T05:54:36.206695Z","end":"2026-05-26T05:54:36.345007Z","steps":["trace[963745907] 'read index received' (duration: 138.140881ms)","trace[963745907] 'applied index is now lower than readState.Index' (duration: 169.655µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:54:36.345239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.524331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-26T05:54:36.345289Z","caller":"traceutil/trace.go:171","msg":"trace[2114985131] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1341; }","duration":"138.622743ms","start":"2026-05-26T05:54:36.206652Z","end":"2026-05-26T05:54:36.345274Z","steps":["trace[2114985131] 'agreement among raft nodes before linearized reading' (duration: 138.438739ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:54:36.345161Z","caller":"traceutil/trace.go:171","msg":"trace[25968875] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"162.120673ms","start":"2026-05-26T05:54:36.183019Z","end":"2026-05-26T05:54:36.345139Z","steps":["trace[25968875] 'process raft request' (duration: 161.870527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:54:37.802074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.994862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:54:37.802192Z","caller":"traceutil/trace.go:171","msg":"trace[887990386] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1349; }","duration":"290.118005ms","start":"2026-05-26T05:54:37.512012Z","end":"2026-05-26T05:54:37.80213Z","steps":["trace[887990386] 'range keys from in-memory index tree' (duration: 289.895869ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:55:57.352618Z","caller":"traceutil/trace.go:171","msg":"trace[1650950871] transaction","detail":"{read_only:false; response_revision:1732; number_of_response:1; }","duration":"350.712918ms","start":"2026-05-26T05:55:57.001879Z","end":"2026-05-26T05:55:57.352592Z","steps":["trace[1650950871] 'process raft request' (duration: 350.439341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:55:57.352758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:55:57.001868Z","time spent":"350.82171ms","remote":"127.0.0.1:49374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T05:55:57.353669Z","caller":"traceutil/trace.go:171","msg":"trace[1313572957] linearizableReadLoop","detail":"{readStateIndex:1804; appliedIndex:1802; }","duration":"286.614221ms","start":"2026-05-26T05:55:57.067032Z","end":"2026-05-26T05:55:57.353646Z","steps":["trace[1313572957] 'read index received' (duration: 285.497043ms)","trace[1313572957] 'applied index is now lower than readState.Index' (duration: 1.116148ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T05:55:57.3538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.749164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T05:55:57.353956Z","caller":"traceutil/trace.go:171","msg":"trace[1527528722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1733; }","duration":"286.949249ms","start":"2026-05-26T05:55:57.066992Z","end":"2026-05-26T05:55:57.353941Z","steps":["trace[1527528722] 'agreement among raft nodes before linearized reading' (duration: 286.746224ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:55:57.354169Z","caller":"traceutil/trace.go:171","msg":"trace[386058840] transaction","detail":"{read_only:false; response_revision:1733; number_of_response:1; }","duration":"348.282747ms","start":"2026-05-26T05:55:57.005867Z","end":"2026-05-26T05:55:57.354149Z","steps":["trace[386058840] 'process raft request' (duration: 347.670722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T05:55:57.354431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T05:55:57.005834Z","time spent":"348.500401ms","remote":"127.0.0.1:49374","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-05-26T05:55:58.571559Z","caller":"traceutil/trace.go:171","msg":"trace[1692857594] transaction","detail":"{read_only:false; response_revision:1735; number_of_response:1; }","duration":"112.903134ms","start":"2026-05-26T05:55:58.458625Z","end":"2026-05-26T05:55:58.571528Z","steps":["trace[1692857594] 'process raft request' (duration: 112.642267ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T05:58:24.88372Z","caller":"traceutil/trace.go:171","msg":"trace[511235082] transaction","detail":"{read_only:false; response_revision:2432; number_of_response:1; }","duration":"140.340881ms","start":"2026-05-26T05:58:24.743223Z","end":"2026-05-26T05:58:24.883564Z","steps":["trace[511235082] 'process raft request' (duration: 140.097145ms)"],"step_count":1}