{"level":"warn","ts":"2026-04-14T16:03:57.534694Z","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-14T16:03:57.534821Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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-14T16:03:57.535021Z","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-14T16:03:57.535111Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-04-14T16:03:57.535182Z","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-14T16:03:57.536047Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-04-14T16:03:57.53614Z","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.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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-14T16:03:57.540748Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.364505ms"} {"level":"info","ts":"2026-04-14T16:03:57.553924Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-04-14T16:03:57.55401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-04-14T16:03:57.554031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-04-14T16:03:57.55404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-14T16:03:57.554049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-04-14T16:03:57.554089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-04-14T16:03:57.562431Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-14T16:03:57.564114Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-14T16:03:57.565599Z","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-14T16:03:57.568142Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-14T16:03:57.568282Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-14T16:03:57.568449Z","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-14T16:03:57.56856Z","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-14T16:03:57.568571Z","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-14T16:03:57.571154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-04-14T16:03:57.571448Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-04-14T16:03:57.573423Z","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-14T16:03:57.57352Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-04-14T16:03:57.573579Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-04-14T16:03:57.573684Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-14T16:03:57.573729Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-14T16:03:58.254715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-04-14T16:03:58.254784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-14T16:03:58.254812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-04-14T16:03:58.254855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-04-14T16:03:58.254864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-04-14T16:03:58.254876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-04-14T16:03:58.254885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-04-14T16:03:58.255964Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-14T16:03:58.255964Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T16:03:58.256001Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T16:03:58.256005Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T16:03:58.256312Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-14T16:03:58.256362Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-14T16:03:58.257745Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-14T16:03:58.257923Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T16:03:58.25809Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T16:03:58.258124Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T16:03:58.260013Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-04-14T16:04:25.008699Z","caller":"traceutil/trace.go:171","msg":"trace[1573819435] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"144.660033ms","start":"2026-04-14T16:04:24.864007Z","end":"2026-04-14T16:04:25.008667Z","steps":["trace[1573819435] 'process raft request' (duration: 144.444239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:04:27.01289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.980259ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-14T16:04:27.013025Z","caller":"traceutil/trace.go:171","msg":"trace[1618640391] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:481; }","duration":"374.887615ms","start":"2026-04-14T16:04:26.638119Z","end":"2026-04-14T16:04:27.013006Z","steps":["trace[1618640391] 'read index received' (duration: 77.60384ms)","trace[1618640391] 'applied index is now lower than readState.Index' (duration: 297.282295ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:04:27.013144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.020208ms","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-14T16:04:27.013182Z","caller":"traceutil/trace.go:171","msg":"trace[1026399871] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:468; }","duration":"375.10408ms","start":"2026-04-14T16:04:26.638067Z","end":"2026-04-14T16:04:27.013171Z","steps":["trace[1026399871] 'agreement among raft nodes before linearized reading' (duration: 375.030938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:04:27.013221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:04:26.638038Z","time spent":"375.17339ms","remote":"127.0.0.1:42016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-04-14T16:04:27.135178Z","caller":"traceutil/trace.go:171","msg":"trace[1757044070] linearizableReadLoop","detail":"{readStateIndex:483; appliedIndex:482; }","duration":"109.547538ms","start":"2026-04-14T16:04:27.025608Z","end":"2026-04-14T16:04:27.135155Z","steps":["trace[1757044070] 'read index received' (duration: 109.397095ms)","trace[1757044070] 'applied index is now lower than readState.Index' (duration: 149.613µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T16:04:27.135244Z","caller":"traceutil/trace.go:171","msg":"trace[1867135911] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"117.72974ms","start":"2026-04-14T16:04:27.01748Z","end":"2026-04-14T16:04:27.13521Z","steps":["trace[1867135911] 'process raft request' (duration: 117.560657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:04:27.135307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.68017ms","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-14T16:04:27.135344Z","caller":"traceutil/trace.go:171","msg":"trace[991836537] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:469; }","duration":"109.758452ms","start":"2026-04-14T16:04:27.025573Z","end":"2026-04-14T16:04:27.135331Z","steps":["trace[991836537] 'agreement among raft nodes before linearized reading' (duration: 109.686591ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:04:28.785847Z","caller":"traceutil/trace.go:171","msg":"trace[599699081] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"501.99874ms","start":"2026-04-14T16:04:28.283408Z","end":"2026-04-14T16:04:28.785406Z","steps":["trace[599699081] 'process raft request' (duration: 497.456565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:04:28.786424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:04:28.283377Z","time spent":"502.569892ms","remote":"127.0.0.1:41956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T16:04:30.165627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.750415ms","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-14T16:04:30.165725Z","caller":"traceutil/trace.go:171","msg":"trace[447190740] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:550; }","duration":"151.882638ms","start":"2026-04-14T16:04:30.013824Z","end":"2026-04-14T16:04:30.165706Z","steps":["trace[447190740] 'range keys from in-memory index tree' (duration: 151.684284ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:04:33.281052Z","caller":"traceutil/trace.go:171","msg":"trace[1029981280] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"113.350719ms","start":"2026-04-14T16:04:33.167628Z","end":"2026-04-14T16:04:33.280979Z","steps":["trace[1029981280] 'process raft request' (duration: 112.905951ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:04:33.705007Z","caller":"traceutil/trace.go:171","msg":"trace[1340551374] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"313.810234ms","start":"2026-04-14T16:04:33.391156Z","end":"2026-04-14T16:04:33.704966Z","steps":["trace[1340551374] 'process raft request' (duration: 312.885337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:04:33.705567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:04:33.391109Z","time spent":"314.011668ms","remote":"127.0.0.1:42016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036241,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T16:04:33.723917Z","caller":"traceutil/trace.go:171","msg":"trace[228758221] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"140.413004ms","start":"2026-04-14T16:04:33.58348Z","end":"2026-04-14T16:04:33.723893Z","steps":["trace[228758221] 'process raft request' (duration: 140.287151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:04:34.497405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.768212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T16:04:34.497522Z","caller":"traceutil/trace.go:171","msg":"trace[1532772092] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"596.09956ms","start":"2026-04-14T16:04:33.901404Z","end":"2026-04-14T16:04:34.497503Z","steps":["trace[1532772092] 'read index received' (duration: 289.096073ms)","trace[1532772092] 'applied index is now lower than readState.Index' (duration: 307.002457ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T16:04:34.497587Z","caller":"traceutil/trace.go:171","msg":"trace[2015641614] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"726.741681ms","start":"2026-04-14T16:04:33.770782Z","end":"2026-04-14T16:04:34.497523Z","steps":["trace[2015641614] 'process raft request' (duration: 419.764755ms)","trace[2015641614] 'compare' (duration: 306.343405ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:04:34.497774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.660167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-z6skf\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-14T16:04:34.49783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:04:33.770757Z","time spent":"726.943254ms","remote":"127.0.0.1:42292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T16:04:34.49784Z","caller":"traceutil/trace.go:171","msg":"trace[706988947] range","detail":"{range_begin:/registry/pods/kube-system/cilium-z6skf; range_end:; response_count:1; response_revision:577; }","duration":"583.721778ms","start":"2026-04-14T16:04:33.914076Z","end":"2026-04-14T16:04:34.497798Z","steps":["trace[706988947] 'agreement among raft nodes before linearized reading' (duration: 583.522574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:04:34.497919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:04:33.914066Z","time spent":"583.84431ms","remote":"127.0.0.1:42096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-z6skf\" "} {"level":"warn","ts":"2026-04-14T16:04:34.497967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.534957ms","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-14T16:04:34.498118Z","caller":"traceutil/trace.go:171","msg":"trace[808718084] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:577; }","duration":"596.742861ms","start":"2026-04-14T16:04:33.901338Z","end":"2026-04-14T16:04:34.498081Z","steps":["trace[808718084] 'agreement among raft nodes before linearized reading' (duration: 596.249622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:04:34.498169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:04:33.901316Z","time spent":"596.843113ms","remote":"127.0.0.1:42192","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-14T16:04:34.498235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.569971ms","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-14T16:04:34.498307Z","caller":"traceutil/trace.go:171","msg":"trace[1907479840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:577; }","duration":"312.713285ms","start":"2026-04-14T16:04:34.185571Z","end":"2026-04-14T16:04:34.498285Z","steps":["trace[1907479840] 'agreement among raft nodes before linearized reading' (duration: 312.419948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:04:34.4983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.406746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-14T16:04:34.498346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:04:34.185529Z","time spent":"312.809116ms","remote":"127.0.0.1:42192","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-14T16:04:34.498395Z","caller":"traceutil/trace.go:171","msg":"trace[441620146] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:577; }","duration":"313.542049ms","start":"2026-04-14T16:04:34.184829Z","end":"2026-04-14T16:04:34.498371Z","steps":["trace[441620146] 'agreement among raft nodes before linearized reading' (duration: 313.250993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:04:34.498454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:04:34.184809Z","time spent":"313.636201ms","remote":"127.0.0.1:42192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-14T16:04:34.693402Z","caller":"traceutil/trace.go:171","msg":"trace[1228001558] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"190.437889ms","start":"2026-04-14T16:04:34.502924Z","end":"2026-04-14T16:04:34.693362Z","steps":["trace[1228001558] 'process raft request' (duration: 188.772898ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:04:34.712074Z","caller":"traceutil/trace.go:171","msg":"trace[1609490287] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"208.004087ms","start":"2026-04-14T16:04:34.504033Z","end":"2026-04-14T16:04:34.712037Z","steps":["trace[1609490287] 'process raft request' (duration: 207.749692ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:04:34.712127Z","caller":"traceutil/trace.go:171","msg":"trace[1545197126] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:594; }","duration":"203.691256ms","start":"2026-04-14T16:04:34.508411Z","end":"2026-04-14T16:04:34.712102Z","steps":["trace[1545197126] 'read index received' (duration: 183.301675ms)","trace[1545197126] 'applied index is now lower than readState.Index' (duration: 20.388181ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:04:34.712282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.855479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T16:04:34.712329Z","caller":"traceutil/trace.go:171","msg":"trace[495125705] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:581; }","duration":"203.943841ms","start":"2026-04-14T16:04:34.508374Z","end":"2026-04-14T16:04:34.712318Z","steps":["trace[495125705] 'agreement among raft nodes before linearized reading' (duration: 203.820778ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:04:34.712399Z","caller":"traceutil/trace.go:171","msg":"trace[958185157] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"207.980956ms","start":"2026-04-14T16:04:34.5044Z","end":"2026-04-14T16:04:34.712381Z","steps":["trace[958185157] 'process raft request' (duration: 207.528508ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:04:34.712474Z","caller":"traceutil/trace.go:171","msg":"trace[825831983] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"200.038768ms","start":"2026-04-14T16:04:34.512424Z","end":"2026-04-14T16:04:34.712463Z","steps":["trace[825831983] 'process raft request' (duration: 199.61195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:04:36.376689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.50279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T16:04:36.376793Z","caller":"traceutil/trace.go:171","msg":"trace[2142270392] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:617; }","duration":"198.689183ms","start":"2026-04-14T16:04:36.178082Z","end":"2026-04-14T16:04:36.376772Z","steps":["trace[2142270392] 'range keys from in-memory index tree' (duration: 198.416348ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:04:49.757952Z","caller":"traceutil/trace.go:171","msg":"trace[1014084153] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"393.013633ms","start":"2026-04-14T16:04:49.364905Z","end":"2026-04-14T16:04:49.757919Z","steps":["trace[1014084153] 'process raft request' (duration: 392.128477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:04:49.758181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:04:49.364873Z","time spent":"393.201577ms","remote":"127.0.0.1:42192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T16:04:50.392136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.608889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T16:04:50.392426Z","caller":"traceutil/trace.go:171","msg":"trace[1901988038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:743; }","duration":"437.004276ms","start":"2026-04-14T16:04:49.955401Z","end":"2026-04-14T16:04:50.392406Z","steps":["trace[1901988038] 'range keys from in-memory index tree' (duration: 436.482457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:04:50.392469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:04:49.955379Z","time spent":"437.080628ms","remote":"127.0.0.1:41932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T16:04:50.392833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.296346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T16:04:50.392967Z","caller":"traceutil/trace.go:171","msg":"trace[1718988970] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:764; }","duration":"336.228484ms","start":"2026-04-14T16:04:50.056717Z","end":"2026-04-14T16:04:50.392946Z","steps":["trace[1718988970] 'read index received' (duration: 175.826416ms)","trace[1718988970] 'applied index is now lower than readState.Index' (duration: 160.400978ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:04:50.39313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.410827ms","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-14T16:04:50.393174Z","caller":"traceutil/trace.go:171","msg":"trace[461657690] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:744; }","duration":"336.463177ms","start":"2026-04-14T16:04:50.056684Z","end":"2026-04-14T16:04:50.393147Z","steps":["trace[461657690] 'agreement among raft nodes before linearized reading' (duration: 336.316445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:04:50.393208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:04:50.056672Z","time spent":"336.527818ms","remote":"127.0.0.1:42192","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-14T16:04:50.393137Z","caller":"traceutil/trace.go:171","msg":"trace[1702529872] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"356.680976ms","start":"2026-04-14T16:04:50.036441Z","end":"2026-04-14T16:04:50.393122Z","steps":["trace[1702529872] 'process raft request' (duration: 195.984192ms)","trace[1702529872] 'compare' (duration: 160.151314ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:04:50.393468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:04:50.03641Z","time spent":"356.967911ms","remote":"127.0.0.1:42162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T16:05:24.875486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.527195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T16:05:24.875608Z","caller":"traceutil/trace.go:171","msg":"trace[1308213608] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:962; }","duration":"269.692949ms","start":"2026-04-14T16:05:24.605882Z","end":"2026-04-14T16:05:24.875575Z","steps":["trace[1308213608] 'count revisions from in-memory index tree' (duration: 269.460325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:05:24.875546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.412918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-14T16:05:24.876103Z","caller":"traceutil/trace.go:171","msg":"trace[1844037051] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:962; }","duration":"305.051951ms","start":"2026-04-14T16:05:24.571023Z","end":"2026-04-14T16:05:24.876075Z","steps":["trace[1844037051] 'range keys from in-memory index tree' (duration: 304.164343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:05:24.876182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:05:24.570998Z","time spent":"305.167823ms","remote":"127.0.0.1:42192","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-14T16:05:25.301931Z","caller":"traceutil/trace.go:171","msg":"trace[570846627] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"352.636941ms","start":"2026-04-14T16:05:24.949275Z","end":"2026-04-14T16:05:25.301912Z","steps":["trace[570846627] 'read index received' (duration: 352.438377ms)","trace[570846627] 'applied index is now lower than readState.Index' (duration: 197.884µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T16:05:25.302156Z","caller":"traceutil/trace.go:171","msg":"trace[714930746] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"420.781706ms","start":"2026-04-14T16:05:24.88136Z","end":"2026-04-14T16:05:25.302141Z","steps":["trace[714930746] 'process raft request' (duration: 420.412528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:05:25.3022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.880855ms","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":"warn","ts":"2026-04-14T16:05:25.302257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:05:24.881332Z","time spent":"420.859917ms","remote":"127.0.0.1:42192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T16:05:25.302285Z","caller":"traceutil/trace.go:171","msg":"trace[1624535744] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:963; }","duration":"194.976397ms","start":"2026-04-14T16:05:25.107245Z","end":"2026-04-14T16:05:25.302221Z","steps":["trace[1624535744] 'agreement among raft nodes before linearized reading' (duration: 194.904766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:05:25.302318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.46838ms","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-14T16:05:25.302305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.406599ms","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-14T16:05:25.302407Z","caller":"traceutil/trace.go:171","msg":"trace[1784659953] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:963; }","duration":"347.528182ms","start":"2026-04-14T16:05:24.954831Z","end":"2026-04-14T16:05:25.302359Z","steps":["trace[1784659953] 'agreement among raft nodes before linearized reading' (duration: 347.369699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:05:25.302449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:05:24.95481Z","time spent":"347.630994ms","remote":"127.0.0.1:42192","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-14T16:05:25.302453Z","caller":"traceutil/trace.go:171","msg":"trace[923074382] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:963; }","duration":"350.630903ms","start":"2026-04-14T16:05:24.951801Z","end":"2026-04-14T16:05:25.302432Z","steps":["trace[923074382] 'agreement among raft nodes before linearized reading' (duration: 350.335007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:05:25.302525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:05:24.951769Z","time spent":"350.739875ms","remote":"127.0.0.1:42192","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-14T16:05:25.302579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.308935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-14T16:05:25.302605Z","caller":"traceutil/trace.go:171","msg":"trace[8555643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:963; }","duration":"353.373006ms","start":"2026-04-14T16:05:24.949224Z","end":"2026-04-14T16:05:25.302597Z","steps":["trace[8555643] 'agreement among raft nodes before linearized reading' (duration: 352.904907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:05:25.302624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:05:24.94921Z","time spent":"353.408536ms","remote":"127.0.0.1:42192","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-14T16:05:25.829231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.572069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T16:05:25.829387Z","caller":"traceutil/trace.go:171","msg":"trace[1445722012] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"523.367134ms","start":"2026-04-14T16:05:25.305993Z","end":"2026-04-14T16:05:25.829361Z","steps":["trace[1445722012] 'process raft request' (duration: 376.080311ms)","trace[1445722012] 'compare' (duration: 146.156731ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:05:25.829449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:05:25.305972Z","time spent":"523.450845ms","remote":"127.0.0.1:42192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T16:05:25.9788Z","caller":"traceutil/trace.go:171","msg":"trace[842860271] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"671.34095ms","start":"2026-04-14T16:05:25.307439Z","end":"2026-04-14T16:05:25.97878Z","steps":["trace[842860271] 'process raft request' (duration: 671.284859ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:05:25.978835Z","caller":"traceutil/trace.go:171","msg":"trace[1550573776] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"672.40418ms","start":"2026-04-14T16:05:25.306401Z","end":"2026-04-14T16:05:25.978806Z","steps":["trace[1550573776] 'process raft request' (duration: 672.213997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:05:25.978966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:05:25.307424Z","time spent":"671.442742ms","remote":"127.0.0.1:42192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T16:05:25.978988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:05:25.306335Z","time spent":"672.561083ms","remote":"127.0.0.1:42192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T16:05:25.979019Z","caller":"traceutil/trace.go:171","msg":"trace[1071607432] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"580.720087ms","start":"2026-04-14T16:05:25.398274Z","end":"2026-04-14T16:05:25.978994Z","steps":["trace[1071607432] 'process raft request' (duration: 580.478272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:05:25.979154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:05:25.398242Z","time spent":"580.834208ms","remote":"127.0.0.1:42192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T16:05:48.195964Z","caller":"traceutil/trace.go:171","msg":"trace[183305243] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1068; }","duration":"240.455966ms","start":"2026-04-14T16:05:47.955487Z","end":"2026-04-14T16:05:48.195943Z","steps":["trace[183305243] 'read index received' (duration: 240.325444ms)","trace[183305243] 'applied index is now lower than readState.Index' (duration: 129.422µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T16:05:48.196017Z","caller":"traceutil/trace.go:171","msg":"trace[9525978] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"269.952484ms","start":"2026-04-14T16:05:47.926035Z","end":"2026-04-14T16:05:48.195988Z","steps":["trace[9525978] 'process raft request' (duration: 269.75752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:05:48.196163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.663431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T16:05:48.196239Z","caller":"traceutil/trace.go:171","msg":"trace[987918804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1036; }","duration":"240.779782ms","start":"2026-04-14T16:05:47.955445Z","end":"2026-04-14T16:05:48.196225Z","steps":["trace[987918804] 'agreement among raft nodes before linearized reading' (duration: 240.619769ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:05:48.203037Z","caller":"traceutil/trace.go:171","msg":"trace[669086489] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"144.674302ms","start":"2026-04-14T16:05:48.05834Z","end":"2026-04-14T16:05:48.203014Z","steps":["trace[669086489] 'process raft request' (duration: 144.537179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:05:48.203188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.862117ms","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-14T16:05:48.203341Z","caller":"traceutil/trace.go:171","msg":"trace[1381644428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1037; }","duration":"117.073052ms","start":"2026-04-14T16:05:48.086247Z","end":"2026-04-14T16:05:48.20332Z","steps":["trace[1381644428] 'agreement among raft nodes before linearized reading' (duration: 116.790076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:05:48.203342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.484911ms","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-14T16:05:48.203384Z","caller":"traceutil/trace.go:171","msg":"trace[592817675] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1037; }","duration":"136.567643ms","start":"2026-04-14T16:05:48.066804Z","end":"2026-04-14T16:05:48.203371Z","steps":["trace[592817675] 'agreement among raft nodes before linearized reading' (duration: 136.402879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:05:48.203689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.314196ms","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-14T16:05:48.203798Z","caller":"traceutil/trace.go:171","msg":"trace[1114729590] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1037; }","duration":"117.451949ms","start":"2026-04-14T16:05:48.086329Z","end":"2026-04-14T16:05:48.203781Z","steps":["trace[1114729590] 'agreement among raft nodes before linearized reading' (duration: 117.125813ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:05:50.449541Z","caller":"traceutil/trace.go:171","msg":"trace[2076527978] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"244.975485ms","start":"2026-04-14T16:05:50.204545Z","end":"2026-04-14T16:05:50.44952Z","steps":["trace[2076527978] 'process raft request' (duration: 244.832142ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:05:50.525413Z","caller":"traceutil/trace.go:171","msg":"trace[237470746] linearizableReadLoop","detail":"{readStateIndex:1076; appliedIndex:1074; }","duration":"295.710577ms","start":"2026-04-14T16:05:50.229674Z","end":"2026-04-14T16:05:50.525385Z","steps":["trace[237470746] 'read index received' (duration: 219.764411ms)","trace[237470746] 'applied index is now lower than readState.Index' (duration: 75.944656ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T16:05:50.525514Z","caller":"traceutil/trace.go:171","msg":"trace[1485761977] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"317.000336ms","start":"2026-04-14T16:05:50.208493Z","end":"2026-04-14T16:05:50.525493Z","steps":["trace[1485761977] 'process raft request' (duration: 316.732279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:05:50.52565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:05:50.208479Z","time spent":"317.083847ms","remote":"127.0.0.1:42192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T16:05:50.525683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.523738ms","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-14T16:05:50.525734Z","caller":"traceutil/trace.go:171","msg":"trace[565765519] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1043; }","duration":"289.601739ms","start":"2026-04-14T16:05:50.236116Z","end":"2026-04-14T16:05:50.525718Z","steps":["trace[565765519] 'agreement among raft nodes before linearized reading' (duration: 289.424565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:05:50.525882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.67048ms","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-14T16:05:50.525968Z","caller":"traceutil/trace.go:171","msg":"trace[578975210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1043; }","duration":"289.785442ms","start":"2026-04-14T16:05:50.236162Z","end":"2026-04-14T16:05:50.525948Z","steps":["trace[578975210] 'agreement among raft nodes before linearized reading' (duration: 289.557008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:05:50.526013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.241618ms","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-14T16:05:50.526174Z","caller":"traceutil/trace.go:171","msg":"trace[1852278929] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1043; }","duration":"296.476213ms","start":"2026-04-14T16:05:50.229629Z","end":"2026-04-14T16:05:50.526105Z","steps":["trace[1852278929] 'agreement among raft nodes before linearized reading' (duration: 295.938103ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:05:56.403796Z","caller":"traceutil/trace.go:171","msg":"trace[1037205209] linearizableReadLoop","detail":"{readStateIndex:1097; appliedIndex:1096; }","duration":"164.861067ms","start":"2026-04-14T16:05:56.238913Z","end":"2026-04-14T16:05:56.403774Z","steps":["trace[1037205209] 'read index received' (duration: 144.649471ms)","trace[1037205209] 'applied index is now lower than readState.Index' (duration: 20.210166ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:05:56.404078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.977079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T16:05:56.404132Z","caller":"traceutil/trace.go:171","msg":"trace[1211029870] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1062; }","duration":"165.248254ms","start":"2026-04-14T16:05:56.23887Z","end":"2026-04-14T16:05:56.404118Z","steps":["trace[1211029870] 'agreement among raft nodes before linearized reading' (duration: 164.989589ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:05:56.606418Z","caller":"traceutil/trace.go:171","msg":"trace[1811098611] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"129.452354ms","start":"2026-04-14T16:05:56.476948Z","end":"2026-04-14T16:05:56.6064Z","steps":["trace[1811098611] 'process raft request' (duration: 129.324371ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:06:03.790717Z","caller":"traceutil/trace.go:171","msg":"trace[913177523] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"220.188939ms","start":"2026-04-14T16:06:03.570504Z","end":"2026-04-14T16:06:03.790693Z","steps":["trace[913177523] 'process raft request' (duration: 220.040646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:06:04.486439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.67144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T16:06:04.486526Z","caller":"traceutil/trace.go:171","msg":"trace[61744977] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1139; }","duration":"571.810132ms","start":"2026-04-14T16:06:03.914693Z","end":"2026-04-14T16:06:04.486503Z","steps":["trace[61744977] 'count revisions from in-memory index tree' (duration: 571.565688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:06:04.48657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:06:03.914675Z","time spent":"571.883864ms","remote":"127.0.0.1:42170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-04-14T16:06:16.487194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.600958ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-14T16:06:19.403351Z","caller":"traceutil/trace.go:171","msg":"trace[454634771] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"311.978028ms","start":"2026-04-14T16:06:19.091342Z","end":"2026-04-14T16:06:19.40332Z","steps":["trace[454634771] 'process raft request' (duration: 305.936533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:06:19.403541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:06:19.09133Z","time spent":"312.11732ms","remote":"127.0.0.1:42340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T16:06:21.268558Z","caller":"traceutil/trace.go:171","msg":"trace[868428969] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"466.490249ms","start":"2026-04-14T16:06:20.80204Z","end":"2026-04-14T16:06:21.268531Z","steps":["trace[868428969] 'process raft request' (duration: 466.332966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:06:21.268736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:06:20.802019Z","time spent":"466.618261ms","remote":"127.0.0.1:42192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T16:06:21.268938Z","caller":"traceutil/trace.go:171","msg":"trace[1289507663] linearizableReadLoop","detail":"{readStateIndex:1253; appliedIndex:1253; }","duration":"464.682495ms","start":"2026-04-14T16:06:20.804222Z","end":"2026-04-14T16:06:21.268904Z","steps":["trace[1289507663] 'read index received' (duration: 464.668225ms)","trace[1289507663] 'applied index is now lower than readState.Index' (duration: 11.78µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:06:21.269256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.080997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T16:06:21.269255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.00424ms","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-14T16:06:21.269305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.325439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-14T16:06:21.269327Z","caller":"traceutil/trace.go:171","msg":"trace[62035092] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1212; }","duration":"314.181869ms","start":"2026-04-14T16:06:20.955133Z","end":"2026-04-14T16:06:21.269315Z","steps":["trace[62035092] 'agreement among raft nodes before linearized reading' (duration: 314.086477ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:06:21.269351Z","caller":"traceutil/trace.go:171","msg":"trace[2132628733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1212; }","duration":"463.408871ms","start":"2026-04-14T16:06:20.805929Z","end":"2026-04-14T16:06:21.269338Z","steps":["trace[2132628733] 'agreement among raft nodes before linearized reading' (duration: 463.147896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:06:21.269363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:06:20.955111Z","time spent":"314.24567ms","remote":"127.0.0.1:41932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T16:06:21.269357Z","caller":"traceutil/trace.go:171","msg":"trace[2008601337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1212; }","duration":"465.228165ms","start":"2026-04-14T16:06:20.804103Z","end":"2026-04-14T16:06:21.269331Z","steps":["trace[2008601337] 'agreement among raft nodes before linearized reading' (duration: 464.95505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:06:21.269405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:06:20.805917Z","time spent":"463.473622ms","remote":"127.0.0.1:42192","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-14T16:06:21.269409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:06:20.804095Z","time spent":"465.305276ms","remote":"127.0.0.1:42192","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-14T16:06:21.269626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.76952ms","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-14T16:06:21.269666Z","caller":"traceutil/trace.go:171","msg":"trace[1300737171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1212; }","duration":"461.865161ms","start":"2026-04-14T16:06:20.807793Z","end":"2026-04-14T16:06:21.269658Z","steps":["trace[1300737171] 'agreement among raft nodes before linearized reading' (duration: 461.7665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:06:21.269694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:06:20.807764Z","time spent":"461.924032ms","remote":"127.0.0.1:42192","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-14T16:06:21.26988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.204777ms","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-14T16:06:21.269922Z","caller":"traceutil/trace.go:171","msg":"trace[754168473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1212; }","duration":"462.261459ms","start":"2026-04-14T16:06:20.807648Z","end":"2026-04-14T16:06:21.26991Z","steps":["trace[754168473] 'agreement among raft nodes before linearized reading' (duration: 462.126077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:06:21.269957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:06:20.807642Z","time spent":"462.30841ms","remote":"127.0.0.1:42192","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-14T16:06:21.394796Z","caller":"traceutil/trace.go:171","msg":"trace[737332675] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"120.196382ms","start":"2026-04-14T16:06:21.274559Z","end":"2026-04-14T16:06:21.394755Z","steps":["trace[737332675] 'process raft request' (duration: 120.08736ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:06:21.394843Z","caller":"traceutil/trace.go:171","msg":"trace[1023583877] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"120.438216ms","start":"2026-04-14T16:06:21.274381Z","end":"2026-04-14T16:06:21.394819Z","steps":["trace[1023583877] 'process raft request' (duration: 120.06846ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:06:21.395054Z","caller":"traceutil/trace.go:171","msg":"trace[655818527] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"120.177692ms","start":"2026-04-14T16:06:21.274859Z","end":"2026-04-14T16:06:21.395037Z","steps":["trace[655818527] 'process raft request' (duration: 119.836466ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:06:21.395242Z","caller":"traceutil/trace.go:171","msg":"trace[1347452784] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"119.51ms","start":"2026-04-14T16:06:21.275704Z","end":"2026-04-14T16:06:21.395214Z","steps":["trace[1347452784] 'process raft request' (duration: 119.246485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:06:22.540516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.516573ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T16:06:22.540774Z","caller":"traceutil/trace.go:171","msg":"trace[392960148] linearizableReadLoop","detail":"{readStateIndex:1264; appliedIndex:1262; }","duration":"105.177289ms","start":"2026-04-14T16:06:22.43558Z","end":"2026-04-14T16:06:22.540757Z","steps":["trace[392960148] 'read index received' (duration: 36.643883ms)","trace[392960148] 'applied index is now lower than readState.Index' (duration: 68.532706ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:06:22.540914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.332362ms","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-14T16:06:22.540947Z","caller":"traceutil/trace.go:171","msg":"trace[463609831] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1222; }","duration":"105.409484ms","start":"2026-04-14T16:06:22.435526Z","end":"2026-04-14T16:06:22.540935Z","steps":["trace[463609831] 'agreement among raft nodes before linearized reading' (duration: 105.27813ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:06:22.54136Z","caller":"traceutil/trace.go:171","msg":"trace[441137028] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"386.305923ms","start":"2026-04-14T16:06:22.155012Z","end":"2026-04-14T16:06:22.541318Z","steps":["trace[441137028] 'process raft request' (duration: 385.66503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:06:22.541611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:06:22.154969Z","time spent":"386.538207ms","remote":"127.0.0.1:41994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T16:06:22.541219Z","caller":"traceutil/trace.go:171","msg":"trace[1317911464] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"530.341547ms","start":"2026-04-14T16:06:22.010865Z","end":"2026-04-14T16:06:22.541206Z","steps":["trace[1317911464] 'process raft request' (duration: 414.048358ms)","trace[1317911464] 'compare' (duration: 115.078916ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:06:22.541791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:06:22.010852Z","time spent":"530.840456ms","remote":"127.0.0.1:42192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T16:06:23.04692Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340029353294963,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-14T16:06:23.260066Z","caller":"traceutil/trace.go:171","msg":"trace[824336842] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"713.940377ms","start":"2026-04-14T16:06:22.5461Z","end":"2026-04-14T16:06:23.26004Z","steps":["trace[824336842] 'process raft request' (duration: 713.810514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:06:23.260203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:06:22.546069Z","time spent":"714.064559ms","remote":"127.0.0.1:41994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T16:06:23.301889Z","caller":"traceutil/trace.go:171","msg":"trace[1113695768] linearizableReadLoop","detail":"{readStateIndex:1267; appliedIndex:1264; }","duration":"754.995602ms","start":"2026-04-14T16:06:22.54687Z","end":"2026-04-14T16:06:23.301866Z","steps":["trace[1113695768] 'read index received' (duration: 713.05985ms)","trace[1113695768] 'applied index is now lower than readState.Index' (duration: 41.934282ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T16:06:23.3019Z","caller":"traceutil/trace.go:171","msg":"trace[766588978] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"754.86485ms","start":"2026-04-14T16:06:22.546992Z","end":"2026-04-14T16:06:23.301857Z","steps":["trace[766588978] 'process raft request' (duration: 754.656397ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:06:23.301981Z","caller":"traceutil/trace.go:171","msg":"trace[1449831080] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"456.884467ms","start":"2026-04-14T16:06:22.84506Z","end":"2026-04-14T16:06:23.301945Z","steps":["trace[1449831080] 'process raft request' (duration: 456.703244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:06:23.302118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.222428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-04-14T16:06:23.302115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:06:22.54696Z","time spent":"755.044124ms","remote":"127.0.0.1:41994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T16:06:23.302167Z","caller":"traceutil/trace.go:171","msg":"trace[1209877505] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1225; }","duration":"755.316299ms","start":"2026-04-14T16:06:22.546839Z","end":"2026-04-14T16:06:23.302156Z","steps":["trace[1209877505] 'agreement among raft nodes before linearized reading' (duration: 755.129945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:06:23.302181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:06:22.845031Z","time spent":"457.03762ms","remote":"127.0.0.1:42192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T16:06:23.302204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:06:22.546824Z","time spent":"755.37162ms","remote":"127.0.0.1:42192","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-14T16:06:23.478993Z","caller":"traceutil/trace.go:171","msg":"trace[854100860] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"171.296979ms","start":"2026-04-14T16:06:23.307668Z","end":"2026-04-14T16:06:23.478965Z","steps":["trace[854100860] 'process raft request' (duration: 171.216437ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:06:23.479006Z","caller":"traceutil/trace.go:171","msg":"trace[363865457] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"205.458413ms","start":"2026-04-14T16:06:23.273502Z","end":"2026-04-14T16:06:23.478961Z","steps":["trace[363865457] 'process raft request' (duration: 116.079944ms)","trace[363865457] 'compare' (duration: 89.121444ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:06:23.47919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.708691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T16:06:23.479247Z","caller":"traceutil/trace.go:171","msg":"trace[1716040443] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1227; }","duration":"523.823803ms","start":"2026-04-14T16:06:22.95541Z","end":"2026-04-14T16:06:23.479234Z","steps":["trace[1716040443] 'agreement among raft nodes before linearized reading' (duration: 523.66538ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:06:23.479018Z","caller":"traceutil/trace.go:171","msg":"trace[292129183] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1267; }","duration":"177.042037ms","start":"2026-04-14T16:06:23.301944Z","end":"2026-04-14T16:06:23.478986Z","steps":["trace[292129183] 'read index received' (duration: 87.553486ms)","trace[292129183] 'applied index is now lower than readState.Index' (duration: 89.484991ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:06:23.479295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:06:22.95539Z","time spent":"523.894474ms","remote":"127.0.0.1:41928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T16:06:24.260534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.347695ms","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":"info","ts":"2026-04-14T16:06:24.260623Z","caller":"traceutil/trace.go:171","msg":"trace[746891981] 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:1232; }","duration":"111.548899ms","start":"2026-04-14T16:06:24.149052Z","end":"2026-04-14T16:06:24.260601Z","steps":["trace[746891981] 'count revisions from in-memory index tree' (duration: 111.187033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:06:34.934813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.267858068s","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":"warn","ts":"2026-04-14T16:06:34.934863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.620706ms","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-14T16:06:34.934905Z","caller":"traceutil/trace.go:171","msg":"trace[616228412] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1302; }","duration":"1.267985011s","start":"2026-04-14T16:06:33.666897Z","end":"2026-04-14T16:06:34.934882Z","steps":["trace[616228412] 'range keys from in-memory index tree' (duration: 1.267104505s)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:06:34.934943Z","caller":"traceutil/trace.go:171","msg":"trace[24360373] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1302; }","duration":"361.75956ms","start":"2026-04-14T16:06:34.57316Z","end":"2026-04-14T16:06:34.934919Z","steps":["trace[24360373] 'range keys from in-memory index tree' (duration: 361.407452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:06:34.93499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:06:34.573134Z","time spent":"361.846281ms","remote":"127.0.0.1:42192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-14T16:06:34.934951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:06:33.666884Z","time spent":"1.268058892s","remote":"127.0.0.1:42192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-14T16:06:34.93498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.609119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T16:06:34.935062Z","caller":"traceutil/trace.go:171","msg":"trace[1916416886] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1302; }","duration":"979.760182ms","start":"2026-04-14T16:06:33.955279Z","end":"2026-04-14T16:06:34.935039Z","steps":["trace[1916416886] 'range keys from in-memory index tree' (duration: 979.544749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:06:34.935115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:06:33.955257Z","time spent":"979.849853ms","remote":"127.0.0.1:41932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T16:06:34.93515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.068915098s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T16:06:34.935235Z","caller":"traceutil/trace.go:171","msg":"trace[404311534] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1302; }","duration":"1.06902437s","start":"2026-04-14T16:06:33.866196Z","end":"2026-04-14T16:06:34.93522Z","steps":["trace[404311534] 'count revisions from in-memory index tree' (duration: 1.068766415s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:06:34.935197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.823755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-14T16:06:34.935273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:06:33.866177Z","time spent":"1.069086311s","remote":"127.0.0.1:42000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-04-14T16:06:34.935293Z","caller":"traceutil/trace.go:171","msg":"trace[1170579245] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1302; }","duration":"914.005779ms","start":"2026-04-14T16:06:34.021268Z","end":"2026-04-14T16:06:34.935274Z","steps":["trace[1170579245] 'range keys from in-memory index tree' (duration: 913.542801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:06:34.935341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:06:34.021219Z","time spent":"914.109341ms","remote":"127.0.0.1:42192","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-14T16:06:35.119119Z","caller":"traceutil/trace.go:171","msg":"trace[1670725885] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"178.416993ms","start":"2026-04-14T16:06:34.940665Z","end":"2026-04-14T16:06:35.119082Z","steps":["trace[1670725885] 'process raft request' (duration: 178.127528ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:06:35.127152Z","caller":"traceutil/trace.go:171","msg":"trace[372353722] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"185.049998ms","start":"2026-04-14T16:06:34.942058Z","end":"2026-04-14T16:06:35.127108Z","steps":["trace[372353722] 'process raft request' (duration: 184.990837ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:06:35.127269Z","caller":"traceutil/trace.go:171","msg":"trace[1841066843] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"186.548567ms","start":"2026-04-14T16:06:34.940691Z","end":"2026-04-14T16:06:35.127239Z","steps":["trace[1841066843] 'process raft request' (duration: 186.18078ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:09:46.693625Z","caller":"traceutil/trace.go:171","msg":"trace[784138075] linearizableReadLoop","detail":"{readStateIndex:2297; appliedIndex:2296; }","duration":"119.816028ms","start":"2026-04-14T16:09:46.573787Z","end":"2026-04-14T16:09:46.693603Z","steps":["trace[784138075] 'read index received' (duration: 92.964528ms)","trace[784138075] 'applied index is now lower than readState.Index' (duration: 26.85061ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T16:09:46.69362Z","caller":"traceutil/trace.go:171","msg":"trace[55308723] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"165.406974ms","start":"2026-04-14T16:09:46.528182Z","end":"2026-04-14T16:09:46.693589Z","steps":["trace[55308723] 'process raft request' (duration: 138.556594ms)","trace[55308723] 'compare' (duration: 26.668156ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T16:09:46.693843Z","caller":"traceutil/trace.go:171","msg":"trace[969345886] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"116.732798ms","start":"2026-04-14T16:09:46.577093Z","end":"2026-04-14T16:09:46.693826Z","steps":["trace[969345886] 'process raft request' (duration: 116.458813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:09:46.694035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.336571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-14T16:09:46.694472Z","caller":"traceutil/trace.go:171","msg":"trace[752295882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2214; }","duration":"166.793061ms","start":"2026-04-14T16:09:46.527642Z","end":"2026-04-14T16:09:46.694435Z","steps":["trace[752295882] 'agreement among raft nodes before linearized reading' (duration: 166.054007ms)"],"step_count":1}