{"level":"warn","ts":"2026-04-28T06:52:43.621219Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T06:52:43.622583Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113: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-04-28T06:52:43.62273Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T06:52:43.622778Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-28T06:52:43.622816Z","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-04-28T06:52:43.623621Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-04-28T06:52:43.623765Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113: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-04-28T06:52:43.628153Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.022908ms"} {"level":"info","ts":"2026-04-28T06:52:43.636187Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-04-28T06:52:43.63631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T06:52:43.636361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-04-28T06:52:43.636382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T06:52:43.636429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-04-28T06:52:43.636513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-04-28T06:52:43.642235Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T06:52:43.64401Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T06:52:43.645149Z","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-04-28T06:52:43.647883Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T06:52:43.64805Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T06:52:43.649516Z","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-04-28T06:52:43.649619Z","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-04-28T06:52:43.649646Z","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-04-28T06:52:43.650671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-04-28T06:52:43.650925Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-28T06:52:43.652833Z","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-04-28T06:52:43.653036Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-28T06:52:43.653069Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-28T06:52:43.653295Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T06:52:43.653351Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T06:52:44.637162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T06:52:44.637238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T06:52:44.637292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-04-28T06:52:44.637318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-04-28T06:52:44.637345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-28T06:52:44.637381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-04-28T06:52:44.637411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-28T06:52:44.638465Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:52:44.639586Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T06:52:44.639628Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T06:52:44.639786Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:52:44.639776Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T06:52:44.639896Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:52:44.64001Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:52:44.640335Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T06:52:44.640383Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T06:52:44.643904Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-04-28T06:52:44.644262Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T06:53:10.210504Z","caller":"traceutil/trace.go:171","msg":"trace[1037193048] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"407.785186ms","start":"2026-04-28T06:53:09.802704Z","end":"2026-04-28T06:53:10.210489Z","steps":["trace[1037193048] 'process raft request' (duration: 407.60486ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:10.210503Z","caller":"traceutil/trace.go:171","msg":"trace[668556475] linearizableReadLoop","detail":"{readStateIndex:442; appliedIndex:441; }","duration":"317.643663ms","start":"2026-04-28T06:53:09.892811Z","end":"2026-04-28T06:53:10.210455Z","steps":["trace[668556475] 'read index received' (duration: 317.414536ms)","trace[668556475] 'applied index is now lower than readState.Index' (duration: 228.037µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:53:10.21081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.800856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-28T06:53:10.210996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.939021ms","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-04-28T06:53:10.212024Z","caller":"traceutil/trace.go:171","msg":"trace[1617518495] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:424; }","duration":"319.335167ms","start":"2026-04-28T06:53:09.892676Z","end":"2026-04-28T06:53:10.212011Z","steps":["trace[1617518495] 'agreement among raft nodes before linearized reading' (duration: 317.885758ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:10.212052Z","caller":"traceutil/trace.go:171","msg":"trace[1814279326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:424; }","duration":"167.017781ms","start":"2026-04-28T06:53:10.045006Z","end":"2026-04-28T06:53:10.212024Z","steps":["trace[1814279326] 'agreement among raft nodes before linearized reading' (duration: 165.921681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:10.212066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:09.892655Z","time spent":"319.400509ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:53:10.212152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:09.802681Z","time spent":"407.894759ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:53:10.418342Z","caller":"traceutil/trace.go:171","msg":"trace[1922818908] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"200.189069ms","start":"2026-04-28T06:53:10.218115Z","end":"2026-04-28T06:53:10.418304Z","steps":["trace[1922818908] 'process raft request' (duration: 133.333338ms)","trace[1922818908] 'compare' (duration: 66.650774ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:53:10.418631Z","caller":"traceutil/trace.go:171","msg":"trace[1531958904] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:442; }","duration":"165.909451ms","start":"2026-04-28T06:53:10.252567Z","end":"2026-04-28T06:53:10.418476Z","steps":["trace[1531958904] 'read index received' (duration: 98.841105ms)","trace[1531958904] 'applied index is now lower than readState.Index' (duration: 67.066346ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:53:10.418689Z","caller":"traceutil/trace.go:171","msg":"trace[1215519190] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"200.258341ms","start":"2026-04-28T06:53:10.218404Z","end":"2026-04-28T06:53:10.418662Z","steps":["trace[1215519190] 'process raft request' (duration: 199.840329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:10.419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.285211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-28T06:53:10.419065Z","caller":"traceutil/trace.go:171","msg":"trace[2000958461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:426; }","duration":"166.534538ms","start":"2026-04-28T06:53:10.252511Z","end":"2026-04-28T06:53:10.419046Z","steps":["trace[2000958461] 'agreement among raft nodes before linearized reading' (duration: 166.175228ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:14.590144Z","caller":"traceutil/trace.go:171","msg":"trace[103001193] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"152.825631ms","start":"2026-04-28T06:53:14.437283Z","end":"2026-04-28T06:53:14.590108Z","steps":["trace[103001193] 'process raft request' (duration: 61.793024ms)","trace[103001193] 'compare' (duration: 90.834872ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:53:15.233441Z","caller":"traceutil/trace.go:171","msg":"trace[1011713714] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:488; }","duration":"214.103412ms","start":"2026-04-28T06:53:15.019302Z","end":"2026-04-28T06:53:15.233405Z","steps":["trace[1011713714] 'read index received' (duration: 213.979169ms)","trace[1011713714] 'applied index is now lower than readState.Index' (duration: 123.183µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:53:15.233527Z","caller":"traceutil/trace.go:171","msg":"trace[158972349] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"239.581483ms","start":"2026-04-28T06:53:14.993904Z","end":"2026-04-28T06:53:15.233485Z","steps":["trace[158972349] 'process raft request' (duration: 239.374138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:15.233594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.087931ms","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":"info","ts":"2026-04-28T06:53:15.233642Z","caller":"traceutil/trace.go:171","msg":"trace[341646874] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:470; }","duration":"146.186614ms","start":"2026-04-28T06:53:15.087445Z","end":"2026-04-28T06:53:15.233632Z","steps":["trace[341646874] 'agreement among raft nodes before linearized reading' (duration: 146.095541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:15.2337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.347649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:53:15.233791Z","caller":"traceutil/trace.go:171","msg":"trace[550533694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:470; }","duration":"214.516733ms","start":"2026-04-28T06:53:15.01925Z","end":"2026-04-28T06:53:15.233767Z","steps":["trace[550533694] 'agreement among raft nodes before linearized reading' (duration: 214.301568ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:15.606005Z","caller":"traceutil/trace.go:171","msg":"trace[676354503] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"106.392757ms","start":"2026-04-28T06:53:15.499587Z","end":"2026-04-28T06:53:15.60598Z","steps":["trace[676354503] 'process raft request' (duration: 94.16433ms)","trace[676354503] 'compare' (duration: 12.091423ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:53:16.344426Z","caller":"traceutil/trace.go:171","msg":"trace[1710904802] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"836.988233ms","start":"2026-04-28T06:53:15.5074Z","end":"2026-04-28T06:53:16.344388Z","steps":["trace[1710904802] 'process raft request' (duration: 836.771658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:16.344594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:15.507383Z","time spent":"837.137038ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:53:16.344591Z","caller":"traceutil/trace.go:171","msg":"trace[1584341505] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"831.748285ms","start":"2026-04-28T06:53:15.512799Z","end":"2026-04-28T06:53:16.344547Z","steps":["trace[1584341505] 'process raft request' (duration: 831.525259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:16.344718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:15.512792Z","time spent":"831.864608ms","remote":"127.0.0.1:41680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:53:16.344761Z","caller":"traceutil/trace.go:171","msg":"trace[152500460] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"833.185042ms","start":"2026-04-28T06:53:15.511552Z","end":"2026-04-28T06:53:16.344737Z","steps":["trace[152500460] 'process raft request' (duration: 832.7343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:16.344852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:15.511539Z","time spent":"833.268085ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:53:16.344867Z","caller":"traceutil/trace.go:171","msg":"trace[1098584402] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"779.629869ms","start":"2026-04-28T06:53:15.565209Z","end":"2026-04-28T06:53:16.344839Z","steps":["trace[1098584402] 'process raft request' (duration: 779.575578ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:16.344979Z","caller":"traceutil/trace.go:171","msg":"trace[1084769375] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"821.194112ms","start":"2026-04-28T06:53:15.52377Z","end":"2026-04-28T06:53:16.344964Z","steps":["trace[1084769375] 'process raft request' (duration: 820.709319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:16.345005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:15.565194Z","time spent":"779.748593ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:53:16.345044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:15.52375Z","time spent":"821.266083ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:53:16.345127Z","caller":"traceutil/trace.go:171","msg":"trace[1054122695] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"832.105913ms","start":"2026-04-28T06:53:15.513012Z","end":"2026-04-28T06:53:16.345118Z","steps":["trace[1054122695] 'process raft request' (duration: 831.346513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:16.34517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:15.512986Z","time spent":"832.166895ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:53:16.345283Z","caller":"traceutil/trace.go:171","msg":"trace[864936021] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"821.433548ms","start":"2026-04-28T06:53:15.523833Z","end":"2026-04-28T06:53:16.345266Z","steps":["trace[864936021] 'process raft request' (duration: 820.687018ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:16.345323Z","caller":"traceutil/trace.go:171","msg":"trace[317523828] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"814.919914ms","start":"2026-04-28T06:53:15.530371Z","end":"2026-04-28T06:53:16.345291Z","steps":["trace[317523828] 'process raft request' (duration: 814.226446ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:16.345379Z","caller":"traceutil/trace.go:171","msg":"trace[785588762] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"791.64691ms","start":"2026-04-28T06:53:15.553724Z","end":"2026-04-28T06:53:16.34537Z","steps":["trace[785588762] 'process raft request' (duration: 790.970192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:16.345395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:15.523828Z","time spent":"821.535341ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:53:16.345419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:15.530352Z","time spent":"815.025738ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:53:16.345305Z","caller":"traceutil/trace.go:171","msg":"trace[1413492572] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"809.473557ms","start":"2026-04-28T06:53:15.535808Z","end":"2026-04-28T06:53:16.345281Z","steps":["trace[1413492572] 'process raft request' (duration: 808.834571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:16.345468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:15.553617Z","time spent":"791.804335ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:53:16.345504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:15.535791Z","time spent":"809.689254ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:53:16.34543Z","caller":"traceutil/trace.go:171","msg":"trace[189640536] transaction","detail":"{read_only:false; number_of_response:1; response_revision:477; }","duration":"827.344196ms","start":"2026-04-28T06:53:15.518072Z","end":"2026-04-28T06:53:16.345416Z","steps":["trace[189640536] 'process raft request' (duration: 826.3689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:16.345654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:15.518063Z","time spent":"827.547521ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7366,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:53:16.345375Z","caller":"traceutil/trace.go:171","msg":"trace[1316051831] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"829.928856ms","start":"2026-04-28T06:53:15.515428Z","end":"2026-04-28T06:53:16.345357Z","steps":["trace[1316051831] 'process raft request' (duration: 828.96556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:16.346059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:15.515419Z","time spent":"830.47864ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:53:16.851687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.201023ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:53:16.85186Z","caller":"traceutil/trace.go:171","msg":"trace[786348160] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:483; }","duration":"425.381857ms","start":"2026-04-28T06:53:16.426459Z","end":"2026-04-28T06:53:16.851841Z","steps":["trace[786348160] 'range keys from in-memory index tree' (duration: 425.183492ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:16.852114Z","caller":"traceutil/trace.go:171","msg":"trace[572119701] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:491; }","duration":"830.587233ms","start":"2026-04-28T06:53:16.021389Z","end":"2026-04-28T06:53:16.851976Z","steps":["trace[572119701] 'read index received' (duration: 322.848842ms)","trace[572119701] 'applied index is now lower than readState.Index' (duration: 507.737601ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:53:16.852106Z","caller":"traceutil/trace.go:171","msg":"trace[637556081] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"1.242102669s","start":"2026-04-28T06:53:15.60995Z","end":"2026-04-28T06:53:16.852052Z","steps":["trace[637556081] 'process raft request' (duration: 1.237312941s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:16.852159Z","caller":"traceutil/trace.go:171","msg":"trace[1070478864] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"1.144122056s","start":"2026-04-28T06:53:15.707997Z","end":"2026-04-28T06:53:16.852119Z","steps":["trace[1070478864] 'process raft request' (duration: 1.143913381s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:16.852219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.83492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:53:16.852258Z","caller":"traceutil/trace.go:171","msg":"trace[1786761499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:485; }","duration":"830.920082ms","start":"2026-04-28T06:53:16.021325Z","end":"2026-04-28T06:53:16.852245Z","steps":["trace[1786761499] 'agreement among raft nodes before linearized reading' (duration: 830.844921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:16.852292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:16.021302Z","time spent":"830.982223ms","remote":"127.0.0.1:41080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:53:16.852286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:15.609928Z","time spent":"1.242283004s","remote":"127.0.0.1:41680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:53:16.852298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:15.707971Z","time spent":"1.14426405s","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:53:16.855303Z","caller":"traceutil/trace.go:171","msg":"trace[1892454112] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"496.294846ms","start":"2026-04-28T06:53:16.358986Z","end":"2026-04-28T06:53:16.855281Z","steps":["trace[1892454112] 'process raft request' (duration: 495.989588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:16.855399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:16.358972Z","time spent":"496.377337ms","remote":"127.0.0.1:41680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:53:16.855517Z","caller":"traceutil/trace.go:171","msg":"trace[983375745] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"498.385221ms","start":"2026-04-28T06:53:16.357114Z","end":"2026-04-28T06:53:16.855499Z","steps":["trace[983375745] 'process raft request' (duration: 497.717943ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:16.855316Z","caller":"traceutil/trace.go:171","msg":"trace[1873668462] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"493.87394ms","start":"2026-04-28T06:53:16.361426Z","end":"2026-04-28T06:53:16.855299Z","steps":["trace[1873668462] 'process raft request' (duration: 493.631784ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:16.855602Z","caller":"traceutil/trace.go:171","msg":"trace[839647027] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"491.467056ms","start":"2026-04-28T06:53:16.364063Z","end":"2026-04-28T06:53:16.85553Z","steps":["trace[839647027] 'process raft request' (duration: 491.091436ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:16.855627Z","caller":"traceutil/trace.go:171","msg":"trace[834269432] transaction","detail":"{read_only:false; number_of_response:1; response_revision:491; }","duration":"490.51792ms","start":"2026-04-28T06:53:16.365089Z","end":"2026-04-28T06:53:16.855607Z","steps":["trace[834269432] 'process raft request' (duration: 490.092899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:16.855691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:16.357094Z","time spent":"498.521915ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:53:16.85581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:16.364056Z","time spent":"491.620331ms","remote":"127.0.0.1:41680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:53:16.85589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.001756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-28T06:53:16.855909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.557872ms","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-04-28T06:53:16.855935Z","caller":"traceutil/trace.go:171","msg":"trace[542349403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:494; }","duration":"224.062378ms","start":"2026-04-28T06:53:16.631862Z","end":"2026-04-28T06:53:16.855925Z","steps":["trace[542349403] 'agreement among raft nodes before linearized reading' (duration: 223.945595ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:16.855954Z","caller":"traceutil/trace.go:171","msg":"trace[1820151417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:494; }","duration":"261.634103ms","start":"2026-04-28T06:53:16.594308Z","end":"2026-04-28T06:53:16.855942Z","steps":["trace[1820151417] 'agreement among raft nodes before linearized reading' (duration: 261.540171ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:16.856079Z","caller":"traceutil/trace.go:171","msg":"trace[693424730] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"489.875833ms","start":"2026-04-28T06:53:16.366192Z","end":"2026-04-28T06:53:16.856068Z","steps":["trace[693424730] 'process raft request' (duration: 489.133664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:16.856145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:16.366174Z","time spent":"489.937214ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:53:16.855618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:16.361421Z","time spent":"494.169797ms","remote":"127.0.0.1:41680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:53:16.85626Z","caller":"traceutil/trace.go:171","msg":"trace[308091611] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"480.200674ms","start":"2026-04-28T06:53:16.376051Z","end":"2026-04-28T06:53:16.856252Z","steps":["trace[308091611] 'process raft request' (duration: 479.451184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:16.856314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:16.376045Z","time spent":"480.238875ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:53:16.855804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:16.365083Z","time spent":"490.637774ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7375,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:53:16.856148Z","caller":"traceutil/trace.go:171","msg":"trace[1453960940] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"493.233522ms","start":"2026-04-28T06:53:16.362832Z","end":"2026-04-28T06:53:16.856066Z","steps":["trace[1453960940] 'process raft request' (duration: 492.255767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:16.856545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:16.362824Z","time spent":"493.689735ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7326,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:53:16.856209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.565909ms","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-04-28T06:53:16.856686Z","caller":"traceutil/trace.go:171","msg":"trace[231478294] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:494; }","duration":"368.077823ms","start":"2026-04-28T06:53:16.488595Z","end":"2026-04-28T06:53:16.856673Z","steps":["trace[231478294] 'agreement among raft nodes before linearized reading' (duration: 367.567679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:16.856731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:16.488577Z","time spent":"368.141725ms","remote":"127.0.0.1:41372","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-04-28T06:53:16.856229Z","caller":"traceutil/trace.go:171","msg":"trace[77978584] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"482.28443ms","start":"2026-04-28T06:53:16.373933Z","end":"2026-04-28T06:53:16.856218Z","steps":["trace[77978584] 'process raft request' (duration: 481.486799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:16.856862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:16.373907Z","time spent":"482.918548ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:53:16.855421Z","caller":"traceutil/trace.go:171","msg":"trace[27935138] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"494.031525ms","start":"2026-04-28T06:53:16.361368Z","end":"2026-04-28T06:53:16.855399Z","steps":["trace[27935138] 'process raft request' (duration: 493.654075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:16.857123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:16.361352Z","time spent":"495.714511ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:53:17.227958Z","caller":"traceutil/trace.go:171","msg":"trace[1508960156] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"218.125648ms","start":"2026-04-28T06:53:17.009808Z","end":"2026-04-28T06:53:17.227933Z","steps":["trace[1508960156] 'process raft request' (duration: 212.648991ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:17.233326Z","caller":"traceutil/trace.go:171","msg":"trace[1415602471] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:532; }","duration":"206.538238ms","start":"2026-04-28T06:53:17.026768Z","end":"2026-04-28T06:53:17.233306Z","steps":["trace[1415602471] 'read index received' (duration: 195.632786ms)","trace[1415602471] 'applied index is now lower than readState.Index' (duration: 10.904822ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:53:17.233454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.669622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:53:17.233482Z","caller":"traceutil/trace.go:171","msg":"trace[885492638] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"206.736364ms","start":"2026-04-28T06:53:17.026737Z","end":"2026-04-28T06:53:17.233474Z","steps":["trace[885492638] 'agreement among raft nodes before linearized reading' (duration: 206.650121ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:17.233776Z","caller":"traceutil/trace.go:171","msg":"trace[362109110] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"222.866276ms","start":"2026-04-28T06:53:17.010901Z","end":"2026-04-28T06:53:17.233768Z","steps":["trace[362109110] 'process raft request' (duration: 222.134017ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:17.233865Z","caller":"traceutil/trace.go:171","msg":"trace[1890492767] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"216.809024ms","start":"2026-04-28T06:53:17.01705Z","end":"2026-04-28T06:53:17.233859Z","steps":["trace[1890492767] 'process raft request' (duration: 216.180097ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:17.233992Z","caller":"traceutil/trace.go:171","msg":"trace[140744530] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"124.331969ms","start":"2026-04-28T06:53:17.109653Z","end":"2026-04-28T06:53:17.233985Z","steps":["trace[140744530] 'process raft request' (duration: 123.61754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:18.160496Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765017878362195,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T06:53:18.317591Z","caller":"traceutil/trace.go:171","msg":"trace[510644549] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"871.413686ms","start":"2026-04-28T06:53:17.446152Z","end":"2026-04-28T06:53:18.317566Z","steps":["trace[510644549] 'process raft request' (duration: 870.840491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:18.3179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:17.446111Z","time spent":"871.57036ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80688,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:53:18.337862Z","caller":"traceutil/trace.go:171","msg":"trace[2123610914] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"876.709549ms","start":"2026-04-28T06:53:17.461129Z","end":"2026-04-28T06:53:18.337838Z","steps":["trace[2123610914] 'process raft request' (duration: 875.902736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:18.338003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:17.461096Z","time spent":"876.843102ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:53:18.338986Z","caller":"traceutil/trace.go:171","msg":"trace[1471133529] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:548; }","duration":"679.092288ms","start":"2026-04-28T06:53:17.659883Z","end":"2026-04-28T06:53:18.338975Z","steps":["trace[1471133529] 'read index received' (duration: 656.968076ms)","trace[1471133529] 'applied index is now lower than readState.Index' (duration: 22.123412ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:53:18.340276Z","caller":"traceutil/trace.go:171","msg":"trace[1135200235] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"646.892206ms","start":"2026-04-28T06:53:17.693373Z","end":"2026-04-28T06:53:18.340266Z","steps":["trace[1135200235] 'process raft request' (duration: 644.182764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:18.340326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.913566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:53:18.340343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:17.693342Z","time spent":"646.958087ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:53:18.340388Z","caller":"traceutil/trace.go:171","msg":"trace[428765786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"319.024989ms","start":"2026-04-28T06:53:18.021345Z","end":"2026-04-28T06:53:18.34037Z","steps":["trace[428765786] 'agreement among raft nodes before linearized reading' (duration: 318.896736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:18.34043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:18.021322Z","time spent":"319.099561ms","remote":"127.0.0.1:41096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:53:18.340868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.977618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T06:53:18.341265Z","caller":"traceutil/trace.go:171","msg":"trace[1531472527] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:529; }","duration":"681.403919ms","start":"2026-04-28T06:53:17.659849Z","end":"2026-04-28T06:53:18.341253Z","steps":["trace[1531472527] 'agreement among raft nodes before linearized reading' (duration: 680.29205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:18.341296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:17.659835Z","time spent":"681.454102ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"warn","ts":"2026-04-28T06:53:21.395285Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765017878362266,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:53:21.896132Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765017878362266,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:53:22.39657Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765017878362266,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:53:22.746257Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.486167517s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T06:53:22.746833Z","caller":"traceutil/trace.go:171","msg":"trace[1871687176] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"3.486815164s","start":"2026-04-28T06:53:19.259993Z","end":"2026-04-28T06:53:22.746808Z","steps":["trace[1871687176] 'process raft request' (duration: 3.486591529s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:22.747023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:19.25997Z","time spent":"3.486974159s","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:53:22.797294Z","caller":"traceutil/trace.go:171","msg":"trace[1983472837] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"2.576179578s","start":"2026-04-28T06:53:20.22109Z","end":"2026-04-28T06:53:22.79727Z","steps":["trace[1983472837] 'process raft request' (duration: 2.576003253s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:22.797412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:20.22107Z","time spent":"2.576280441s","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:53:22.820789Z","caller":"traceutil/trace.go:171","msg":"trace[1836645494] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:577; }","duration":"1.925899722s","start":"2026-04-28T06:53:20.894862Z","end":"2026-04-28T06:53:22.820762Z","steps":["trace[1836645494] 'read index received' (duration: 1.851742877s)","trace[1836645494] 'applied index is now lower than readState.Index' (duration: 74.155465ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:53:22.821004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.89365888s","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-04-28T06:53:22.821043Z","caller":"traceutil/trace.go:171","msg":"trace[145854147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:556; }","duration":"1.893770372s","start":"2026-04-28T06:53:20.927261Z","end":"2026-04-28T06:53:22.821031Z","steps":["trace[145854147] 'agreement among raft nodes before linearized reading' (duration: 1.89367261s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:22.821082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:20.927239Z","time spent":"1.893835114s","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T06:53:22.821066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.926180759s","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-04-28T06:53:22.821134Z","caller":"traceutil/trace.go:171","msg":"trace[1494025790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:556; }","duration":"1.926300612s","start":"2026-04-28T06:53:20.894819Z","end":"2026-04-28T06:53:22.82112Z","steps":["trace[1494025790] 'agreement among raft nodes before linearized reading' (duration: 1.926079997s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:22.821175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:20.8948Z","time spent":"1.926367385s","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:53:22.821275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.799954641s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:53:22.821296Z","caller":"traceutil/trace.go:171","msg":"trace[9427403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"1.800002643s","start":"2026-04-28T06:53:21.021287Z","end":"2026-04-28T06:53:22.821289Z","steps":["trace[9427403] 'agreement among raft nodes before linearized reading' (duration: 1.799969182s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:22.821321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:21.02127Z","time spent":"1.800045713s","remote":"127.0.0.1:41080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:53:22.821336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.742205886s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-04-28T06:53:22.821371Z","caller":"traceutil/trace.go:171","msg":"trace[1653528637] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:556; }","duration":"1.742267507s","start":"2026-04-28T06:53:21.079093Z","end":"2026-04-28T06:53:22.82136Z","steps":["trace[1653528637] 'agreement among raft nodes before linearized reading' (duration: 1.742103143s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:22.821403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:21.079078Z","time spent":"1.742318318s","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":29593,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-04-28T06:53:22.821508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.891822s","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-04-28T06:53:22.821547Z","caller":"traceutil/trace.go:171","msg":"trace[1445674991] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:556; }","duration":"1.891899712s","start":"2026-04-28T06:53:20.929638Z","end":"2026-04-28T06:53:22.821538Z","steps":["trace[1445674991] 'agreement among raft nodes before linearized reading' (duration: 1.89183179s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:22.821571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:20.929618Z","time spent":"1.891946423s","remote":"127.0.0.1:41372","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-04-28T06:53:27.392052Z","caller":"traceutil/trace.go:171","msg":"trace[818095520] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"330.796494ms","start":"2026-04-28T06:53:27.061235Z","end":"2026-04-28T06:53:27.392031Z","steps":["trace[818095520] 'process raft request' (duration: 330.655501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:27.392251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:27.061206Z","time spent":"330.927059ms","remote":"127.0.0.1:41476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:53:27.691628Z","caller":"traceutil/trace.go:171","msg":"trace[848001607] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"282.701327ms","start":"2026-04-28T06:53:27.408903Z","end":"2026-04-28T06:53:27.691605Z","steps":["trace[848001607] 'process raft request' (duration: 282.571413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:28.69621Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.004701323s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-28T06:53:29.673776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.150746ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:53:29.673964Z","caller":"traceutil/trace.go:171","msg":"trace[1185762383] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:606; }","duration":"2.013168879s","start":"2026-04-28T06:53:27.660736Z","end":"2026-04-28T06:53:29.673905Z","steps":["trace[1185762383] 'read index received' (duration: 30.817745ms)","trace[1185762383] 'applied index is now lower than readState.Index' (duration: 1.982349694s)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:53:29.674201Z","caller":"traceutil/trace.go:171","msg":"trace[1049163892] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"2.190493255s","start":"2026-04-28T06:53:27.48369Z","end":"2026-04-28T06:53:29.674183Z","steps":["trace[1049163892] 'process raft request' (duration: 1.212714513s)","trace[1049163892] 'compare' (duration: 976.994691ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:53:29.67433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:27.483658Z","time spent":"2.190603218s","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:53:29.675716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.013996131s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T06:53:29.675785Z","caller":"traceutil/trace.go:171","msg":"trace[239875743] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:584; }","duration":"2.015082009s","start":"2026-04-28T06:53:27.660687Z","end":"2026-04-28T06:53:29.67577Z","steps":["trace[239875743] 'agreement among raft nodes before linearized reading' (duration: 2.013730214s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:29.675812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:27.660669Z","time spent":"2.015134171s","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"warn","ts":"2026-04-28T06:53:29.678599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.657383635s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:53:29.678663Z","caller":"traceutil/trace.go:171","msg":"trace[254006705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"1.657471858s","start":"2026-04-28T06:53:28.021173Z","end":"2026-04-28T06:53:29.678645Z","steps":["trace[254006705] 'agreement among raft nodes before linearized reading' (duration: 1.657373085s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:29.678699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:28.021158Z","time spent":"1.657531449s","remote":"127.0.0.1:41080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:53:29.678707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.615035ms","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-04-28T06:53:29.678806Z","caller":"traceutil/trace.go:171","msg":"trace[1198290208] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"802.759549ms","start":"2026-04-28T06:53:28.876026Z","end":"2026-04-28T06:53:29.678786Z","steps":["trace[1198290208] 'agreement among raft nodes before linearized reading' (duration: 802.545652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:29.678865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:28.876002Z","time spent":"802.846491ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:53:29.678894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.879211ms","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-04-28T06:53:29.678914Z","caller":"traceutil/trace.go:171","msg":"trace[192405989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:585; }","duration":"802.945534ms","start":"2026-04-28T06:53:28.875962Z","end":"2026-04-28T06:53:29.678908Z","steps":["trace[192405989] 'agreement among raft nodes before linearized reading' (duration: 802.901733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:29.678934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:28.875937Z","time spent":"802.992974ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T06:53:29.678987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.311037ms","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-04-28T06:53:29.679001Z","caller":"traceutil/trace.go:171","msg":"trace[2027370359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:585; }","duration":"808.352708ms","start":"2026-04-28T06:53:28.870644Z","end":"2026-04-28T06:53:29.678996Z","steps":["trace[2027370359] 'agreement among raft nodes before linearized reading' (duration: 808.328047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:29.679021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:28.870629Z","time spent":"808.38739ms","remote":"127.0.0.1:41372","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-04-28T06:53:29.679079Z","caller":"traceutil/trace.go:171","msg":"trace[1697610433] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.884581227s","start":"2026-04-28T06:53:27.794481Z","end":"2026-04-28T06:53:29.679062Z","steps":["trace[1697610433] 'process raft request' (duration: 1.88355577s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:29.679131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.70138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T06:53:29.679151Z","caller":"traceutil/trace.go:171","msg":"trace[1915531127] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"912.749692ms","start":"2026-04-28T06:53:28.766396Z","end":"2026-04-28T06:53:29.679145Z","steps":["trace[1915531127] 'agreement among raft nodes before linearized reading' (duration: 912.68643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:29.679184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:28.766381Z","time spent":"912.798623ms","remote":"127.0.0.1:41372","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-04-28T06:53:29.679248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:27.794461Z","time spent":"1.884651798s","remote":"127.0.0.1:41196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:53:31.690143Z","caller":"traceutil/trace.go:171","msg":"trace[763578567] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"216.056454ms","start":"2026-04-28T06:53:31.474055Z","end":"2026-04-28T06:53:31.690111Z","steps":["trace[763578567] 'process raft request' (duration: 215.779386ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:34.598386Z","caller":"traceutil/trace.go:171","msg":"trace[1047360845] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"108.419493ms","start":"2026-04-28T06:53:34.489944Z","end":"2026-04-28T06:53:34.598364Z","steps":["trace[1047360845] 'process raft request' (duration: 108.250498ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:43.374984Z","caller":"traceutil/trace.go:171","msg":"trace[1866039140] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"243.556106ms","start":"2026-04-28T06:53:43.131396Z","end":"2026-04-28T06:53:43.374952Z","steps":["trace[1866039140] 'process raft request' (duration: 243.341531ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:43.445722Z","caller":"traceutil/trace.go:171","msg":"trace[1815257666] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"127.759653ms","start":"2026-04-28T06:53:43.317945Z","end":"2026-04-28T06:53:43.445705Z","steps":["trace[1815257666] 'process raft request' (duration: 127.63039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:47.237483Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765017878362748,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:53:47.737996Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765017878362748,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:53:47.926578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.483400266s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:53:47.926705Z","caller":"traceutil/trace.go:171","msg":"trace[383286949] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:756; }","duration":"1.483543641s","start":"2026-04-28T06:53:46.443145Z","end":"2026-04-28T06:53:47.926689Z","steps":["trace[383286949] 'range keys from in-memory index tree' (duration: 1.483383216s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:48.238652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765017878362748,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:53:48.66557Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.220458647s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T06:53:48.666973Z","caller":"traceutil/trace.go:171","msg":"trace[293363228] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:785; }","duration":"1.930368443s","start":"2026-04-28T06:53:46.73659Z","end":"2026-04-28T06:53:48.666958Z","steps":["trace[293363228] 'read index received' (duration: 1.929252943s)","trace[293363228] 'applied index is now lower than readState.Index' (duration: 1.11486ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:53:48.667062Z","caller":"traceutil/trace.go:171","msg":"trace[1175166425] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"2.22205906s","start":"2026-04-28T06:53:46.444996Z","end":"2026-04-28T06:53:48.667055Z","steps":["trace[1175166425] 'process raft request' (duration: 2.221831244s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:48.667124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:46.444973Z","time spent":"2.222103221s","remote":"127.0.0.1:41354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:53:48.66727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.930665881s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:53:48.667335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.274265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:53:48.66734Z","caller":"traceutil/trace.go:171","msg":"trace[245661507] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:757; }","duration":"1.930786726s","start":"2026-04-28T06:53:46.736541Z","end":"2026-04-28T06:53:48.667328Z","steps":["trace[245661507] 'agreement among raft nodes before linearized reading' (duration: 1.930654241s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:48.667332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.732551ms","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-04-28T06:53:48.667355Z","caller":"traceutil/trace.go:171","msg":"trace[2025770645] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:757; }","duration":"494.296495ms","start":"2026-04-28T06:53:48.173054Z","end":"2026-04-28T06:53:48.66735Z","steps":["trace[2025770645] 'agreement among raft nodes before linearized reading' (duration: 494.263154ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:48.66738Z","caller":"traceutil/trace.go:171","msg":"trace[269314403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:757; }","duration":"864.790852ms","start":"2026-04-28T06:53:47.802576Z","end":"2026-04-28T06:53:48.667366Z","steps":["trace[269314403] 'agreement among raft nodes before linearized reading' (duration: 864.617027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:48.667383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:46.736521Z","time spent":"1.930854637s","remote":"127.0.0.1:41258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:53:48.667426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:47.802572Z","time spent":"864.847054ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:53:48.667447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005272076s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-28T06:53:48.667437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.66428ms","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-04-28T06:53:48.667474Z","caller":"traceutil/trace.go:171","msg":"trace[742876907] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:757; }","duration":"1.005353139s","start":"2026-04-28T06:53:47.662104Z","end":"2026-04-28T06:53:48.667457Z","steps":["trace[742876907] 'agreement among raft nodes before linearized reading' (duration: 1.005310018s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:48.667491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:47.662084Z","time spent":"1.00540423s","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"warn","ts":"2026-04-28T06:53:48.667503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113232778s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:53:48.667505Z","caller":"traceutil/trace.go:171","msg":"trace[1848647576] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:757; }","duration":"408.774163ms","start":"2026-04-28T06:53:48.25871Z","end":"2026-04-28T06:53:48.667485Z","steps":["trace[1848647576] 'agreement among raft nodes before linearized reading' (duration: 408.64528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:48.667371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:48.172997Z","time spent":"494.370458ms","remote":"127.0.0.1:41314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:53:48.667584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.06372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-28T06:53:48.667581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:48.258691Z","time spent":"408.869936ms","remote":"127.0.0.1:41436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-04-28T06:53:48.66761Z","caller":"traceutil/trace.go:171","msg":"trace[163806679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:757; }","duration":"865.120872ms","start":"2026-04-28T06:53:47.802485Z","end":"2026-04-28T06:53:48.667605Z","steps":["trace[163806679] 'agreement among raft nodes before linearized reading' (duration: 865.06897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:48.667624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:47.802467Z","time spent":"865.153772ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T06:53:48.667605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.960569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:53:48.667667Z","caller":"traceutil/trace.go:171","msg":"trace[1690942047] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:757; }","duration":"727.04395ms","start":"2026-04-28T06:53:47.940598Z","end":"2026-04-28T06:53:48.667642Z","steps":["trace[1690942047] 'agreement among raft nodes before linearized reading' (duration: 726.955018ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:48.66758Z","caller":"traceutil/trace.go:171","msg":"trace[1086278052] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:757; }","duration":"1.113324461s","start":"2026-04-28T06:53:47.554217Z","end":"2026-04-28T06:53:48.667541Z","steps":["trace[1086278052] 'agreement among raft nodes before linearized reading' (duration: 1.113200768s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:48.667313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.645872811s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:53:48.667753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.277173762s","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-04-28T06:53:48.667763Z","caller":"traceutil/trace.go:171","msg":"trace[1536366620] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:757; }","duration":"1.646365573s","start":"2026-04-28T06:53:47.021382Z","end":"2026-04-28T06:53:48.667747Z","steps":["trace[1536366620] 'agreement among raft nodes before linearized reading' (duration: 1.645815278s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:48.667795Z","caller":"traceutil/trace.go:171","msg":"trace[1626899360] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:757; }","duration":"1.277252513s","start":"2026-04-28T06:53:47.390533Z","end":"2026-04-28T06:53:48.667785Z","steps":["trace[1626899360] 'agreement among raft nodes before linearized reading' (duration: 1.277071869s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:48.667808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:47.021367Z","time spent":"1.646428875s","remote":"127.0.0.1:41080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:53:48.667832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:47.390514Z","time spent":"1.277309995s","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T06:53:48.6678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:47.554199Z","time spent":"1.113576738s","remote":"127.0.0.1:41490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":52,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:53:48.667721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.750217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T06:53:48.667981Z","caller":"traceutil/trace.go:171","msg":"trace[1298063652] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:757; }","duration":"866.033147ms","start":"2026-04-28T06:53:47.801934Z","end":"2026-04-28T06:53:48.667967Z","steps":["trace[1298063652] 'agreement among raft nodes before linearized reading' (duration: 865.759369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:48.668009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:47.801891Z","time spent":"866.110839ms","remote":"127.0.0.1:41372","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-04-28T06:53:48.66771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:47.940584Z","time spent":"727.116483ms","remote":"127.0.0.1:41208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:53:48.669116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.932091ms","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-04-28T06:53:48.669175Z","caller":"traceutil/trace.go:171","msg":"trace[79329814] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:757; }","duration":"860.015704ms","start":"2026-04-28T06:53:47.809144Z","end":"2026-04-28T06:53:48.66916Z","steps":["trace[79329814] 'agreement among raft nodes before linearized reading' (duration: 859.85695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:48.669209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:53:47.809133Z","time spent":"860.067845ms","remote":"127.0.0.1:41372","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-04-28T06:54:21.139225Z","caller":"traceutil/trace.go:171","msg":"trace[366628329] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:998; }","duration":"182.632593ms","start":"2026-04-28T06:54:20.956542Z","end":"2026-04-28T06:54:21.139174Z","steps":["trace[366628329] 'read index received' (duration: 143.656009ms)","trace[366628329] 'applied index is now lower than readState.Index' (duration: 38.975414ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:54:21.139339Z","caller":"traceutil/trace.go:171","msg":"trace[432239662] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"253.518702ms","start":"2026-04-28T06:54:20.885766Z","end":"2026-04-28T06:54:21.139285Z","steps":["trace[432239662] 'process raft request' (duration: 214.388504ms)","trace[432239662] 'compare' (duration: 38.774348ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:54:21.139452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.8823ms","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-04-28T06:54:21.139536Z","caller":"traceutil/trace.go:171","msg":"trace[1288675078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:961; }","duration":"183.011733ms","start":"2026-04-28T06:54:20.956505Z","end":"2026-04-28T06:54:21.139516Z","steps":["trace[1288675078] 'agreement among raft nodes before linearized reading' (duration: 182.764087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:21.139533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.968862ms","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-04-28T06:54:21.139607Z","caller":"traceutil/trace.go:171","msg":"trace[1299853424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:961; }","duration":"183.052624ms","start":"2026-04-28T06:54:20.956535Z","end":"2026-04-28T06:54:21.139588Z","steps":["trace[1299853424] 'agreement among raft nodes before linearized reading' (duration: 182.832238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:21.139753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.54745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T06:54:21.139786Z","caller":"traceutil/trace.go:171","msg":"trace[1912434774] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:961; }","duration":"182.613473ms","start":"2026-04-28T06:54:20.957162Z","end":"2026-04-28T06:54:21.139776Z","steps":["trace[1912434774] 'agreement among raft nodes before linearized reading' (duration: 182.53149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:21.139788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.743912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:54:21.139833Z","caller":"traceutil/trace.go:171","msg":"trace[341769081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"118.831934ms","start":"2026-04-28T06:54:21.020986Z","end":"2026-04-28T06:54:21.139818Z","steps":["trace[341769081] 'agreement among raft nodes before linearized reading' (duration: 118.765681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:23.948647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.329233ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:54:23.948767Z","caller":"traceutil/trace.go:171","msg":"trace[1987674460] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"759.647434ms","start":"2026-04-28T06:54:23.1891Z","end":"2026-04-28T06:54:23.948747Z","steps":["trace[1987674460] 'process raft request' (duration: 617.126936ms)","trace[1987674460] 'compare' (duration: 142.144068ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:54:23.948831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:23.189074Z","time spent":"759.726407ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:54:23.975469Z","caller":"traceutil/trace.go:171","msg":"trace[1015342804] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"230.934958ms","start":"2026-04-28T06:54:23.74451Z","end":"2026-04-28T06:54:23.975445Z","steps":["trace[1015342804] 'process raft request' (duration: 230.904817ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:23.975534Z","caller":"traceutil/trace.go:171","msg":"trace[1698096774] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"786.009971ms","start":"2026-04-28T06:54:23.189496Z","end":"2026-04-28T06:54:23.975506Z","steps":["trace[1698096774] 'process raft request' (duration: 785.894019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:23.975665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:23.189474Z","time spent":"786.117785ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:54:23.975864Z","caller":"traceutil/trace.go:171","msg":"trace[609797954] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"786.412251ms","start":"2026-04-28T06:54:23.189441Z","end":"2026-04-28T06:54:23.975854Z","steps":["trace[609797954] 'process raft request' (duration: 785.854597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:23.975928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:23.189419Z","time spent":"786.478874ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:54:24.855817Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765017878363220,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:54:25.356778Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765017878363220,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:54:25.85743Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765017878363220,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:54:26.357791Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765017878363220,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:54:26.557951Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.26158568s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T06:54:26.558241Z","caller":"traceutil/trace.go:171","msg":"trace[1397783475] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"2.261955709s","start":"2026-04-28T06:54:24.296265Z","end":"2026-04-28T06:54:26.558221Z","steps":["trace[1397783475] 'process raft request' (duration: 2.261837196s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:26.558359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:24.296239Z","time spent":"2.262053051s","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:54:27.368034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999816755s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-28T06:54:27.368121Z","caller":"traceutil/trace.go:171","msg":"trace[1661241622] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999957109s","start":"2026-04-28T06:54:25.368146Z","end":"2026-04-28T06:54:27.368103Z","steps":["trace[1661241622] 'agreement among raft nodes before linearized reading' (duration: 1.999847316s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:27.368194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:25.368126Z","time spent":"2.000052362s","remote":"127.0.0.1:41080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/28 06:54:27 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-28T06:54:28.020862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999999019s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-28T06:54:28.02094Z","caller":"traceutil/trace.go:171","msg":"trace[1049300438] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000106612s","start":"2026-04-28T06:54:26.020818Z","end":"2026-04-28T06:54:28.020925Z","steps":["trace[1049300438] 'agreement among raft nodes before linearized reading' (duration: 2.00000815s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:28.020972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:26.020811Z","time spent":"2.000154784s","remote":"127.0.0.1:41096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:54:28.033751Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.475666281s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-28T06:54:28.85188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.882875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:54:28.852015Z","caller":"traceutil/trace.go:171","msg":"trace[880315275] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"1.481842585s","start":"2026-04-28T06:54:27.370159Z","end":"2026-04-28T06:54:28.852002Z","steps":["trace[880315275] 'process raft request' (duration: 1.481800944s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:28.852056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:27.37014Z","time spent":"1.481899118s","remote":"127.0.0.1:41172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:54:28.852263Z","caller":"traceutil/trace.go:171","msg":"trace[1410740768] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"2.871642855s","start":"2026-04-28T06:54:25.980611Z","end":"2026-04-28T06:54:28.852253Z","steps":["trace[1410740768] 'process raft request' (duration: 2.053317848s)","trace[1410740768] 'compare' (duration: 817.406003ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:54:28.852299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:25.980589Z","time spent":"2.871690397s","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:54:28.85238Z","caller":"traceutil/trace.go:171","msg":"trace[812980990] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1014; }","duration":"4.497074169s","start":"2026-04-28T06:54:24.355298Z","end":"2026-04-28T06:54:28.852372Z","steps":["trace[812980990] 'read index received' (duration: 2.202847256s)","trace[812980990] 'applied index is now lower than readState.Index' (duration: 2.294225903s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:54:28.852477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.279831278s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:54:28.852497Z","caller":"traceutil/trace.go:171","msg":"trace[2139036699] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:978; }","duration":"4.279882518s","start":"2026-04-28T06:54:24.572608Z","end":"2026-04-28T06:54:28.85249Z","steps":["trace[2139036699] 'agreement among raft nodes before linearized reading' (duration: 4.279838967s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:28.852517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:24.572591Z","time spent":"4.27992078s","remote":"127.0.0.1:41622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:54:28.852526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.497207452s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:54:28.852549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.564052573s","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":"info","ts":"2026-04-28T06:54:28.852605Z","caller":"traceutil/trace.go:171","msg":"trace[1340304875] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:978; }","duration":"4.497338146s","start":"2026-04-28T06:54:24.355246Z","end":"2026-04-28T06:54:28.852584Z","steps":["trace[1340304875] 'agreement among raft nodes before linearized reading' (duration: 4.497189932s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:28.852623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.855575604s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:54:28.852596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.550255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:54:28.852649Z","caller":"traceutil/trace.go:171","msg":"trace[1370620472] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:978; }","duration":"2.855634886s","start":"2026-04-28T06:54:25.997006Z","end":"2026-04-28T06:54:28.85264Z","steps":["trace[1370620472] 'agreement among raft nodes before linearized reading' (duration: 2.855597205s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:28.852653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:24.355229Z","time spent":"4.497413058s","remote":"127.0.0.1:56856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:54:28.852669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:25.996997Z","time spent":"2.855666768s","remote":"127.0.0.1:56864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-04-28T06:54:28.852674Z","caller":"traceutil/trace.go:171","msg":"trace[432034639] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:978; }","duration":"418.669988ms","start":"2026-04-28T06:54:28.433985Z","end":"2026-04-28T06:54:28.852655Z","steps":["trace[432034639] 'agreement among raft nodes before linearized reading' (duration: 418.537545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:28.852723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:28.433971Z","time spent":"418.74201ms","remote":"127.0.0.1:41572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:54:28.852747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.944088847s","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":"warn","ts":"2026-04-28T06:54:28.852805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.66449303s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-28T06:54:28.852826Z","caller":"traceutil/trace.go:171","msg":"trace[1144447584] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:978; }","duration":"3.664561122s","start":"2026-04-28T06:54:25.188258Z","end":"2026-04-28T06:54:28.85282Z","steps":["trace[1144447584] 'agreement among raft nodes before linearized reading' (duration: 3.6644828s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:28.85285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:25.18824Z","time spent":"3.664603293s","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-28T06:54:28.852812Z","caller":"traceutil/trace.go:171","msg":"trace[681743647] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:978; }","duration":"2.94420126s","start":"2026-04-28T06:54:25.90859Z","end":"2026-04-28T06:54:28.852791Z","steps":["trace[681743647] 'agreement among raft nodes before linearized reading' (duration: 2.944103226s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:28.852931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.176613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-28T06:54:28.852948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:25.908569Z","time spent":"2.944371875s","remote":"127.0.0.1:41448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:54:28.85295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.902694933s","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-04-28T06:54:28.852622Z","caller":"traceutil/trace.go:171","msg":"trace[1824643368] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:978; }","duration":"2.564160086s","start":"2026-04-28T06:54:26.288443Z","end":"2026-04-28T06:54:28.852603Z","steps":["trace[1824643368] 'agreement among raft nodes before linearized reading' (duration: 2.564025473s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:28.852972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.872859368s","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-04-28T06:54:28.852995Z","caller":"traceutil/trace.go:171","msg":"trace[89875938] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:978; }","duration":"3.902769865s","start":"2026-04-28T06:54:24.950214Z","end":"2026-04-28T06:54:28.852984Z","steps":["trace[89875938] 'agreement among raft nodes before linearized reading' (duration: 3.902622021s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:28.853001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:26.288428Z","time spent":"2.564562547s","remote":"127.0.0.1:57014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-04-28T06:54:28.85302Z","caller":"traceutil/trace.go:171","msg":"trace[1247835562] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:978; }","duration":"2.87293002s","start":"2026-04-28T06:54:25.980076Z","end":"2026-04-28T06:54:28.853006Z","steps":["trace[1247835562] 'agreement among raft nodes before linearized reading' (duration: 2.872765796s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:28.853029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:24.950199Z","time spent":"3.902822346s","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:54:28.853065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:25.980063Z","time spent":"2.872990512s","remote":"127.0.0.1:41372","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-04-28T06:54:28.852962Z","caller":"traceutil/trace.go:171","msg":"trace[1744786948] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:978; }","duration":"116.234804ms","start":"2026-04-28T06:54:28.736718Z","end":"2026-04-28T06:54:28.852953Z","steps":["trace[1744786948] 'agreement among raft nodes before linearized reading' (duration: 116.178073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:28.85317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.874599244s","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-04-28T06:54:28.853193Z","caller":"traceutil/trace.go:171","msg":"trace[888246602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:978; }","duration":"2.874645276s","start":"2026-04-28T06:54:25.978541Z","end":"2026-04-28T06:54:28.853187Z","steps":["trace[888246602] 'agreement among raft nodes before linearized reading' (duration: 2.874580994s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:28.853212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:25.978531Z","time spent":"2.874676386s","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:54:28.853284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.899495301s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-28T06:54:28.853329Z","caller":"traceutil/trace.go:171","msg":"trace[400960431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:978; }","duration":"2.899580213s","start":"2026-04-28T06:54:25.953736Z","end":"2026-04-28T06:54:28.853316Z","steps":["trace[400960431] 'agreement among raft nodes before linearized reading' (duration: 2.89944671s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:28.853371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190301363s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-28T06:54:28.853365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:25.953721Z","time spent":"2.899635404s","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T06:54:28.853395Z","caller":"traceutil/trace.go:171","msg":"trace[1098940792] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:978; }","duration":"1.190368035s","start":"2026-04-28T06:54:27.663017Z","end":"2026-04-28T06:54:28.853385Z","steps":["trace[1098940792] 'agreement among raft nodes before linearized reading' (duration: 1.190311064s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:28.853417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:27.662996Z","time spent":"1.190414667s","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"warn","ts":"2026-04-28T06:54:28.853467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.405699236s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:54:28.853507Z","caller":"traceutil/trace.go:171","msg":"trace[1485962763] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:978; }","duration":"1.405784508s","start":"2026-04-28T06:54:27.447711Z","end":"2026-04-28T06:54:28.853496Z","steps":["trace[1485962763] 'agreement among raft nodes before linearized reading' (duration: 1.405715566s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:28.853539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:27.447687Z","time spent":"1.40584392s","remote":"127.0.0.1:41368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:54:28.853964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.509849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:54:28.854028Z","caller":"traceutil/trace.go:171","msg":"trace[1931145071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:978; }","duration":"825.565911ms","start":"2026-04-28T06:54:28.028442Z","end":"2026-04-28T06:54:28.854008Z","steps":["trace[1931145071] 'agreement among raft nodes before linearized reading' (duration: 824.207834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:28.854057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:28.028396Z","time spent":"825.653973ms","remote":"127.0.0.1:41080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T06:54:29.012643Z","caller":"traceutil/trace.go:171","msg":"trace[1841493434] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1017; }","duration":"157.829409ms","start":"2026-04-28T06:54:28.85479Z","end":"2026-04-28T06:54:29.01262Z","steps":["trace[1841493434] 'read index received' (duration: 126.446518ms)","trace[1841493434] 'applied index is now lower than readState.Index' (duration: 31.381521ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:54:29.012784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.966863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:54:29.012814Z","caller":"traceutil/trace.go:171","msg":"trace[444385842] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:978; }","duration":"158.033165ms","start":"2026-04-28T06:54:28.854772Z","end":"2026-04-28T06:54:29.012806Z","steps":["trace[444385842] 'agreement among raft nodes before linearized reading' (duration: 157.944693ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:29.107186Z","caller":"traceutil/trace.go:171","msg":"trace[1971801793] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"250.463291ms","start":"2026-04-28T06:54:28.856687Z","end":"2026-04-28T06:54:29.10715Z","steps":["trace[1971801793] 'process raft request' (duration: 250.164663ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:29.107229Z","caller":"traceutil/trace.go:171","msg":"trace[539057318] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"250.39149ms","start":"2026-04-28T06:54:28.856776Z","end":"2026-04-28T06:54:29.107167Z","steps":["trace[539057318] 'process raft request' (duration: 250.224045ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:29.107244Z","caller":"traceutil/trace.go:171","msg":"trace[700955054] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"250.39922ms","start":"2026-04-28T06:54:28.856788Z","end":"2026-04-28T06:54:29.107187Z","steps":["trace[700955054] 'process raft request' (duration: 250.247296ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:29.107297Z","caller":"traceutil/trace.go:171","msg":"trace[1381506205] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"250.226744ms","start":"2026-04-28T06:54:28.857037Z","end":"2026-04-28T06:54:29.107263Z","steps":["trace[1381506205] 'process raft request' (duration: 250.029339ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:29.107408Z","caller":"traceutil/trace.go:171","msg":"trace[784138762] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"250.229824ms","start":"2026-04-28T06:54:28.857157Z","end":"2026-04-28T06:54:29.107386Z","steps":["trace[784138762] 'process raft request' (duration: 249.961727ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:29.107543Z","caller":"traceutil/trace.go:171","msg":"trace[614227114] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"249.375752ms","start":"2026-04-28T06:54:28.858156Z","end":"2026-04-28T06:54:29.107531Z","steps":["trace[614227114] 'process raft request' (duration: 249.280629ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:29.356468Z","caller":"traceutil/trace.go:171","msg":"trace[59719171] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"342.895368ms","start":"2026-04-28T06:54:29.013515Z","end":"2026-04-28T06:54:29.356411Z","steps":["trace[59719171] 'process raft request' (duration: 250.42631ms)","trace[59719171] 'compare' (duration: 92.242162ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:54:29.356838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:29.013501Z","time spent":"343.221446ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:54:30.992963Z","caller":"traceutil/trace.go:171","msg":"trace[129421730] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"135.182423ms","start":"2026-04-28T06:54:30.857762Z","end":"2026-04-28T06:54:30.992944Z","steps":["trace[129421730] 'process raft request' (duration: 135.080649ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:49.546904Z","caller":"traceutil/trace.go:171","msg":"trace[354413373] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1089; }","duration":"359.783765ms","start":"2026-04-28T06:54:49.187101Z","end":"2026-04-28T06:54:49.546885Z","steps":["trace[354413373] 'read index received' (duration: 359.618379ms)","trace[354413373] 'applied index is now lower than readState.Index' (duration: 164.516µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:54:49.547034Z","caller":"traceutil/trace.go:171","msg":"trace[1364168591] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"498.110122ms","start":"2026-04-28T06:54:49.048912Z","end":"2026-04-28T06:54:49.547022Z","steps":["trace[1364168591] 'process raft request' (duration: 497.843824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:49.547121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:49.048887Z","time spent":"498.177093ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:54:49.547165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.035591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-28T06:54:49.547225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.650624ms","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-04-28T06:54:49.547272Z","caller":"traceutil/trace.go:171","msg":"trace[245421836] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1046; }","duration":"360.200625ms","start":"2026-04-28T06:54:49.187051Z","end":"2026-04-28T06:54:49.547252Z","steps":["trace[245421836] 'agreement among raft nodes before linearized reading' (duration: 359.951118ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:49.547281Z","caller":"traceutil/trace.go:171","msg":"trace[931705545] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1046; }","duration":"314.757307ms","start":"2026-04-28T06:54:49.232507Z","end":"2026-04-28T06:54:49.547264Z","steps":["trace[931705545] 'agreement among raft nodes before linearized reading' (duration: 314.577233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:49.547321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:49.232485Z","time spent":"314.825628ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:54:49.547322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:49.187032Z","time spent":"360.278587ms","remote":"127.0.0.1:41506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:54:49.547323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.38035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-04-28T06:54:49.547318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.294648ms","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-04-28T06:54:49.547357Z","caller":"traceutil/trace.go:171","msg":"trace[1486372007] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1046; }","duration":"313.429181ms","start":"2026-04-28T06:54:49.233921Z","end":"2026-04-28T06:54:49.547351Z","steps":["trace[1486372007] 'agreement among raft nodes before linearized reading' (duration: 313.355689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:49.547331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.011529ms","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":"warn","ts":"2026-04-28T06:54:49.54738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:49.233915Z","time spent":"313.460792ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-28T06:54:49.54739Z","caller":"traceutil/trace.go:171","msg":"trace[1735539134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1046; }","duration":"313.38327ms","start":"2026-04-28T06:54:49.23399Z","end":"2026-04-28T06:54:49.547373Z","steps":["trace[1735539134] 'agreement among raft nodes before linearized reading' (duration: 313.146954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:49.54742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.40776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-28T06:54:49.547421Z","caller":"traceutil/trace.go:171","msg":"trace[1367703788] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1046; }","duration":"290.142742ms","start":"2026-04-28T06:54:49.25725Z","end":"2026-04-28T06:54:49.547393Z","steps":["trace[1367703788] 'agreement among raft nodes before linearized reading' (duration: 289.990268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:49.547448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:49.233987Z","time spent":"313.436591ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T06:54:49.547475Z","caller":"traceutil/trace.go:171","msg":"trace[590178113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1046; }","duration":"313.485083ms","start":"2026-04-28T06:54:49.233973Z","end":"2026-04-28T06:54:49.547458Z","steps":["trace[590178113] 'agreement among raft nodes before linearized reading' (duration: 313.296878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:49.547594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:49.23397Z","time spent":"313.613807ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T06:54:49.547492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.471263ms","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-04-28T06:54:49.547649Z","caller":"traceutil/trace.go:171","msg":"trace[1540508742] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1046; }","duration":"313.637388ms","start":"2026-04-28T06:54:49.234003Z","end":"2026-04-28T06:54:49.547641Z","steps":["trace[1540508742] 'agreement among raft nodes before linearized reading' (duration: 313.228156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:49.547677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:54:49.234001Z","time spent":"313.668378ms","remote":"127.0.0.1:41372","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-04-28T06:54:51.88944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.439681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:54:51.889753Z","caller":"traceutil/trace.go:171","msg":"trace[2040807121] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"297.792786ms","start":"2026-04-28T06:54:51.591874Z","end":"2026-04-28T06:54:51.889667Z","steps":["trace[2040807121] 'process raft request' (duration: 297.729554ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:51.889841Z","caller":"traceutil/trace.go:171","msg":"trace[1623130220] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"297.763275ms","start":"2026-04-28T06:54:51.591998Z","end":"2026-04-28T06:54:51.889761Z","steps":["trace[1623130220] 'process raft request' (duration: 297.636662ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:51.889996Z","caller":"traceutil/trace.go:171","msg":"trace[929281975] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"298.70717ms","start":"2026-04-28T06:54:51.591245Z","end":"2026-04-28T06:54:51.889952Z","steps":["trace[929281975] 'process raft request' (duration: 298.31023ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:51.890033Z","caller":"traceutil/trace.go:171","msg":"trace[1366730672] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"299.137092ms","start":"2026-04-28T06:54:51.590855Z","end":"2026-04-28T06:54:51.889992Z","steps":["trace[1366730672] 'process raft request' (duration: 160.064435ms)","trace[1366730672] 'compare' (duration: 138.336607ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:54:52.125593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.597074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:54:52.125744Z","caller":"traceutil/trace.go:171","msg":"trace[940438683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1058; }","duration":"106.800979ms","start":"2026-04-28T06:54:52.018925Z","end":"2026-04-28T06:54:52.125726Z","steps":["trace[940438683] 'range keys from in-memory index tree' (duration: 106.460641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:05.217053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.196539958s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:55:05.21713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.229395ms","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":"info","ts":"2026-04-28T06:55:05.21714Z","caller":"traceutil/trace.go:171","msg":"trace[1945156652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1144; }","duration":"1.196660882s","start":"2026-04-28T06:55:04.020461Z","end":"2026-04-28T06:55:05.217122Z","steps":["trace[1945156652] 'range keys from in-memory index tree' (duration: 1.196469487s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:05.217165Z","caller":"traceutil/trace.go:171","msg":"trace[100864974] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1144; }","duration":"861.307127ms","start":"2026-04-28T06:55:04.355848Z","end":"2026-04-28T06:55:05.217155Z","steps":["trace[100864974] 'count revisions from in-memory index tree' (duration: 861.146923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:05.217177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:04.020445Z","time spent":"1.196722393s","remote":"127.0.0.1:41080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:55:05.217192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:04.355828Z","time spent":"861.354499ms","remote":"127.0.0.1:57016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:55:05.217318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271428829s","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-04-28T06:55:05.217389Z","caller":"traceutil/trace.go:171","msg":"trace[690821496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1144; }","duration":"1.271506961s","start":"2026-04-28T06:55:03.945866Z","end":"2026-04-28T06:55:05.217373Z","steps":["trace[690821496] 'range keys from in-memory index tree' (duration: 1.271284845s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:05.217418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:03.945824Z","time spent":"1.271586973s","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T06:55:05.217525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.269086037s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-28T06:55:05.217546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271496041s","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-04-28T06:55:05.217557Z","caller":"traceutil/trace.go:171","msg":"trace[1192100262] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1144; }","duration":"1.269147159s","start":"2026-04-28T06:55:03.9484Z","end":"2026-04-28T06:55:05.217548Z","steps":["trace[1192100262] 'range keys from in-memory index tree' (duration: 1.268965523s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:05.217578Z","caller":"traceutil/trace.go:171","msg":"trace[74632669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1144; }","duration":"1.271541662s","start":"2026-04-28T06:55:03.946028Z","end":"2026-04-28T06:55:05.21757Z","steps":["trace[74632669] 'range keys from in-memory index tree' (duration: 1.271440029s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:05.217582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:03.948388Z","time spent":"1.26918683s","remote":"127.0.0.1:41372","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-04-28T06:55:05.217602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:03.946022Z","time spent":"1.271573832s","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:55:05.217687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271082589s","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-04-28T06:55:05.21771Z","caller":"traceutil/trace.go:171","msg":"trace[748926788] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1144; }","duration":"1.27111726s","start":"2026-04-28T06:55:03.946587Z","end":"2026-04-28T06:55:05.217704Z","steps":["trace[748926788] 'range keys from in-memory index tree' (duration: 1.271028548s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:05.217729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:03.946581Z","time spent":"1.27114404s","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-28T06:55:21.978819Z","caller":"traceutil/trace.go:171","msg":"trace[907123196] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"305.8357ms","start":"2026-04-28T06:55:21.67296Z","end":"2026-04-28T06:55:21.978795Z","steps":["trace[907123196] 'process raft request' (duration: 305.724436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:21.978985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:21.672933Z","time spent":"305.980794ms","remote":"127.0.0.1:41172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:55:21.996656Z","caller":"traceutil/trace.go:171","msg":"trace[511904310] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"313.696589ms","start":"2026-04-28T06:55:21.682912Z","end":"2026-04-28T06:55:21.996609Z","steps":["trace[511904310] 'process raft request' (duration: 313.427622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:21.996804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.072726ms","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-04-28T06:55:21.996877Z","caller":"traceutil/trace.go:171","msg":"trace[1077380596] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1224; }","duration":"262.203819ms","start":"2026-04-28T06:55:21.734655Z","end":"2026-04-28T06:55:21.996859Z","steps":["trace[1077380596] 'agreement among raft nodes before linearized reading' (duration: 262.049096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:21.996812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:21.682898Z","time spent":"313.827522ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:21.996657Z","caller":"traceutil/trace.go:171","msg":"trace[1329160345] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"157.338743ms","start":"2026-04-28T06:55:21.839295Z","end":"2026-04-28T06:55:21.996634Z","steps":["trace[1329160345] 'process raft request' (duration: 157.24023ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:21.996628Z","caller":"traceutil/trace.go:171","msg":"trace[990419314] linearizableReadLoop","detail":"{readStateIndex:1275; appliedIndex:1273; }","duration":"261.903012ms","start":"2026-04-28T06:55:21.734691Z","end":"2026-04-28T06:55:21.996594Z","steps":["trace[990419314] 'read index received' (duration: 244.059418ms)","trace[990419314] 'applied index is now lower than readState.Index' (duration: 17.842354ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:55:21.997301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.072041ms","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-04-28T06:55:21.997355Z","caller":"traceutil/trace.go:171","msg":"trace[2093939247] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1224; }","duration":"243.166283ms","start":"2026-04-28T06:55:21.75417Z","end":"2026-04-28T06:55:21.997337Z","steps":["trace[2093939247] 'agreement among raft nodes before linearized reading' (duration: 243.02175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:22.76261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.791742ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:55:22.762741Z","caller":"traceutil/trace.go:171","msg":"trace[1667690202] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"761.537274ms","start":"2026-04-28T06:55:22.001178Z","end":"2026-04-28T06:55:22.762715Z","steps":["trace[1667690202] 'process raft request' (duration: 397.524147ms)","trace[1667690202] 'compare' (duration: 363.419532ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:55:22.762857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:22.001159Z","time spent":"761.625055ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:55:23.907823Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765017878363797,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T06:55:24.090719Z","caller":"traceutil/trace.go:171","msg":"trace[312173249] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"705.994737ms","start":"2026-04-28T06:55:23.384702Z","end":"2026-04-28T06:55:24.090697Z","steps":["trace[312173249] 'process raft request' (duration: 705.816883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:24.090862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:23.384673Z","time spent":"706.11906ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:24.449694Z","caller":"traceutil/trace.go:171","msg":"trace[264834997] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"796.522925ms","start":"2026-04-28T06:55:23.65314Z","end":"2026-04-28T06:55:24.449663Z","steps":["trace[264834997] 'process raft request' (duration: 796.32282ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:24.449735Z","caller":"traceutil/trace.go:171","msg":"trace[1873533736] linearizableReadLoop","detail":"{readStateIndex:1280; appliedIndex:1278; }","duration":"1.042338529s","start":"2026-04-28T06:55:23.407377Z","end":"2026-04-28T06:55:24.449716Z","steps":["trace[1873533736] 'read index received' (duration: 683.172081ms)","trace[1873533736] 'applied index is now lower than readState.Index' (duration: 359.165548ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:24.449799Z","caller":"traceutil/trace.go:171","msg":"trace[169412848] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"447.163278ms","start":"2026-04-28T06:55:24.002608Z","end":"2026-04-28T06:55:24.449771Z","steps":["trace[169412848] 'process raft request' (duration: 446.998743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:24.449894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:23.653122Z","time spent":"796.649078ms","remote":"127.0.0.1:41298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5883,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:55:24.449986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:24.002579Z","time spent":"447.332371ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:55:24.450094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.150439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:55:24.450093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042705099s","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-04-28T06:55:24.450148Z","caller":"traceutil/trace.go:171","msg":"trace[659773987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1228; }","duration":"429.226831ms","start":"2026-04-28T06:55:24.020898Z","end":"2026-04-28T06:55:24.450125Z","steps":["trace[659773987] 'agreement among raft nodes before linearized reading' (duration: 429.155189ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:24.450167Z","caller":"traceutil/trace.go:171","msg":"trace[916539716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1228; }","duration":"1.042814251s","start":"2026-04-28T06:55:23.407344Z","end":"2026-04-28T06:55:24.450159Z","steps":["trace[916539716] 'agreement among raft nodes before linearized reading' (duration: 1.042623066s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:24.450179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:24.020866Z","time spent":"429.306373ms","remote":"127.0.0.1:41080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:55:24.450198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:23.407331Z","time spent":"1.042857473s","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:55:24.450171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.037681936s","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-04-28T06:55:24.450442Z","caller":"traceutil/trace.go:171","msg":"trace[1106508340] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1228; }","duration":"1.037987393s","start":"2026-04-28T06:55:23.41244Z","end":"2026-04-28T06:55:24.450427Z","steps":["trace[1106508340] 'agreement among raft nodes before linearized reading' (duration: 1.037600802s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:24.450578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.633702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:55:24.451185Z","caller":"traceutil/trace.go:171","msg":"trace[1375090847] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1228; }","duration":"373.720795ms","start":"2026-04-28T06:55:24.076885Z","end":"2026-04-28T06:55:24.450606Z","steps":["trace[1375090847] 'agreement among raft nodes before linearized reading' (duration: 373.363646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:24.451234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:24.076865Z","time spent":"374.360412ms","remote":"127.0.0.1:41490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":55,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:55:24.451316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:23.412425Z","time spent":"1.038062636s","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T06:55:24.450297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036651798s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T06:55:24.451792Z","caller":"traceutil/trace.go:171","msg":"trace[192595418] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1228; }","duration":"1.037964782s","start":"2026-04-28T06:55:23.413628Z","end":"2026-04-28T06:55:24.451593Z","steps":["trace[192595418] 'agreement among raft nodes before linearized reading' (duration: 1.036585196s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:24.451824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:23.413623Z","time spent":"1.038191629s","remote":"127.0.0.1:41372","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-04-28T06:55:25.213913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.264158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:55:25.21405Z","caller":"traceutil/trace.go:171","msg":"trace[588392527] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"759.405557ms","start":"2026-04-28T06:55:24.45462Z","end":"2026-04-28T06:55:25.214026Z","steps":["trace[588392527] 'process raft request' (duration: 91.916473ms)","trace[588392527] 'compare' (duration: 667.026462ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:55:25.214117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:24.454604Z","time spent":"759.4839ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:55:25.280549Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765017878363803,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:55:26.112469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.296251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:55:26.112661Z","caller":"traceutil/trace.go:171","msg":"trace[1706254819] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"788.435819ms","start":"2026-04-28T06:55:25.324208Z","end":"2026-04-28T06:55:26.112644Z","steps":["trace[1706254819] 'process raft request' (duration: 788.396168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:26.112732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:25.32419Z","time spent":"788.510161ms","remote":"127.0.0.1:41172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:55:26.112934Z","caller":"traceutil/trace.go:171","msg":"trace[931468421] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"1.656905427s","start":"2026-04-28T06:55:24.456017Z","end":"2026-04-28T06:55:26.112923Z","steps":["trace[931468421] 'process raft request' (duration: 1.656530626s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:26.112987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:24.45601Z","time spent":"1.656949008s","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:26.113073Z","caller":"traceutil/trace.go:171","msg":"trace[242832339] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1282; }","duration":"1.345396135s","start":"2026-04-28T06:55:24.767668Z","end":"2026-04-28T06:55:26.113065Z","steps":["trace[242832339] 'read index received' (duration: 549.223161ms)","trace[242832339] 'applied index is now lower than readState.Index' (duration: 796.172144ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:55:26.113276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.345610541s","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-04-28T06:55:26.113312Z","caller":"traceutil/trace.go:171","msg":"trace[977482217] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1232; }","duration":"1.345673322s","start":"2026-04-28T06:55:24.767629Z","end":"2026-04-28T06:55:26.113302Z","steps":["trace[977482217] 'agreement among raft nodes before linearized reading' (duration: 1.345556369s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:26.113298Z","caller":"traceutil/trace.go:171","msg":"trace[611920847] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"1.657712739s","start":"2026-04-28T06:55:24.45552Z","end":"2026-04-28T06:55:26.113233Z","steps":["trace[611920847] 'process raft request' (duration: 861.582985ms)","trace[611920847] 'compare' (duration: 795.173719ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:55:26.113406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.354394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:26.113432Z","caller":"traceutil/trace.go:171","msg":"trace[467940809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1232; }","duration":"748.417565ms","start":"2026-04-28T06:55:25.365007Z","end":"2026-04-28T06:55:26.113425Z","steps":["trace[467940809] 'agreement among raft nodes before linearized reading' (duration: 748.381825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:26.113461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:25.364986Z","time spent":"748.469997ms","remote":"127.0.0.1:41080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:55:26.113587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:24.455512Z","time spent":"1.657906173s","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:55:26.113977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.245804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:26.114029Z","caller":"traceutil/trace.go:171","msg":"trace[80631903] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1232; }","duration":"273.323235ms","start":"2026-04-28T06:55:25.840693Z","end":"2026-04-28T06:55:26.114017Z","steps":["trace[80631903] 'agreement among raft nodes before linearized reading' (duration: 273.255584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:26.114176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.440009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:26.114219Z","caller":"traceutil/trace.go:171","msg":"trace[1798243767] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1232; }","duration":"577.504362ms","start":"2026-04-28T06:55:25.536703Z","end":"2026-04-28T06:55:26.114208Z","steps":["trace[1798243767] 'agreement among raft nodes before linearized reading' (duration: 577.44598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:26.114254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:25.536689Z","time spent":"577.556293ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-28T06:55:26.113366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:24.767615Z","time spent":"1.345744925s","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:55:26.467847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.030784ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:55:26.468006Z","caller":"traceutil/trace.go:171","msg":"trace[1175124134] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1286; }","duration":"300.472938ms","start":"2026-04-28T06:55:26.167517Z","end":"2026-04-28T06:55:26.46799Z","steps":["trace[1175124134] 'read index received' (duration: 84.246279ms)","trace[1175124134] 'applied index is now lower than readState.Index' (duration: 216.225729ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:55:26.468197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.675213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-04-28T06:55:26.468233Z","caller":"traceutil/trace.go:171","msg":"trace[1012975236] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1235; }","duration":"300.750984ms","start":"2026-04-28T06:55:26.167472Z","end":"2026-04-28T06:55:26.468223Z","steps":["trace[1012975236] 'agreement among raft nodes before linearized reading' (duration: 300.56408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:26.468269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:26.167456Z","time spent":"300.805606ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-28T06:55:26.468266Z","caller":"traceutil/trace.go:171","msg":"trace[1747324955] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"349.021338ms","start":"2026-04-28T06:55:26.11922Z","end":"2026-04-28T06:55:26.468241Z","steps":["trace[1747324955] 'process raft request' (duration: 132.478421ms)","trace[1747324955] 'compare' (duration: 215.91427ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:26.468251Z","caller":"traceutil/trace.go:171","msg":"trace[1643005914] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"347.048806ms","start":"2026-04-28T06:55:26.121156Z","end":"2026-04-28T06:55:26.468205Z","steps":["trace[1643005914] 'process raft request' (duration: 346.784459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:26.468368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:26.119176Z","time spent":"349.140721ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:55:26.468512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:26.121129Z","time spent":"347.303524ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:26.834371Z","caller":"traceutil/trace.go:171","msg":"trace[799290082] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"663.92195ms","start":"2026-04-28T06:55:26.170422Z","end":"2026-04-28T06:55:26.834344Z","steps":["trace[799290082] 'process raft request' (duration: 630.244774ms)","trace[799290082] 'compare' (duration: 33.572623ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:55:26.83449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:26.170411Z","time spent":"664.027052ms","remote":"127.0.0.1:41172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:55:26.863153Z","caller":"traceutil/trace.go:171","msg":"trace[982585898] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"390.876471ms","start":"2026-04-28T06:55:26.472251Z","end":"2026-04-28T06:55:26.863127Z","steps":["trace[982585898] 'process raft request' (duration: 390.826459ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:26.863168Z","caller":"traceutil/trace.go:171","msg":"trace[2021529669] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"592.212644ms","start":"2026-04-28T06:55:26.270919Z","end":"2026-04-28T06:55:26.863131Z","steps":["trace[2021529669] 'process raft request' (duration: 591.985848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:26.863339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:26.2709Z","time spent":"592.371268ms","remote":"127.0.0.1:41172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:55:26.863362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:26.472227Z","time spent":"391.024015ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:26.863755Z","caller":"traceutil/trace.go:171","msg":"trace[1926676544] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"406.64121ms","start":"2026-04-28T06:55:26.457101Z","end":"2026-04-28T06:55:26.863742Z","steps":["trace[1926676544] 'process raft request' (duration: 405.918591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:26.863839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:26.457078Z","time spent":"406.714922ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:55:27.171035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.863973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:27.171118Z","caller":"traceutil/trace.go:171","msg":"trace[1111180410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1240; }","duration":"149.989546ms","start":"2026-04-28T06:55:27.021112Z","end":"2026-04-28T06:55:27.171101Z","steps":["trace[1111180410] 'range keys from in-memory index tree' (duration: 149.803782ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:29.217062Z","caller":"traceutil/trace.go:171","msg":"trace[362891245] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1308; }","duration":"196.33301ms","start":"2026-04-28T06:55:29.020705Z","end":"2026-04-28T06:55:29.217038Z","steps":["trace[362891245] 'read index received' (duration: 196.061112ms)","trace[362891245] 'applied index is now lower than readState.Index' (duration: 270.938µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:29.217185Z","caller":"traceutil/trace.go:171","msg":"trace[349455817] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"349.603835ms","start":"2026-04-28T06:55:28.867571Z","end":"2026-04-28T06:55:29.217174Z","steps":["trace[349455817] 'process raft request' (duration: 349.214234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:29.217294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:28.867548Z","time spent":"349.669186ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:55:29.217348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.233486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-28T06:55:29.217403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.686759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:29.217425Z","caller":"traceutil/trace.go:171","msg":"trace[147616869] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1255; }","duration":"161.351169ms","start":"2026-04-28T06:55:29.056056Z","end":"2026-04-28T06:55:29.217408Z","steps":["trace[147616869] 'agreement among raft nodes before linearized reading' (duration: 161.216655ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:29.217441Z","caller":"traceutil/trace.go:171","msg":"trace[1476305302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1255; }","duration":"196.769661ms","start":"2026-04-28T06:55:29.020661Z","end":"2026-04-28T06:55:29.21743Z","steps":["trace[1476305302] 'agreement among raft nodes before linearized reading' (duration: 196.696919ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:34.664323Z","caller":"traceutil/trace.go:171","msg":"trace[516326158] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"161.805831ms","start":"2026-04-28T06:55:34.502471Z","end":"2026-04-28T06:55:34.664277Z","steps":["trace[516326158] 'process raft request' (duration: 161.580035ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:34.66601Z","caller":"traceutil/trace.go:171","msg":"trace[1013514569] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"163.357733ms","start":"2026-04-28T06:55:34.502631Z","end":"2026-04-28T06:55:34.665989Z","steps":["trace[1013514569] 'process raft request' (duration: 163.24994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:35.786988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.680913ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:55:35.787285Z","caller":"traceutil/trace.go:171","msg":"trace[449445735] linearizableReadLoop","detail":"{readStateIndex:1360; appliedIndex:1359; }","duration":"187.234977ms","start":"2026-04-28T06:55:35.600033Z","end":"2026-04-28T06:55:35.787268Z","steps":["trace[449445735] 'read index received' (duration: 63.381µs)","trace[449445735] 'applied index is now lower than readState.Index' (duration: 187.170476ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:55:35.787783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.72804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:35.787826Z","caller":"traceutil/trace.go:171","msg":"trace[546616989] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"408.846518ms","start":"2026-04-28T06:55:35.378763Z","end":"2026-04-28T06:55:35.787609Z","steps":["trace[546616989] 'process raft request' (duration: 57.464407ms)","trace[546616989] 'compare' (duration: 350.262071ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:35.787868Z","caller":"traceutil/trace.go:171","msg":"trace[718422536] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1305; }","duration":"187.873584ms","start":"2026-04-28T06:55:35.599974Z","end":"2026-04-28T06:55:35.787848Z","steps":["trace[718422536] 'agreement among raft nodes before linearized reading' (duration: 187.70086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:35.787912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:35.378736Z","time spent":"409.129936ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:55:37.487482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.06079875s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:37.487621Z","caller":"traceutil/trace.go:171","msg":"trace[106289007] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1305; }","duration":"1.060958174s","start":"2026-04-28T06:55:36.426643Z","end":"2026-04-28T06:55:37.487601Z","steps":["trace[106289007] 'range keys from in-memory index tree' (duration: 1.060779979s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:37.487951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171465301s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:55:37.488064Z","caller":"traceutil/trace.go:171","msg":"trace[852674101] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1360; }","duration":"1.306162963s","start":"2026-04-28T06:55:36.181884Z","end":"2026-04-28T06:55:37.488046Z","steps":["trace[852674101] 'read index received' (duration: 134.469485ms)","trace[852674101] 'applied index is now lower than readState.Index' (duration: 1.171691798s)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:37.488142Z","caller":"traceutil/trace.go:171","msg":"trace[1300322406] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"1.694845955s","start":"2026-04-28T06:55:35.793282Z","end":"2026-04-28T06:55:37.488128Z","steps":["trace[1300322406] 'process raft request' (duration: 523.117576ms)","trace[1300322406] 'compare' (duration: 1.171178274s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:55:37.488218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:35.793256Z","time spent":"1.694920207s","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:55:37.488333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.306445249s","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-04-28T06:55:37.488427Z","caller":"traceutil/trace.go:171","msg":"trace[2108799323] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1306; }","duration":"1.306575773s","start":"2026-04-28T06:55:36.181834Z","end":"2026-04-28T06:55:37.48841Z","steps":["trace[2108799323] 'agreement among raft nodes before linearized reading' (duration: 1.306298496s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:37.488499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:36.181818Z","time spent":"1.306669775s","remote":"127.0.0.1:41372","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-04-28T06:55:37.988778Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356765017878363952,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T06:55:38.071778Z","caller":"traceutil/trace.go:171","msg":"trace[684207212] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"692.287293ms","start":"2026-04-28T06:55:37.379459Z","end":"2026-04-28T06:55:38.071746Z","steps":["trace[684207212] 'process raft request' (duration: 691.983135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:38.072513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:37.379433Z","time spent":"692.983011ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:55:38.083063Z","caller":"traceutil/trace.go:171","msg":"trace[1198556363] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"590.482647ms","start":"2026-04-28T06:55:37.492564Z","end":"2026-04-28T06:55:38.083047Z","steps":["trace[1198556363] 'process raft request' (duration: 590.342863ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:38.08317Z","caller":"traceutil/trace.go:171","msg":"trace[933314518] linearizableReadLoop","detail":"{readStateIndex:1363; appliedIndex:1361; }","duration":"595.059599ms","start":"2026-04-28T06:55:37.48809Z","end":"2026-04-28T06:55:38.08315Z","steps":["trace[933314518] 'read index received' (duration: 583.306006ms)","trace[933314518] 'applied index is now lower than readState.Index' (duration: 11.752803ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:55:38.083422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.415410627s","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-04-28T06:55:38.083447Z","caller":"traceutil/trace.go:171","msg":"trace[618735585] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1309; }","duration":"1.415478869s","start":"2026-04-28T06:55:36.667961Z","end":"2026-04-28T06:55:38.08344Z","steps":["trace[618735585] 'agreement among raft nodes before linearized reading' (duration: 1.415377646s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:38.083469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:36.667944Z","time spent":"1.41551842s","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:55:38.083471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.414481061s","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-04-28T06:55:38.08355Z","caller":"traceutil/trace.go:171","msg":"trace[829970896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1309; }","duration":"1.414584104s","start":"2026-04-28T06:55:36.668953Z","end":"2026-04-28T06:55:38.083537Z","steps":["trace[829970896] 'agreement among raft nodes before linearized reading' (duration: 1.414362029s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:38.083571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.55322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T06:55:38.083591Z","caller":"traceutil/trace.go:171","msg":"trace[625813380] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:1309; }","duration":"405.610912ms","start":"2026-04-28T06:55:37.677976Z","end":"2026-04-28T06:55:38.083587Z","steps":["trace[625813380] 'agreement among raft nodes before linearized reading' (duration: 405.55875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:38.083605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:37.677935Z","time spent":"405.667483ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"warn","ts":"2026-04-28T06:55:38.083642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.317278897s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:38.083687Z","caller":"traceutil/trace.go:171","msg":"trace[1438307940] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1309; }","duration":"1.317364949s","start":"2026-04-28T06:55:36.766316Z","end":"2026-04-28T06:55:38.083681Z","steps":["trace[1438307940] 'agreement among raft nodes before linearized reading' (duration: 1.317301957s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:38.083709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:36.766294Z","time spent":"1.317409132s","remote":"127.0.0.1:41326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:55:38.08374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.835473603s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-04-28T06:55:38.08374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.205046195s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"warn","ts":"2026-04-28T06:55:38.083565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061767845s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:55:38.083883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.88778ms","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-04-28T06:55:38.083905Z","caller":"traceutil/trace.go:171","msg":"trace[76859275] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1309; }","duration":"1.835548415s","start":"2026-04-28T06:55:36.248219Z","end":"2026-04-28T06:55:38.083768Z","steps":["trace[76859275] 'agreement among raft nodes before linearized reading' (duration: 1.835265427s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:38.083915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.16589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:38.083935Z","caller":"traceutil/trace.go:171","msg":"trace[298803779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1309; }","duration":"291.951531ms","start":"2026-04-28T06:55:37.791965Z","end":"2026-04-28T06:55:38.083917Z","steps":["trace[298803779] 'agreement among raft nodes before linearized reading' (duration: 291.808967ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:38.083927Z","caller":"traceutil/trace.go:171","msg":"trace[2085273223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1309; }","duration":"1.062229288s","start":"2026-04-28T06:55:37.021677Z","end":"2026-04-28T06:55:38.083906Z","steps":["trace[2085273223] 'agreement among raft nodes before linearized reading' (duration: 1.061757645s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:38.083947Z","caller":"traceutil/trace.go:171","msg":"trace[327836916] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1309; }","duration":"300.245752ms","start":"2026-04-28T06:55:37.783693Z","end":"2026-04-28T06:55:38.083939Z","steps":["trace[327836916] 'agreement among raft nodes before linearized reading' (duration: 300.18552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:38.083986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:37.783665Z","time spent":"300.314454ms","remote":"127.0.0.1:41258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:55:38.083988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:37.492544Z","time spent":"590.722344ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:55:38.083999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:37.021659Z","time spent":"1.06232224s","remote":"127.0.0.1:41080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:55:38.084069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.605225493s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-28T06:55:38.083815Z","caller":"traceutil/trace.go:171","msg":"trace[1749997200] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1309; }","duration":"1.205152778s","start":"2026-04-28T06:55:36.87864Z","end":"2026-04-28T06:55:38.083792Z","steps":["trace[1749997200] 'agreement among raft nodes before linearized reading' (duration: 1.204960792s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:38.0841Z","caller":"traceutil/trace.go:171","msg":"trace[508460842] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1309; }","duration":"1.605314445s","start":"2026-04-28T06:55:36.478775Z","end":"2026-04-28T06:55:38.08409Z","steps":["trace[508460842] 'agreement among raft nodes before linearized reading' (duration: 1.605199952s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:38.084137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:36.87862Z","time spent":"1.205509197s","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-28T06:55:38.084148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:36.47875Z","time spent":"1.605366946s","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-28T06:55:38.083602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:36.668946Z","time spent":"1.414647186s","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T06:55:38.083939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:55:36.248191Z","time spent":"1.83573955s","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T06:55:38.401525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.409785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:55:38.4017Z","caller":"traceutil/trace.go:171","msg":"trace[495368151] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"284.749629ms","start":"2026-04-28T06:55:38.116937Z","end":"2026-04-28T06:55:38.401686Z","steps":["trace[495368151] 'process raft request' (duration: 171.095358ms)","trace[495368151] 'compare' (duration: 113.303431ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:38.401642Z","caller":"traceutil/trace.go:171","msg":"trace[466685276] linearizableReadLoop","detail":"{readStateIndex:1372; appliedIndex:1365; }","duration":"277.704023ms","start":"2026-04-28T06:55:38.123921Z","end":"2026-04-28T06:55:38.401625Z","steps":["trace[466685276] 'read index received' (duration: 60.156139ms)","trace[466685276] 'applied index is now lower than readState.Index' (duration: 217.546774ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:55:38.401956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.00751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:38.402134Z","caller":"traceutil/trace.go:171","msg":"trace[786851297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1316; }","duration":"278.233416ms","start":"2026-04-28T06:55:38.12388Z","end":"2026-04-28T06:55:38.402114Z","steps":["trace[786851297] 'agreement among raft nodes before linearized reading' (duration: 277.916838ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:39.629084Z","caller":"traceutil/trace.go:171","msg":"trace[803636383] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"127.313905ms","start":"2026-04-28T06:55:39.501748Z","end":"2026-04-28T06:55:39.629062Z","steps":["trace[803636383] 'process raft request' (duration: 127.176061ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:40.145321Z","caller":"traceutil/trace.go:171","msg":"trace[1570676890] linearizableReadLoop","detail":"{readStateIndex:1375; appliedIndex:1374; }","duration":"123.527054ms","start":"2026-04-28T06:55:40.02177Z","end":"2026-04-28T06:55:40.145297Z","steps":["trace[1570676890] 'read index received' (duration: 123.458722ms)","trace[1570676890] 'applied index is now lower than readState.Index' (duration: 66.952µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:40.145394Z","caller":"traceutil/trace.go:171","msg":"trace[107351298] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"233.437256ms","start":"2026-04-28T06:55:39.911929Z","end":"2026-04-28T06:55:40.145366Z","steps":["trace[107351298] 'process raft request' (duration: 233.23421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:40.145489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.706068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:55:40.145537Z","caller":"traceutil/trace.go:171","msg":"trace[270563345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1319; }","duration":"123.804942ms","start":"2026-04-28T06:55:40.021721Z","end":"2026-04-28T06:55:40.145526Z","steps":["trace[270563345] 'agreement among raft nodes before linearized reading' (duration: 123.683607ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:40.18818Z","caller":"traceutil/trace.go:171","msg":"trace[1428152076] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"110.121488ms","start":"2026-04-28T06:55:40.078031Z","end":"2026-04-28T06:55:40.188153Z","steps":["trace[1428152076] 'process raft request' (duration: 109.896622ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:42.403603Z","caller":"traceutil/trace.go:171","msg":"trace[224575712] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"160.986319ms","start":"2026-04-28T06:55:42.242604Z","end":"2026-04-28T06:55:42.40359Z","steps":["trace[224575712] 'process raft request' (duration: 160.862536ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:42.40353Z","caller":"traceutil/trace.go:171","msg":"trace[1935448017] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"160.898587ms","start":"2026-04-28T06:55:42.2426Z","end":"2026-04-28T06:55:42.403499Z","steps":["trace[1935448017] 'process raft request' (duration: 107.327802ms)","trace[1935448017] 'compare' (duration: 53.423631ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:42.421768Z","caller":"traceutil/trace.go:171","msg":"trace[391191759] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"175.812634ms","start":"2026-04-28T06:55:42.245934Z","end":"2026-04-28T06:55:42.421746Z","steps":["trace[391191759] 'process raft request' (duration: 175.781303ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:42.4218Z","caller":"traceutil/trace.go:171","msg":"trace[1989235102] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"176.206895ms","start":"2026-04-28T06:55:42.245576Z","end":"2026-04-28T06:55:42.421783Z","steps":["trace[1989235102] 'process raft request' (duration: 176.116263ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:42.42178Z","caller":"traceutil/trace.go:171","msg":"trace[669518864] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"176.43021ms","start":"2026-04-28T06:55:42.245329Z","end":"2026-04-28T06:55:42.42176Z","steps":["trace[669518864] 'process raft request' (duration: 176.257686ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:56.86144Z","caller":"traceutil/trace.go:171","msg":"trace[686956709] linearizableReadLoop","detail":"{readStateIndex:1454; appliedIndex:1453; }","duration":"212.336132ms","start":"2026-04-28T06:55:56.649086Z","end":"2026-04-28T06:55:56.861422Z","steps":["trace[686956709] 'read index received' (duration: 212.196439ms)","trace[686956709] 'applied index is now lower than readState.Index' (duration: 139.003µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:56.861635Z","caller":"traceutil/trace.go:171","msg":"trace[729316829] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"231.487186ms","start":"2026-04-28T06:55:56.630133Z","end":"2026-04-28T06:55:56.86162Z","steps":["trace[729316829] 'process raft request' (duration: 231.196359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:56.861866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.754444ms","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-04-28T06:55:56.861933Z","caller":"traceutil/trace.go:171","msg":"trace[389817698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1395; }","duration":"212.853646ms","start":"2026-04-28T06:55:56.649063Z","end":"2026-04-28T06:55:56.861917Z","steps":["trace[389817698] 'agreement among raft nodes before linearized reading' (duration: 212.660391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:56.861947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.637455ms","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-04-28T06:55:56.862029Z","caller":"traceutil/trace.go:171","msg":"trace[623637046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1395; }","duration":"208.736538ms","start":"2026-04-28T06:55:56.653268Z","end":"2026-04-28T06:55:56.862004Z","steps":["trace[623637046] 'agreement among raft nodes before linearized reading' (duration: 208.505832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:56.861959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.947174ms","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-04-28T06:55:56.862149Z","caller":"traceutil/trace.go:171","msg":"trace[63861644] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1395; }","duration":"209.18636ms","start":"2026-04-28T06:55:56.652943Z","end":"2026-04-28T06:55:56.862129Z","steps":["trace[63861644] 'agreement among raft nodes before linearized reading' (duration: 208.79415ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:57.089802Z","caller":"traceutil/trace.go:171","msg":"trace[1774785168] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"222.800617ms","start":"2026-04-28T06:55:56.866966Z","end":"2026-04-28T06:55:57.089767Z","steps":["trace[1774785168] 'process raft request' (duration: 131.324178ms)","trace[1774785168] 'compare' (duration: 91.272944ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:57.090271Z","caller":"traceutil/trace.go:171","msg":"trace[1478857232] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"223.024213ms","start":"2026-04-28T06:55:56.867233Z","end":"2026-04-28T06:55:57.090258Z","steps":["trace[1478857232] 'process raft request' (duration: 222.462348ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:57.090822Z","caller":"traceutil/trace.go:171","msg":"trace[1845086282] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"223.156748ms","start":"2026-04-28T06:55:56.867604Z","end":"2026-04-28T06:55:57.090761Z","steps":["trace[1845086282] 'process raft request' (duration: 222.600763ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:17.87568Z","caller":"traceutil/trace.go:171","msg":"trace[1262709709] transaction","detail":"{read_only:false; response_revision:1491; number_of_response:1; }","duration":"133.224749ms","start":"2026-04-28T06:56:17.742424Z","end":"2026-04-28T06:56:17.875649Z","steps":["trace[1262709709] 'process raft request' (duration: 43.667541ms)","trace[1262709709] 'compare' (duration: 89.208339ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:18.690451Z","caller":"traceutil/trace.go:171","msg":"trace[366029612] transaction","detail":"{read_only:false; response_revision:1495; number_of_response:1; }","duration":"202.76048ms","start":"2026-04-28T06:56:18.487657Z","end":"2026-04-28T06:56:18.690417Z","steps":["trace[366029612] 'process raft request' (duration: 199.330229ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:23.471821Z","caller":"traceutil/trace.go:171","msg":"trace[887640778] transaction","detail":"{read_only:false; response_revision:1517; number_of_response:1; }","duration":"103.007583ms","start":"2026-04-28T06:56:23.368773Z","end":"2026-04-28T06:56:23.471781Z","steps":["trace[887640778] 'process raft request' (duration: 102.90227ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:23.472046Z","caller":"traceutil/trace.go:171","msg":"trace[898865834] linearizableReadLoop","detail":"{readStateIndex:1583; appliedIndex:1580; }","duration":"101.313678ms","start":"2026-04-28T06:56:23.370678Z","end":"2026-04-28T06:56:23.471992Z","steps":["trace[898865834] 'read index received' (duration: 66.985775ms)","trace[898865834] 'applied index is now lower than readState.Index' (duration: 34.325643ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:23.472333Z","caller":"traceutil/trace.go:171","msg":"trace[471151325] transaction","detail":"{read_only:false; response_revision:1518; number_of_response:1; }","duration":"102.714975ms","start":"2026-04-28T06:56:23.3696Z","end":"2026-04-28T06:56:23.472315Z","steps":["trace[471151325] 'process raft request' (duration: 102.15299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:23.472555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.865573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-04-28T06:56:23.472595Z","caller":"traceutil/trace.go:171","msg":"trace[1244715301] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1518; }","duration":"101.930315ms","start":"2026-04-28T06:56:23.370654Z","end":"2026-04-28T06:56:23.472584Z","steps":["trace[1244715301] 'agreement among raft nodes before linearized reading' (duration: 101.851063ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:23.608412Z","caller":"traceutil/trace.go:171","msg":"trace[1447884811] transaction","detail":"{read_only:false; response_revision:1519; number_of_response:1; }","duration":"131.481503ms","start":"2026-04-28T06:56:23.476902Z","end":"2026-04-28T06:56:23.608383Z","steps":["trace[1447884811] 'process raft request' (duration: 33.617385ms)","trace[1447884811] 'compare' (duration: 97.675983ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:23.611153Z","caller":"traceutil/trace.go:171","msg":"trace[1924561207] transaction","detail":"{read_only:false; response_revision:1520; number_of_response:1; }","duration":"134.146923ms","start":"2026-04-28T06:56:23.476988Z","end":"2026-04-28T06:56:23.611135Z","steps":["trace[1924561207] 'process raft request' (duration: 134.072642ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:27.960567Z","caller":"traceutil/trace.go:171","msg":"trace[495372380] transaction","detail":"{read_only:false; response_revision:1537; number_of_response:1; }","duration":"143.275183ms","start":"2026-04-28T06:56:27.81724Z","end":"2026-04-28T06:56:27.960516Z","steps":["trace[495372380] 'process raft request' (duration: 84.077144ms)","trace[495372380] 'compare' (duration: 58.893791ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:56:28.196599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.778505ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:56:28.196703Z","caller":"traceutil/trace.go:171","msg":"trace[1102002019] transaction","detail":"{read_only:false; response_revision:1538; number_of_response:1; }","duration":"149.143028ms","start":"2026-04-28T06:56:28.047546Z","end":"2026-04-28T06:56:28.196689Z","steps":["trace[1102002019] 'process raft request' (duration: 21.175788ms)","trace[1102002019] 'compare' (duration: 127.670152ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:29.574062Z","caller":"traceutil/trace.go:171","msg":"trace[1840671625] transaction","detail":"{read_only:false; response_revision:1546; number_of_response:1; }","duration":"111.350423ms","start":"2026-04-28T06:56:29.462688Z","end":"2026-04-28T06:56:29.574039Z","steps":["trace[1840671625] 'process raft request' (duration: 69.804479ms)","trace[1840671625] 'compare' (duration: 41.378559ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:35.227855Z","caller":"traceutil/trace.go:171","msg":"trace[42986124] transaction","detail":"{read_only:false; response_revision:1570; number_of_response:1; }","duration":"204.392574ms","start":"2026-04-28T06:56:35.023431Z","end":"2026-04-28T06:56:35.227823Z","steps":["trace[42986124] 'process raft request' (duration: 204.141287ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:35.227781Z","caller":"traceutil/trace.go:171","msg":"trace[1798427517] linearizableReadLoop","detail":"{readStateIndex:1637; appliedIndex:1636; }","duration":"204.279821ms","start":"2026-04-28T06:56:35.023475Z","end":"2026-04-28T06:56:35.227754Z","steps":["trace[1798427517] 'read index received' (duration: 203.963422ms)","trace[1798427517] 'applied index is now lower than readState.Index' (duration: 315.069µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:56:35.228071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.577188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:56:35.228119Z","caller":"traceutil/trace.go:171","msg":"trace[2016677233] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1570; }","duration":"204.6626ms","start":"2026-04-28T06:56:35.023443Z","end":"2026-04-28T06:56:35.228105Z","steps":["trace[2016677233] 'agreement among raft nodes before linearized reading' (duration: 204.500636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:35.228299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.204149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:56:35.228365Z","caller":"traceutil/trace.go:171","msg":"trace[1335184578] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1570; }","duration":"193.313072ms","start":"2026-04-28T06:56:35.035037Z","end":"2026-04-28T06:56:35.22835Z","steps":["trace[1335184578] 'agreement among raft nodes before linearized reading' (duration: 193.206789ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:37.837881Z","caller":"traceutil/trace.go:171","msg":"trace[1117669466] linearizableReadLoop","detail":"{readStateIndex:1650; appliedIndex:1648; }","duration":"106.71239ms","start":"2026-04-28T06:56:37.731138Z","end":"2026-04-28T06:56:37.83785Z","steps":["trace[1117669466] 'read index received' (duration: 25.022758ms)","trace[1117669466] 'applied index is now lower than readState.Index' (duration: 81.688491ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:37.837999Z","caller":"traceutil/trace.go:171","msg":"trace[1581929782] transaction","detail":"{read_only:false; response_revision:1582; number_of_response:1; }","duration":"106.823823ms","start":"2026-04-28T06:56:37.731112Z","end":"2026-04-28T06:56:37.837936Z","steps":["trace[1581929782] 'process raft request' (duration: 106.588827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:37.83815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.985617ms","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-04-28T06:56:37.838222Z","caller":"traceutil/trace.go:171","msg":"trace[1917906722] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1582; }","duration":"107.07578ms","start":"2026-04-28T06:56:37.731106Z","end":"2026-04-28T06:56:37.838182Z","steps":["trace[1917906722] 'agreement among raft nodes before linearized reading' (duration: 106.890264ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:37.950219Z","caller":"traceutil/trace.go:171","msg":"trace[909224185] transaction","detail":"{read_only:false; response_revision:1583; number_of_response:1; }","duration":"107.04416ms","start":"2026-04-28T06:56:37.843145Z","end":"2026-04-28T06:56:37.950189Z","steps":["trace[909224185] 'process raft request' (duration: 65.136436ms)","trace[909224185] 'compare' (duration: 41.653776ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:38.837389Z","caller":"traceutil/trace.go:171","msg":"trace[529320604] transaction","detail":"{read_only:false; response_revision:1586; number_of_response:1; }","duration":"195.001266ms","start":"2026-04-28T06:56:38.642363Z","end":"2026-04-28T06:56:38.837364Z","steps":["trace[529320604] 'process raft request' (duration: 110.729166ms)","trace[529320604] 'compare' (duration: 84.105025ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:39.177377Z","caller":"traceutil/trace.go:171","msg":"trace[835911999] linearizableReadLoop","detail":"{readStateIndex:1656; appliedIndex:1655; }","duration":"157.591221ms","start":"2026-04-28T06:56:39.019761Z","end":"2026-04-28T06:56:39.177353Z","steps":["trace[835911999] 'read index received' (duration: 157.375136ms)","trace[835911999] 'applied index is now lower than readState.Index' (duration: 215.235µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:39.17751Z","caller":"traceutil/trace.go:171","msg":"trace[2005723697] transaction","detail":"{read_only:false; response_revision:1588; number_of_response:1; }","duration":"251.191626ms","start":"2026-04-28T06:56:38.926279Z","end":"2026-04-28T06:56:39.177471Z","steps":["trace[2005723697] 'process raft request' (duration: 250.934579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:39.177606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.831668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:56:39.177659Z","caller":"traceutil/trace.go:171","msg":"trace[1531297159] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1588; }","duration":"157.93207ms","start":"2026-04-28T06:56:39.019712Z","end":"2026-04-28T06:56:39.177644Z","steps":["trace[1531297159] 'agreement among raft nodes before linearized reading' (duration: 157.739396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:39.953006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.641157ms","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-04-28T06:56:39.953091Z","caller":"traceutil/trace.go:171","msg":"trace[285678607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1592; }","duration":"109.76112ms","start":"2026-04-28T06:56:39.843311Z","end":"2026-04-28T06:56:39.953072Z","steps":["trace[285678607] 'range keys from in-memory index tree' (duration: 109.493693ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:41.960824Z","caller":"traceutil/trace.go:171","msg":"trace[1736394727] transaction","detail":"{read_only:false; response_revision:1600; number_of_response:1; }","duration":"146.893009ms","start":"2026-04-28T06:56:41.813906Z","end":"2026-04-28T06:56:41.960799Z","steps":["trace[1736394727] 'process raft request' (duration: 146.756055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:42.742527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.445993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:56:42.742604Z","caller":"traceutil/trace.go:171","msg":"trace[1986690293] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1603; }","duration":"139.595267ms","start":"2026-04-28T06:56:42.602992Z","end":"2026-04-28T06:56:42.742587Z","steps":["trace[1986690293] 'count revisions from in-memory index tree' (duration: 139.307269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:43.122983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.152081ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-28T06:56:43.123099Z","caller":"traceutil/trace.go:171","msg":"trace[1958609035] linearizableReadLoop","detail":"{readStateIndex:1672; appliedIndex:1671; }","duration":"101.175264ms","start":"2026-04-28T06:56:43.021906Z","end":"2026-04-28T06:56:43.123081Z","steps":["trace[1958609035] 'read index received' (duration: 32.98µs)","trace[1958609035] 'applied index is now lower than readState.Index' (duration: 101.140974ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:56:43.123195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.278657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:56:43.123296Z","caller":"traceutil/trace.go:171","msg":"trace[194025299] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1603; }","duration":"101.422282ms","start":"2026-04-28T06:56:43.021857Z","end":"2026-04-28T06:56:43.12328Z","steps":["trace[194025299] 'agreement among raft nodes before linearized reading' (duration: 101.276057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:47.84508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.282731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T06:56:47.845178Z","caller":"traceutil/trace.go:171","msg":"trace[718566243] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:1625; }","duration":"163.423043ms","start":"2026-04-28T06:56:47.681732Z","end":"2026-04-28T06:56:47.845155Z","steps":["trace[718566243] 'range keys from in-memory index tree' (duration: 163.109186ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:50.36502Z","caller":"traceutil/trace.go:171","msg":"trace[1749024497] linearizableReadLoop","detail":"{readStateIndex:1709; appliedIndex:1708; }","duration":"128.283385ms","start":"2026-04-28T06:56:50.236721Z","end":"2026-04-28T06:56:50.365004Z","steps":["trace[1749024497] 'read index received' (duration: 128.12896ms)","trace[1749024497] 'applied index is now lower than readState.Index' (duration: 153.835µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:56:50.365187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.447579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-28T06:56:50.365162Z","caller":"traceutil/trace.go:171","msg":"trace[1912089737] transaction","detail":"{read_only:false; response_revision:1639; number_of_response:1; }","duration":"160.412039ms","start":"2026-04-28T06:56:50.204717Z","end":"2026-04-28T06:56:50.365129Z","steps":["trace[1912089737] 'process raft request' (duration: 160.179323ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:50.365222Z","caller":"traceutil/trace.go:171","msg":"trace[1026596228] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1639; }","duration":"128.529011ms","start":"2026-04-28T06:56:50.236685Z","end":"2026-04-28T06:56:50.365214Z","steps":["trace[1026596228] 'agreement among raft nodes before linearized reading' (duration: 128.408858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:53.093758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.095604ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-04-28T06:56:54.964985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.647666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-28T06:56:54.965081Z","caller":"traceutil/trace.go:171","msg":"trace[1177456106] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1659; }","duration":"230.785839ms","start":"2026-04-28T06:56:54.734275Z","end":"2026-04-28T06:56:54.965061Z","steps":["trace[1177456106] 'range keys from in-memory index tree' (duration: 230.466861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:58.856088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.48048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-04-28T06:56:58.856154Z","caller":"traceutil/trace.go:171","msg":"trace[1714643782] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1675; }","duration":"106.600903ms","start":"2026-04-28T06:56:58.749539Z","end":"2026-04-28T06:56:58.85614Z","steps":["trace[1714643782] 'range keys from in-memory index tree' (duration: 106.322837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:59.835928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.385629ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:56:59.836064Z","caller":"traceutil/trace.go:171","msg":"trace[128443528] linearizableReadLoop","detail":"{readStateIndex:1754; appliedIndex:1753; }","duration":"142.392745ms","start":"2026-04-28T06:56:59.693622Z","end":"2026-04-28T06:56:59.836015Z","steps":["trace[128443528] 'read index received' (duration: 49.291µs)","trace[128443528] 'applied index is now lower than readState.Index' (duration: 142.342404ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:59.836187Z","caller":"traceutil/trace.go:171","msg":"trace[1436855451] transaction","detail":"{read_only:false; response_revision:1682; number_of_response:1; }","duration":"218.913007ms","start":"2026-04-28T06:56:59.61726Z","end":"2026-04-28T06:56:59.836173Z","steps":["trace[1436855451] 'process raft request' (duration: 37.187848ms)","trace[1436855451] 'compare' (duration: 181.277757ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:56:59.836314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.689323ms","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-04-28T06:56:59.836355Z","caller":"traceutil/trace.go:171","msg":"trace[2126056085] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1682; }","duration":"142.769854ms","start":"2026-04-28T06:56:59.693575Z","end":"2026-04-28T06:56:59.836345Z","steps":["trace[2126056085] 'agreement among raft nodes before linearized reading' (duration: 142.516528ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:00.517598Z","caller":"traceutil/trace.go:171","msg":"trace[212293278] transaction","detail":"{read_only:false; response_revision:1684; number_of_response:1; }","duration":"125.195842ms","start":"2026-04-28T06:57:00.39237Z","end":"2026-04-28T06:57:00.517566Z","steps":["trace[212293278] 'process raft request' (duration: 124.999528ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:00.645179Z","caller":"traceutil/trace.go:171","msg":"trace[1931262439] transaction","detail":"{read_only:false; response_revision:1685; number_of_response:1; }","duration":"100.281148ms","start":"2026-04-28T06:57:00.544865Z","end":"2026-04-28T06:57:00.645146Z","steps":["trace[1931262439] 'process raft request' (duration: 66.457838ms)","trace[1931262439] 'compare' (duration: 33.584544ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:57:01.637603Z","caller":"traceutil/trace.go:171","msg":"trace[1051218833] transaction","detail":"{read_only:false; response_revision:1689; number_of_response:1; }","duration":"197.061993ms","start":"2026-04-28T06:57:01.440516Z","end":"2026-04-28T06:57:01.637578Z","steps":["trace[1051218833] 'process raft request' (duration: 196.891479ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:11.615639Z","caller":"traceutil/trace.go:171","msg":"trace[1656482699] transaction","detail":"{read_only:false; response_revision:1734; number_of_response:1; }","duration":"211.389719ms","start":"2026-04-28T06:57:11.404226Z","end":"2026-04-28T06:57:11.615616Z","steps":["trace[1656482699] 'process raft request' (duration: 211.264646ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:13.839897Z","caller":"traceutil/trace.go:171","msg":"trace[803162556] transaction","detail":"{read_only:false; response_revision:1742; number_of_response:1; }","duration":"129.393283ms","start":"2026-04-28T06:57:13.710475Z","end":"2026-04-28T06:57:13.839868Z","steps":["trace[803162556] 'process raft request' (duration: 123.61401ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:14.054187Z","caller":"traceutil/trace.go:171","msg":"trace[117070613] linearizableReadLoop","detail":"{readStateIndex:1818; appliedIndex:1817; }","duration":"110.549808ms","start":"2026-04-28T06:57:13.943606Z","end":"2026-04-28T06:57:14.054156Z","steps":["trace[117070613] 'read index received' (duration: 31.953ms)","trace[117070613] 'applied index is now lower than readState.Index' (duration: 78.595308ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:57:14.054248Z","caller":"traceutil/trace.go:171","msg":"trace[58669253] transaction","detail":"{read_only:false; response_revision:1743; number_of_response:1; }","duration":"210.020844ms","start":"2026-04-28T06:57:13.844183Z","end":"2026-04-28T06:57:14.054204Z","steps":["trace[58669253] 'process raft request' (duration: 131.486198ms)","trace[58669253] 'compare' (duration: 78.192286ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:57:14.054407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.785503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-04-28T06:57:14.054472Z","caller":"traceutil/trace.go:171","msg":"trace[888178048] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1743; }","duration":"110.931457ms","start":"2026-04-28T06:57:13.943524Z","end":"2026-04-28T06:57:14.054456Z","steps":["trace[888178048] 'agreement among raft nodes before linearized reading' (duration: 110.767473ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:14.247709Z","caller":"traceutil/trace.go:171","msg":"trace[1497694216] linearizableReadLoop","detail":"{readStateIndex:1821; appliedIndex:1819; }","duration":"163.018797ms","start":"2026-04-28T06:57:14.084667Z","end":"2026-04-28T06:57:14.247686Z","steps":["trace[1497694216] 'read index received' (duration: 145.719942ms)","trace[1497694216] 'applied index is now lower than readState.Index' (duration: 17.297795ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:57:14.24773Z","caller":"traceutil/trace.go:171","msg":"trace[130158616] transaction","detail":"{read_only:false; response_revision:1745; number_of_response:1; }","duration":"188.126788ms","start":"2026-04-28T06:57:14.059571Z","end":"2026-04-28T06:57:14.247698Z","steps":["trace[130158616] 'process raft request' (duration: 170.845923ms)","trace[130158616] 'compare' (duration: 16.951677ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:57:14.247823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.144381ms","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-04-28T06:57:14.247842Z","caller":"traceutil/trace.go:171","msg":"trace[131983200] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1746; }","duration":"163.209332ms","start":"2026-04-28T06:57:14.084628Z","end":"2026-04-28T06:57:14.247837Z","steps":["trace[131983200] 'agreement among raft nodes before linearized reading' (duration: 163.12291ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:14.24789Z","caller":"traceutil/trace.go:171","msg":"trace[2119544897] transaction","detail":"{read_only:false; response_revision:1746; number_of_response:1; }","duration":"187.708487ms","start":"2026-04-28T06:57:14.060172Z","end":"2026-04-28T06:57:14.24788Z","steps":["trace[2119544897] 'process raft request' (duration: 187.478051ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:19.356733Z","caller":"traceutil/trace.go:171","msg":"trace[1988636773] transaction","detail":"{read_only:false; response_revision:1769; number_of_response:1; }","duration":"100.136553ms","start":"2026-04-28T06:57:19.256271Z","end":"2026-04-28T06:57:19.356408Z","steps":["trace[1988636773] 'process raft request' (duration: 99.972909ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:21.141148Z","caller":"traceutil/trace.go:171","msg":"trace[1266232267] transaction","detail":"{read_only:false; response_revision:1778; number_of_response:1; }","duration":"203.988774ms","start":"2026-04-28T06:57:20.937136Z","end":"2026-04-28T06:57:21.141124Z","steps":["trace[1266232267] 'process raft request' (duration: 154.978486ms)","trace[1266232267] 'compare' (duration: 48.807672ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:57:21.186036Z","caller":"traceutil/trace.go:171","msg":"trace[432576510] transaction","detail":"{read_only:false; response_revision:1779; number_of_response:1; }","duration":"236.947943ms","start":"2026-04-28T06:57:20.949062Z","end":"2026-04-28T06:57:21.18601Z","steps":["trace[432576510] 'process raft request' (duration: 236.795768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:33.165364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.541048ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-28T06:57:33.165444Z","caller":"traceutil/trace.go:171","msg":"trace[902362531] linearizableReadLoop","detail":"{readStateIndex:1910; appliedIndex:1909; }","duration":"144.956152ms","start":"2026-04-28T06:57:33.020473Z","end":"2026-04-28T06:57:33.16543Z","steps":["trace[902362531] 'read index received' (duration: 18.26674ms)","trace[902362531] 'applied index is now lower than readState.Index' (duration: 126.688452ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:57:33.165505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.029984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:57:33.165532Z","caller":"traceutil/trace.go:171","msg":"trace[1671430845] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1831; }","duration":"145.090345ms","start":"2026-04-28T06:57:33.020432Z","end":"2026-04-28T06:57:33.165522Z","steps":["trace[1671430845] 'agreement among raft nodes before linearized reading' (duration: 145.033144ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:42.857367Z","caller":"traceutil/trace.go:171","msg":"trace[525183128] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"191.548557ms","start":"2026-04-28T06:57:42.665781Z","end":"2026-04-28T06:57:42.85733Z","steps":["trace[525183128] 'process raft request' (duration: 191.316751ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:45.238956Z","caller":"traceutil/trace.go:171","msg":"trace[624417221] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"165.869233ms","start":"2026-04-28T06:57:45.073068Z","end":"2026-04-28T06:57:45.238937Z","steps":["trace[624417221] 'process raft request' (duration: 165.75837ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:45.252719Z","caller":"traceutil/trace.go:171","msg":"trace[1688243426] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"142.479068ms","start":"2026-04-28T06:57:45.11022Z","end":"2026-04-28T06:57:45.252699Z","steps":["trace[1688243426] 'process raft request' (duration: 142.345174ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:50.980338Z","caller":"traceutil/trace.go:171","msg":"trace[236465883] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"108.745209ms","start":"2026-04-28T06:57:50.871561Z","end":"2026-04-28T06:57:50.980306Z","steps":["trace[236465883] 'process raft request' (duration: 108.233366ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:56.253829Z","caller":"traceutil/trace.go:171","msg":"trace[22711733] linearizableReadLoop","detail":"{readStateIndex:2021; appliedIndex:2020; }","duration":"234.259828ms","start":"2026-04-28T06:57:56.019534Z","end":"2026-04-28T06:57:56.253794Z","steps":["trace[22711733] 'read index received' (duration: 233.647332ms)","trace[22711733] 'applied index is now lower than readState.Index' (duration: 611.136µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:57:56.254033Z","caller":"traceutil/trace.go:171","msg":"trace[675584279] transaction","detail":"{read_only:false; response_revision:1938; number_of_response:1; }","duration":"266.11419ms","start":"2026-04-28T06:57:55.987884Z","end":"2026-04-28T06:57:56.253998Z","steps":["trace[675584279] 'process raft request' (duration: 265.178126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:56.25455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.006188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:57:56.254603Z","caller":"traceutil/trace.go:171","msg":"trace[2087973088] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1938; }","duration":"235.11074ms","start":"2026-04-28T06:57:56.019479Z","end":"2026-04-28T06:57:56.25459Z","steps":["trace[2087973088] 'agreement among raft nodes before linearized reading' (duration: 234.444083ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:56.315197Z","caller":"traceutil/trace.go:171","msg":"trace[669486468] transaction","detail":"{read_only:false; response_revision:1939; number_of_response:1; }","duration":"151.083621ms","start":"2026-04-28T06:57:56.164075Z","end":"2026-04-28T06:57:56.315158Z","steps":["trace[669486468] 'process raft request' (duration: 150.896036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:56.531035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.380537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-28T06:57:56.53098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.366791ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:57:56.531104Z","caller":"traceutil/trace.go:171","msg":"trace[1683335683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1939; }","duration":"131.50162ms","start":"2026-04-28T06:57:56.399588Z","end":"2026-04-28T06:57:56.53109Z","steps":["trace[1683335683] 'range keys from in-memory index tree' (duration: 131.231563ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:56.53113Z","caller":"traceutil/trace.go:171","msg":"trace[1578410323] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1939; }","duration":"104.544746ms","start":"2026-04-28T06:57:56.426561Z","end":"2026-04-28T06:57:56.531106Z","steps":["trace[1578410323] 'range keys from in-memory index tree' (duration: 104.335ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:58:23.82131Z","caller":"traceutil/trace.go:171","msg":"trace[294884071] transaction","detail":"{read_only:false; response_revision:2063; number_of_response:1; }","duration":"127.929346ms","start":"2026-04-28T06:58:23.693362Z","end":"2026-04-28T06:58:23.821291Z","steps":["trace[294884071] 'process raft request' (duration: 127.772041ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:58:24.778467Z","caller":"traceutil/trace.go:171","msg":"trace[443077933] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"118.580882ms","start":"2026-04-28T06:58:24.659864Z","end":"2026-04-28T06:58:24.778445Z","steps":["trace[443077933] 'process raft request' (duration: 118.444578ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:58:24.855187Z","caller":"traceutil/trace.go:171","msg":"trace[276504697] linearizableReadLoop","detail":"{readStateIndex:2157; appliedIndex:2155; }","duration":"127.768631ms","start":"2026-04-28T06:58:24.727394Z","end":"2026-04-28T06:58:24.855163Z","steps":["trace[276504697] 'read index received' (duration: 50.8947ms)","trace[276504697] 'applied index is now lower than readState.Index' (duration: 76.872741ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:58:24.855341Z","caller":"traceutil/trace.go:171","msg":"trace[87950892] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"192.845844ms","start":"2026-04-28T06:58:24.662466Z","end":"2026-04-28T06:58:24.855312Z","steps":["trace[87950892] 'process raft request' (duration: 179.079204ms)","trace[87950892] 'compare' (duration: 13.518374ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:58:24.855491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.07161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-04-28T06:58:24.855535Z","caller":"traceutil/trace.go:171","msg":"trace[402411454] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2068; }","duration":"128.176302ms","start":"2026-04-28T06:58:24.727346Z","end":"2026-04-28T06:58:24.855522Z","steps":["trace[402411454] 'agreement among raft nodes before linearized reading' (duration: 127.990197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:58:24.855573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.948518ms","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-04-28T06:58:24.855703Z","caller":"traceutil/trace.go:171","msg":"trace[525284980] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:2068; }","duration":"112.145133ms","start":"2026-04-28T06:58:24.743522Z","end":"2026-04-28T06:58:24.855667Z","steps":["trace[525284980] 'agreement among raft nodes before linearized reading' (duration: 111.902967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:58:37.125153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.435135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:58:37.125258Z","caller":"traceutil/trace.go:171","msg":"trace[711547988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2124; }","duration":"103.60758ms","start":"2026-04-28T06:58:37.021631Z","end":"2026-04-28T06:58:37.125238Z","steps":["trace[711547988] 'range keys from in-memory index tree' (duration: 103.324982ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:58:41.529168Z","caller":"traceutil/trace.go:171","msg":"trace[299912282] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"177.936954ms","start":"2026-04-28T06:58:41.351172Z","end":"2026-04-28T06:58:41.529109Z","steps":["trace[299912282] 'process raft request' (duration: 86.805741ms)","trace[299912282] 'compare' (duration: 90.791894ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:58:41.689645Z","caller":"traceutil/trace.go:171","msg":"trace[507803576] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"104.110012ms","start":"2026-04-28T06:58:41.585512Z","end":"2026-04-28T06:58:41.689622Z","steps":["trace[507803576] 'process raft request' (duration: 51.799654ms)","trace[507803576] 'compare' (duration: 52.209556ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:58:51.372662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.344465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T06:58:51.372749Z","caller":"traceutil/trace.go:171","msg":"trace[2052758459] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2196; }","duration":"113.476468ms","start":"2026-04-28T06:58:51.259254Z","end":"2026-04-28T06:58:51.37273Z","steps":["trace[2052758459] 'range keys from in-memory index tree' (duration: 113.17798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:58:56.384359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.281218ms","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-04-28T06:58:56.384474Z","caller":"traceutil/trace.go:171","msg":"trace[161649420] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2231; }","duration":"333.458532ms","start":"2026-04-28T06:58:56.05099Z","end":"2026-04-28T06:58:56.384448Z","steps":["trace[161649420] 'range keys from in-memory index tree' (duration: 333.027391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:58:56.38453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:58:56.050974Z","time spent":"333.545274ms","remote":"127.0.0.1:41372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T06:58:56.384519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.824638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2723"} {"level":"info","ts":"2026-04-28T06:58:56.384615Z","caller":"traceutil/trace.go:171","msg":"trace[1879211346] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2231; }","duration":"192.32032ms","start":"2026-04-28T06:58:56.192272Z","end":"2026-04-28T06:58:56.384592Z","steps":["trace[1879211346] 'range keys from in-memory index tree' (duration: 191.606211ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:59:00.566552Z","caller":"traceutil/trace.go:171","msg":"trace[308517759] transaction","detail":"{read_only:false; response_revision:2256; number_of_response:1; }","duration":"157.300817ms","start":"2026-04-28T06:59:00.409218Z","end":"2026-04-28T06:59:00.566518Z","steps":["trace[308517759] 'process raft request' (duration: 157.0631ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:59:00.708415Z","caller":"traceutil/trace.go:171","msg":"trace[792773336] transaction","detail":"{read_only:false; response_revision:2257; number_of_response:1; }","duration":"136.70086ms","start":"2026-04-28T06:59:00.571688Z","end":"2026-04-28T06:59:00.708389Z","steps":["trace[792773336] 'process raft request' (duration: 124.456012ms)","trace[792773336] 'compare' (duration: 12.050134ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:59:08.024328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.455831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T06:59:08.024426Z","caller":"traceutil/trace.go:171","msg":"trace[502089446] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:2295; }","duration":"334.625045ms","start":"2026-04-28T06:59:07.689781Z","end":"2026-04-28T06:59:08.024407Z","steps":["trace[502089446] 'range keys from in-memory index tree' (duration: 334.254766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:59:08.024468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:59:07.689762Z","time spent":"334.696378ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"info","ts":"2026-04-28T06:59:08.864797Z","caller":"traceutil/trace.go:171","msg":"trace[763162919] linearizableReadLoop","detail":"{readStateIndex:2400; appliedIndex:2399; }","duration":"126.647929ms","start":"2026-04-28T06:59:08.738119Z","end":"2026-04-28T06:59:08.864767Z","steps":["trace[763162919] 'read index received' (duration: 126.389021ms)","trace[763162919] 'applied index is now lower than readState.Index' (duration: 257.708µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:59:08.865074Z","caller":"traceutil/trace.go:171","msg":"trace[657537926] transaction","detail":"{read_only:false; response_revision:2301; number_of_response:1; }","duration":"132.84179ms","start":"2026-04-28T06:59:08.73219Z","end":"2026-04-28T06:59:08.865032Z","steps":["trace[657537926] 'process raft request' (duration: 132.379838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:59:08.86517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.029429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-28T06:59:08.865541Z","caller":"traceutil/trace.go:171","msg":"trace[666407657] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2301; }","duration":"127.217143ms","start":"2026-04-28T06:59:08.738064Z","end":"2026-04-28T06:59:08.865281Z","steps":["trace[666407657] 'agreement among raft nodes before linearized reading' (duration: 126.836624ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:59:08.99293Z","caller":"traceutil/trace.go:171","msg":"trace[212179826] transaction","detail":"{read_only:false; response_revision:2302; number_of_response:1; }","duration":"122.700147ms","start":"2026-04-28T06:59:08.870201Z","end":"2026-04-28T06:59:08.992901Z","steps":["trace[212179826] 'process raft request' (duration: 122.519471ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:59:13.38097Z","caller":"traceutil/trace.go:171","msg":"trace[2108709311] transaction","detail":"{read_only:false; response_revision:2324; number_of_response:1; }","duration":"109.320587ms","start":"2026-04-28T06:59:13.271619Z","end":"2026-04-28T06:59:13.380939Z","steps":["trace[2108709311] 'process raft request' (duration: 105.481867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:59:13.715294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.185229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/default/\" range_end:\"/registry/secrets/default0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:59:13.715369Z","caller":"traceutil/trace.go:171","msg":"trace[136792917] range","detail":"{range_begin:/registry/secrets/default/; range_end:/registry/secrets/default0; response_count:0; response_revision:2324; }","duration":"191.319483ms","start":"2026-04-28T06:59:13.524036Z","end":"2026-04-28T06:59:13.715355Z","steps":["trace[136792917] 'range keys from in-memory index tree' (duration: 191.042426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:59:13.715476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.793464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-28T06:59:13.715574Z","caller":"traceutil/trace.go:171","msg":"trace[1484218359] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2324; }","duration":"176.935687ms","start":"2026-04-28T06:59:13.538614Z","end":"2026-04-28T06:59:13.71555Z","steps":["trace[1484218359] 'range keys from in-memory index tree' (duration: 176.548087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:59:13.715619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.998719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-28T06:59:13.71571Z","caller":"traceutil/trace.go:171","msg":"trace[204998514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2324; }","duration":"119.158394ms","start":"2026-04-28T06:59:13.59653Z","end":"2026-04-28T06:59:13.715688Z","steps":["trace[204998514] 'range keys from in-memory index tree' (duration: 118.6672ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:59:13.826795Z","caller":"traceutil/trace.go:171","msg":"trace[1455517643] transaction","detail":"{read_only:false; response_revision:2326; number_of_response:1; }","duration":"106.504813ms","start":"2026-04-28T06:59:13.720265Z","end":"2026-04-28T06:59:13.82677Z","steps":["trace[1455517643] 'process raft request' (duration: 106.317809ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:59:22.012118Z","caller":"traceutil/trace.go:171","msg":"trace[530691048] linearizableReadLoop","detail":"{readStateIndex:2468; appliedIndex:2467; }","duration":"105.114447ms","start":"2026-04-28T06:59:21.906974Z","end":"2026-04-28T06:59:22.012088Z","steps":["trace[530691048] 'read index received' (duration: 55.730011ms)","trace[530691048] 'applied index is now lower than readState.Index' (duration: 49.383256ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:59:22.012202Z","caller":"traceutil/trace.go:171","msg":"trace[308726207] transaction","detail":"{read_only:false; response_revision:2367; number_of_response:1; }","duration":"149.35799ms","start":"2026-04-28T06:59:21.8628Z","end":"2026-04-28T06:59:22.012158Z","steps":["trace[308726207] 'process raft request' (duration: 100.005565ms)","trace[308726207] 'compare' (duration: 49.15841ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:59:22.012341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.343493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:expand-controller\" ","response":"range_response_count:1 size:743"} {"level":"info","ts":"2026-04-28T06:59:22.012741Z","caller":"traceutil/trace.go:171","msg":"trace[1334498331] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:expand-controller; range_end:; response_count:1; response_revision:2367; }","duration":"105.711883ms","start":"2026-04-28T06:59:21.906903Z","end":"2026-04-28T06:59:22.012615Z","steps":["trace[1334498331] 'agreement among raft nodes before linearized reading' (duration: 105.298562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:59:23.326247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.719932ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-28T06:59:23.326373Z","caller":"traceutil/trace.go:171","msg":"trace[974870198] linearizableReadLoop","detail":"{readStateIndex:2473; appliedIndex:2472; }","duration":"163.943159ms","start":"2026-04-28T06:59:23.162411Z","end":"2026-04-28T06:59:23.326354Z","steps":["trace[974870198] 'read index received' (duration: 35.049593ms)","trace[974870198] 'applied index is now lower than readState.Index' (duration: 128.891936ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:59:23.326496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.066152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:service-account-controller\" ","response":"range_response_count:1 size:677"} {"level":"info","ts":"2026-04-28T06:59:23.326556Z","caller":"traceutil/trace.go:171","msg":"trace[917176673] range","detail":"{range_begin:/registry/clusterroles/system:controller:service-account-controller; range_end:; response_count:1; response_revision:2371; }","duration":"164.159956ms","start":"2026-04-28T06:59:23.16238Z","end":"2026-04-28T06:59:23.32654Z","steps":["trace[917176673] 'agreement among raft nodes before linearized reading' (duration: 164.035402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:59:23.326554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.779464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pod-garbage-collector\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-04-28T06:59:23.32663Z","caller":"traceutil/trace.go:171","msg":"trace[1570961041] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pod-garbage-collector; range_end:; response_count:1; response_revision:2371; }","duration":"128.891827ms","start":"2026-04-28T06:59:23.197723Z","end":"2026-04-28T06:59:23.326615Z","steps":["trace[1570961041] 'agreement among raft nodes before linearized reading' (duration: 128.727472ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:59:23.617582Z","caller":"traceutil/trace.go:171","msg":"trace[159076953] linearizableReadLoop","detail":"{readStateIndex:2474; appliedIndex:2473; }","duration":"218.979744ms","start":"2026-04-28T06:59:23.398579Z","end":"2026-04-28T06:59:23.617559Z","steps":["trace[159076953] 'read index received' (duration: 218.896642ms)","trace[159076953] 'applied index is now lower than readState.Index' (duration: 82.152µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:59:23.617699Z","caller":"traceutil/trace.go:171","msg":"trace[1753551868] transaction","detail":"{read_only:false; response_revision:2372; number_of_response:1; }","duration":"261.49017ms","start":"2026-04-28T06:59:23.356175Z","end":"2026-04-28T06:59:23.617665Z","steps":["trace[1753551868] 'process raft request' (duration: 261.232133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:59:23.617745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.146068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pod-garbage-collector\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-04-28T06:59:23.617807Z","caller":"traceutil/trace.go:171","msg":"trace[242492007] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pod-garbage-collector; range_end:; response_count:1; response_revision:2372; }","duration":"219.254472ms","start":"2026-04-28T06:59:23.39854Z","end":"2026-04-28T06:59:23.617794Z","steps":["trace[242492007] 'agreement among raft nodes before linearized reading' (duration: 219.139248ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:59:23.619509Z","caller":"traceutil/trace.go:171","msg":"trace[1273099016] transaction","detail":"{read_only:false; response_revision:2373; number_of_response:1; }","duration":"127.219423ms","start":"2026-04-28T06:59:23.492264Z","end":"2026-04-28T06:59:23.619484Z","steps":["trace[1273099016] 'process raft request' (duration: 127.028819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:59:23.619518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.335637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:service-account-controller\" ","response":"range_response_count:1 size:677"} {"level":"info","ts":"2026-04-28T06:59:23.619592Z","caller":"traceutil/trace.go:171","msg":"trace[1970348956] range","detail":"{range_begin:/registry/clusterroles/system:controller:service-account-controller; range_end:; response_count:1; response_revision:2373; }","duration":"218.43245ms","start":"2026-04-28T06:59:23.401142Z","end":"2026-04-28T06:59:23.619574Z","steps":["trace[1970348956] 'agreement among raft nodes before linearized reading' (duration: 218.293486ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:59:25.755764Z","caller":"traceutil/trace.go:171","msg":"trace[990931938] transaction","detail":"{read_only:false; response_revision:2383; number_of_response:1; }","duration":"131.841814ms","start":"2026-04-28T06:59:25.623902Z","end":"2026-04-28T06:59:25.755744Z","steps":["trace[990931938] 'process raft request' (duration: 131.661619ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:59:26.861994Z","caller":"traceutil/trace.go:171","msg":"trace[689946671] transaction","detail":"{read_only:false; response_revision:2388; number_of_response:1; }","duration":"114.934773ms","start":"2026-04-28T06:59:26.747045Z","end":"2026-04-28T06:59:26.86198Z","steps":["trace[689946671] 'process raft request' (duration: 114.680477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:59:27.317204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.609231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:monitoring\" ","response":"range_response_count:1 size:694"} {"level":"info","ts":"2026-04-28T06:59:27.317308Z","caller":"traceutil/trace.go:171","msg":"trace[2002066409] range","detail":"{range_begin:/registry/clusterrolebindings/system:monitoring; range_end:; response_count:1; response_revision:2390; }","duration":"100.749964ms","start":"2026-04-28T06:59:27.216535Z","end":"2026-04-28T06:59:27.317285Z","steps":["trace[2002066409] 'range keys from in-memory index tree' (duration: 100.522878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:59:36.890336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.510238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system::extension-apiserver-authentication-reader\" ","response":"range_response_count:1 size:856"} {"level":"info","ts":"2026-04-28T06:59:36.890416Z","caller":"traceutil/trace.go:171","msg":"trace[291977864] range","detail":"{range_begin:/registry/rolebindings/kube-system/system::extension-apiserver-authentication-reader; range_end:; response_count:1; response_revision:2434; }","duration":"133.632881ms","start":"2026-04-28T06:59:36.756767Z","end":"2026-04-28T06:59:36.8904Z","steps":["trace[291977864] 'range keys from in-memory index tree' (duration: 133.419436ms)"],"step_count":1}