{"level":"warn","ts":"2026-07-23T04:56:13.477007Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-23T04:56:13.477157Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72: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.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-23T04:56:13.477257Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-23T04:56:13.477294Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-07-23T04:56:13.477335Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-23T04:56:13.477983Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-07-23T04:56:13.478124Z","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.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-23T04:56:13.481962Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.593945ms"} {"level":"info","ts":"2026-07-23T04:56:13.488283Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-07-23T04:56:13.488427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-07-23T04:56:13.488486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-07-23T04:56:13.488528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-23T04:56:13.488553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-07-23T04:56:13.488642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-07-23T04:56:13.494789Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-23T04:56:13.498081Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-23T04:56:13.49924Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-23T04:56:13.503763Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-23T04:56:13.504058Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-23T04:56:13.504158Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-23T04:56:13.504337Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-23T04:56:13.504348Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-23T04:56:13.506002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-07-23T04:56:13.506352Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-07-23T04:56:13.510162Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-23T04:56:13.510263Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-07-23T04:56:13.51032Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-07-23T04:56:13.510553Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-23T04:56:13.510601Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-23T04:56:14.189304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-07-23T04:56:14.189372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-07-23T04:56:14.189412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-07-23T04:56:14.189435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-07-23T04:56:14.189447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-07-23T04:56:14.189462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-07-23T04:56:14.189474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-07-23T04:56:14.190466Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T04:56:14.191002Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-07-23T04:56:14.191498Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T04:56:14.191776Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T04:56:14.191851Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T04:56:14.191873Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T04:56:14.191909Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-23T04:56:14.191925Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-23T04:56:14.191937Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T04:56:14.193719Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-07-23T04:56:14.195077Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-23T04:56:39.01958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.395956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-23T04:56:39.019696Z","caller":"traceutil/trace.go:171","msg":"trace[1971546641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:423; }","duration":"158.566372ms","start":"2026-07-23T04:56:38.86111Z","end":"2026-07-23T04:56:39.019677Z","steps":["trace[1971546641] 'range keys from in-memory index tree' (duration: 158.306463ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:56:48.520653Z","caller":"traceutil/trace.go:171","msg":"trace[921045398] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"140.250034ms","start":"2026-07-23T04:56:48.380384Z","end":"2026-07-23T04:56:48.520634Z","steps":["trace[921045398] 'process raft request' (duration: 140.154751ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:56:48.520914Z","caller":"traceutil/trace.go:171","msg":"trace[1130931543] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"140.187432ms","start":"2026-07-23T04:56:48.380664Z","end":"2026-07-23T04:56:48.520852Z","steps":["trace[1130931543] 'process raft request' (duration: 139.937714ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:56:48.520634Z","caller":"traceutil/trace.go:171","msg":"trace[639341051] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"147.477316ms","start":"2026-07-23T04:56:48.373126Z","end":"2026-07-23T04:56:48.520603Z","steps":["trace[639341051] 'process raft request' (duration: 77.766052ms)","trace[639341051] 'compare' (duration: 69.527998ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:56:48.548157Z","caller":"traceutil/trace.go:171","msg":"trace[1663907110] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"159.706108ms","start":"2026-07-23T04:56:48.388422Z","end":"2026-07-23T04:56:48.548128Z","steps":["trace[1663907110] 'process raft request' (duration: 159.426179ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:56:48.548211Z","caller":"traceutil/trace.go:171","msg":"trace[915794773] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"155.756661ms","start":"2026-07-23T04:56:48.392428Z","end":"2026-07-23T04:56:48.548185Z","steps":["trace[915794773] 'process raft request' (duration: 155.659868ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:56:48.548179Z","caller":"traceutil/trace.go:171","msg":"trace[1001597209] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"155.877635ms","start":"2026-07-23T04:56:48.392275Z","end":"2026-07-23T04:56:48.548153Z","steps":["trace[1001597209] 'process raft request' (duration: 155.73101ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:56:48.548775Z","caller":"traceutil/trace.go:171","msg":"trace[206898488] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"152.765005ms","start":"2026-07-23T04:56:48.395992Z","end":"2026-07-23T04:56:48.548757Z","steps":["trace[206898488] 'process raft request' (duration: 152.138395ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:56:48.549456Z","caller":"traceutil/trace.go:171","msg":"trace[720730860] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"120.249703ms","start":"2026-07-23T04:56:48.429191Z","end":"2026-07-23T04:56:48.549441Z","steps":["trace[720730860] 'process raft request' (duration: 119.033304ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:56:48.768983Z","caller":"traceutil/trace.go:171","msg":"trace[2005419224] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:489; }","duration":"219.550235ms","start":"2026-07-23T04:56:48.549413Z","end":"2026-07-23T04:56:48.768963Z","steps":["trace[2005419224] 'read index received' (duration: 130.7547ms)","trace[2005419224] 'applied index is now lower than readState.Index' (duration: 88.791365ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:56:48.768983Z","caller":"traceutil/trace.go:171","msg":"trace[1818850145] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"240.835387ms","start":"2026-07-23T04:56:48.52813Z","end":"2026-07-23T04:56:48.768965Z","steps":["trace[1818850145] 'process raft request' (duration: 152.038512ms)","trace[1818850145] 'compare' (duration: 88.602529ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:56:48.76903Z","caller":"traceutil/trace.go:171","msg":"trace[960460682] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"212.325423ms","start":"2026-07-23T04:56:48.556678Z","end":"2026-07-23T04:56:48.769003Z","steps":["trace[960460682] 'process raft request' (duration: 212.240501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:48.769154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.010335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-07-23T04:56:48.769179Z","caller":"traceutil/trace.go:171","msg":"trace[2078215073] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:471; }","duration":"242.083477ms","start":"2026-07-23T04:56:48.527087Z","end":"2026-07-23T04:56:48.769171Z","steps":["trace[2078215073] 'agreement among raft nodes before linearized reading' (duration: 241.980334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:48.769347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.779538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vxgjx\" ","response":"range_response_count:1 size:15076"} {"level":"info","ts":"2026-07-23T04:56:48.76938Z","caller":"traceutil/trace.go:171","msg":"trace[1767988666] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vxgjx; range_end:; response_count:1; response_revision:472; }","duration":"212.83739ms","start":"2026-07-23T04:56:48.556533Z","end":"2026-07-23T04:56:48.76937Z","steps":["trace[1767988666] 'agreement among raft nodes before linearized reading' (duration: 212.767838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:48.769529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.648451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14401"} {"level":"info","ts":"2026-07-23T04:56:48.769529Z","caller":"traceutil/trace.go:171","msg":"trace[97372910] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"209.700909ms","start":"2026-07-23T04:56:48.559821Z","end":"2026-07-23T04:56:48.769522Z","steps":["trace[97372910] 'process raft request' (duration: 209.145421ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:56:48.769548Z","caller":"traceutil/trace.go:171","msg":"trace[941096170] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:472; }","duration":"201.709394ms","start":"2026-07-23T04:56:48.567833Z","end":"2026-07-23T04:56:48.769543Z","steps":["trace[941096170] 'agreement among raft nodes before linearized reading' (duration: 201.643952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:48.769901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.237963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-07-23T04:56:48.76996Z","caller":"traceutil/trace.go:171","msg":"trace[2119835803] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:472; }","duration":"149.333946ms","start":"2026-07-23T04:56:48.620611Z","end":"2026-07-23T04:56:48.769945Z","steps":["trace[2119835803] 'agreement among raft nodes before linearized reading' (duration: 148.827589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:49.463844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.846559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T04:56:49.464118Z","caller":"traceutil/trace.go:171","msg":"trace[1474074894] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"613.281452ms","start":"2026-07-23T04:56:48.85081Z","end":"2026-07-23T04:56:49.464091Z","steps":["trace[1474074894] 'process raft request' (duration: 448.4324ms)","trace[1474074894] 'compare' (duration: 163.711995ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:56:49.464135Z","caller":"traceutil/trace.go:171","msg":"trace[555102367] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:496; }","duration":"602.898539ms","start":"2026-07-23T04:56:48.861046Z","end":"2026-07-23T04:56:49.463945Z","steps":["trace[555102367] 'read index received' (duration: 438.12898ms)","trace[555102367] 'applied index is now lower than readState.Index' (duration: 164.768319ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T04:56:49.464251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.182618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vxgjx\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-07-23T04:56:49.464293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.420252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-23T04:56:49.464255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:48.850792Z","time spent":"613.392896ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T04:56:49.46433Z","caller":"traceutil/trace.go:171","msg":"trace[432024908] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:477; }","duration":"401.503226ms","start":"2026-07-23T04:56:49.062816Z","end":"2026-07-23T04:56:49.46432Z","steps":["trace[432024908] 'agreement among raft nodes before linearized reading' (duration: 401.420973ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:56:49.46433Z","caller":"traceutil/trace.go:171","msg":"trace[256897958] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vxgjx; range_end:; response_count:1; response_revision:477; }","duration":"603.299672ms","start":"2026-07-23T04:56:48.861012Z","end":"2026-07-23T04:56:49.464312Z","steps":["trace[256897958] 'agreement among raft nodes before linearized reading' (duration: 603.153257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:49.464366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:49.062798Z","time spent":"401.559717ms","remote":"127.0.0.1:33736","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-07-23T04:56:49.464373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:48.860997Z","time spent":"603.368114ms","remote":"127.0.0.1:33650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-vxgjx\" "} {"level":"warn","ts":"2026-07-23T04:56:49.464963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.042372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-07-23T04:56:49.465056Z","caller":"traceutil/trace.go:171","msg":"trace[422238825] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:477; }","duration":"602.138085ms","start":"2026-07-23T04:56:48.862899Z","end":"2026-07-23T04:56:49.465037Z","steps":["trace[422238825] 'agreement among raft nodes before linearized reading' (duration: 601.340439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:49.465135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:48.862879Z","time spent":"602.243218ms","remote":"127.0.0.1:33552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329974,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-07-23T04:56:51.27729Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221364729656389,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-23T04:56:51.314279Z","caller":"traceutil/trace.go:171","msg":"trace[1793726848] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"585.839252ms","start":"2026-07-23T04:56:50.728344Z","end":"2026-07-23T04:56:51.314183Z","steps":["trace[1793726848] 'process raft request' (duration: 585.741749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:51.314404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:50.728315Z","time spent":"586.028308ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T04:56:51.320839Z","caller":"traceutil/trace.go:171","msg":"trace[1813593338] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"576.435721ms","start":"2026-07-23T04:56:50.744385Z","end":"2026-07-23T04:56:51.320821Z","steps":["trace[1813593338] 'process raft request' (duration: 576.177302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:51.320933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:50.744382Z","time spent":"576.511673ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T04:56:51.321169Z","caller":"traceutil/trace.go:171","msg":"trace[2064605824] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"588.768346ms","start":"2026-07-23T04:56:50.732389Z","end":"2026-07-23T04:56:51.321157Z","steps":["trace[2064605824] 'process raft request' (duration: 588.033613ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:51.321327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:50.732374Z","time spent":"588.827188ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T04:56:51.321379Z","caller":"traceutil/trace.go:171","msg":"trace[1903867752] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"572.242977ms","start":"2026-07-23T04:56:50.749106Z","end":"2026-07-23T04:56:51.321349Z","steps":["trace[1903867752] 'process raft request' (duration: 571.605416ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:51.321506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:50.749099Z","time spent":"572.34182ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T04:56:51.321517Z","caller":"traceutil/trace.go:171","msg":"trace[1538493306] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"562.907837ms","start":"2026-07-23T04:56:50.758593Z","end":"2026-07-23T04:56:51.321501Z","steps":["trace[1538493306] 'process raft request' (duration: 562.163034ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:56:51.321531Z","caller":"traceutil/trace.go:171","msg":"trace[180514576] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"584.562611ms","start":"2026-07-23T04:56:50.736958Z","end":"2026-07-23T04:56:51.32152Z","steps":["trace[180514576] 'process raft request' (duration: 583.523918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:51.321581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:50.736941Z","time spent":"584.617493ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T04:56:51.321621Z","caller":"traceutil/trace.go:171","msg":"trace[554510592] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"532.547414ms","start":"2026-07-23T04:56:50.789066Z","end":"2026-07-23T04:56:51.321613Z","steps":["trace[554510592] 'process raft request' (duration: 531.736438ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:56:51.321668Z","caller":"traceutil/trace.go:171","msg":"trace[1004408876] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"577.32626ms","start":"2026-07-23T04:56:50.744332Z","end":"2026-07-23T04:56:51.321658Z","steps":["trace[1004408876] 'process raft request' (duration: 576.197673ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:56:51.321696Z","caller":"traceutil/trace.go:171","msg":"trace[1094125003] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"420.320279ms","start":"2026-07-23T04:56:50.901369Z","end":"2026-07-23T04:56:51.321689Z","steps":["trace[1094125003] 'process raft request' (duration: 419.904815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:51.321715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:50.744315Z","time spent":"577.382111ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T04:56:51.321721Z","caller":"traceutil/trace.go:171","msg":"trace[679788803] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"577.223776ms","start":"2026-07-23T04:56:50.74448Z","end":"2026-07-23T04:56:51.321704Z","steps":["trace[679788803] 'process raft request' (duration: 576.124871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:51.321728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:50.90134Z","time spent":"420.37392ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T04:56:51.321775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:50.744467Z","time spent":"577.283597ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T04:56:51.321803Z","caller":"traceutil/trace.go:171","msg":"trace[1724956397] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:503; }","duration":"545.51563ms","start":"2026-07-23T04:56:50.776278Z","end":"2026-07-23T04:56:51.321794Z","steps":["trace[1724956397] 'read index received' (duration: 537.868585ms)","trace[1724956397] 'applied index is now lower than readState.Index' (duration: 7.646005ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:56:51.321856Z","caller":"traceutil/trace.go:171","msg":"trace[557965001] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"575.43929ms","start":"2026-07-23T04:56:50.746407Z","end":"2026-07-23T04:56:51.321846Z","steps":["trace[557965001] 'process raft request' (duration: 574.231801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:51.321893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.608343ms","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-07-23T04:56:51.321898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:50.7464Z","time spent":"575.480941ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T04:56:51.321923Z","caller":"traceutil/trace.go:171","msg":"trace[485815569] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:496; }","duration":"545.657255ms","start":"2026-07-23T04:56:50.776257Z","end":"2026-07-23T04:56:51.321914Z","steps":["trace[485815569] 'agreement among raft nodes before linearized reading' (duration: 545.572802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:51.321957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:50.776244Z","time spent":"545.706826ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-23T04:56:51.321969Z","caller":"traceutil/trace.go:171","msg":"trace[1138725090] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"574.166338ms","start":"2026-07-23T04:56:50.747793Z","end":"2026-07-23T04:56:51.321959Z","steps":["trace[1138725090] 'process raft request' (duration: 572.881877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:51.322013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:50.747787Z","time spent":"574.208249ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T04:56:51.321668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:50.789051Z","time spent":"532.599046ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T04:56:51.321586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:50.758581Z","time spent":"562.971279ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T04:56:57.561842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.374398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-23T04:56:57.561945Z","caller":"traceutil/trace.go:171","msg":"trace[1703919825] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:588; }","duration":"390.529833ms","start":"2026-07-23T04:56:57.171395Z","end":"2026-07-23T04:56:57.561925Z","steps":["trace[1703919825] 'range keys from in-memory index tree' (duration: 390.281496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:57.562003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:57.171377Z","time spent":"390.616927ms","remote":"127.0.0.1:33476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"info","ts":"2026-07-23T04:56:57.736309Z","caller":"traceutil/trace.go:171","msg":"trace[1239076088] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"405.890767ms","start":"2026-07-23T04:56:57.330399Z","end":"2026-07-23T04:56:57.736289Z","steps":["trace[1239076088] 'process raft request' (duration: 405.05817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:57.736453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:57.330363Z","time spent":"406.010411ms","remote":"127.0.0.1:33552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T04:56:57.736269Z","caller":"traceutil/trace.go:171","msg":"trace[721650412] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"348.801067ms","start":"2026-07-23T04:56:57.387411Z","end":"2026-07-23T04:56:57.736212Z","steps":["trace[721650412] 'read index received' (duration: 347.667891ms)","trace[721650412] 'applied index is now lower than readState.Index' (duration: 1.132116ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T04:56:57.73655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.126298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-23T04:56:57.7368Z","caller":"traceutil/trace.go:171","msg":"trace[1998856154] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:589; }","duration":"349.273582ms","start":"2026-07-23T04:56:57.387356Z","end":"2026-07-23T04:56:57.73663Z","steps":["trace[1998856154] 'agreement among raft nodes before linearized reading' (duration: 348.999713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:57.73765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:57.387331Z","time spent":"349.557751ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-23T04:56:58.059413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.539786ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-07-23T04:56:58.059532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:57.563626Z","time spent":"495.897131ms","remote":"127.0.0.1:33476","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-23T04:56:58.059689Z","caller":"traceutil/trace.go:171","msg":"trace[1217541994] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"262.78347ms","start":"2026-07-23T04:56:57.796885Z","end":"2026-07-23T04:56:58.059668Z","steps":["trace[1217541994] 'process raft request' (duration: 262.739009ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:56:58.059727Z","caller":"traceutil/trace.go:171","msg":"trace[1994688148] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"319.093255ms","start":"2026-07-23T04:56:57.740596Z","end":"2026-07-23T04:56:58.05969Z","steps":["trace[1994688148] 'process raft request' (duration: 318.914989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:58.059887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:57.740574Z","time spent":"319.206019ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T04:56:58.059674Z","caller":"traceutil/trace.go:171","msg":"trace[1780238266] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"323.042941ms","start":"2026-07-23T04:56:57.736592Z","end":"2026-07-23T04:56:58.059635Z","steps":["trace[1780238266] 'read index received' (duration: 78.369511ms)","trace[1780238266] 'applied index is now lower than readState.Index' (duration: 244.66909ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T04:56:58.060139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.770591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-23T04:56:58.060164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.018221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T04:56:58.060207Z","caller":"traceutil/trace.go:171","msg":"trace[780603186] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"382.111964ms","start":"2026-07-23T04:56:57.678082Z","end":"2026-07-23T04:56:58.060194Z","steps":["trace[780603186] 'agreement among raft nodes before linearized reading' (duration: 382.033012ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:56:58.060225Z","caller":"traceutil/trace.go:171","msg":"trace[2054254177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"556.874475ms","start":"2026-07-23T04:56:57.503303Z","end":"2026-07-23T04:56:58.060178Z","steps":["trace[2054254177] 'agreement among raft nodes before linearized reading' (duration: 556.460101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:58.06024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:57.678064Z","time spent":"382.169116ms","remote":"127.0.0.1:33452","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-07-23T04:56:58.060286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.956208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vxgjx\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-07-23T04:56:58.060306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:57.503291Z","time spent":"556.974897ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-23T04:56:58.060334Z","caller":"traceutil/trace.go:171","msg":"trace[1723110135] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vxgjx; range_end:; response_count:1; response_revision:591; }","duration":"166.03574ms","start":"2026-07-23T04:56:57.894282Z","end":"2026-07-23T04:56:58.060318Z","steps":["trace[1723110135] 'agreement among raft nodes before linearized reading' (duration: 165.927777ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:58.060329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.960548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-23T04:56:58.060386Z","caller":"traceutil/trace.go:171","msg":"trace[1325042755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"557.055781ms","start":"2026-07-23T04:56:57.503309Z","end":"2026-07-23T04:56:58.060365Z","steps":["trace[1325042755] 'agreement among raft nodes before linearized reading' (duration: 556.849424ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:58.060426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:57.503251Z","time spent":"557.167524ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-23T04:56:59.035757Z","caller":"traceutil/trace.go:171","msg":"trace[536924093] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:617; }","duration":"962.44495ms","start":"2026-07-23T04:56:58.073289Z","end":"2026-07-23T04:56:59.035734Z","steps":["trace[536924093] 'read index received' (duration: 45.754776ms)","trace[536924093] 'applied index is now lower than readState.Index' (duration: 916.689284ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:56:59.035818Z","caller":"traceutil/trace.go:171","msg":"trace[1631654920] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"970.88282ms","start":"2026-07-23T04:56:58.064869Z","end":"2026-07-23T04:56:59.035752Z","steps":["trace[1631654920] 'process raft request' (duration: 898.298965ms)","trace[1631654920] 'compare' (duration: 72.329627ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:56:59.035878Z","caller":"traceutil/trace.go:171","msg":"trace[755686557] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"970.581111ms","start":"2026-07-23T04:56:58.065286Z","end":"2026-07-23T04:56:59.035867Z","steps":["trace[755686557] 'process raft request' (duration: 970.357334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:59.035962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:58.064845Z","time spent":"971.038475ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T04:56:59.035983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:58.065275Z","time spent":"970.631712ms","remote":"127.0.0.1:33736","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-07-23T04:56:59.035993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.700198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T04:56:59.03607Z","caller":"traceutil/trace.go:171","msg":"trace[515753374] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:595; }","duration":"962.806622ms","start":"2026-07-23T04:56:58.073249Z","end":"2026-07-23T04:56:59.036055Z","steps":["trace[515753374] 'agreement among raft nodes before linearized reading' (duration: 962.707479ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:59.036103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:58.073231Z","time spent":"962.864833ms","remote":"127.0.0.1:33706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-23T04:56:59.036168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.948848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-07-23T04:56:59.036199Z","caller":"traceutil/trace.go:171","msg":"trace[603645761] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:595; }","duration":"915.01027ms","start":"2026-07-23T04:56:58.12118Z","end":"2026-07-23T04:56:59.03619Z","steps":["trace[603645761] 'agreement among raft nodes before linearized reading' (duration: 914.953928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:59.036227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:58.121164Z","time spent":"915.057141ms","remote":"127.0.0.1:33626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":443,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-07-23T04:56:59.036395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.171608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T04:56:59.036421Z","caller":"traceutil/trace.go:171","msg":"trace[444137857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"358.234739ms","start":"2026-07-23T04:56:58.678178Z","end":"2026-07-23T04:56:59.036413Z","steps":["trace[444137857] 'agreement among raft nodes before linearized reading' (duration: 358.195508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:59.036445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:58.678155Z","time spent":"358.28497ms","remote":"127.0.0.1:33438","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-07-23T04:56:59.036506Z","caller":"traceutil/trace.go:171","msg":"trace[2053275419] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"961.436727ms","start":"2026-07-23T04:56:58.075039Z","end":"2026-07-23T04:56:59.036476Z","steps":["trace[2053275419] 'process raft request' (duration: 960.645432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:59.036553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.65137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-23T04:56:59.036615Z","caller":"traceutil/trace.go:171","msg":"trace[1709016260] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"719.753563ms","start":"2026-07-23T04:56:58.316844Z","end":"2026-07-23T04:56:59.036597Z","steps":["trace[1709016260] 'agreement among raft nodes before linearized reading' (duration: 719.64237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:56:59.036666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:58.31682Z","time spent":"719.833306ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-23T04:56:59.036674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:58.075023Z","time spent":"961.545161ms","remote":"127.0.0.1:33650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T04:57:00.453062Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.018252538s","expected-duration":"1s"} {"level":"info","ts":"2026-07-23T04:57:00.454202Z","caller":"traceutil/trace.go:171","msg":"trace[1475976159] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"1.027018739s","start":"2026-07-23T04:56:59.427158Z","end":"2026-07-23T04:57:00.454177Z","steps":["trace[1475976159] 'process raft request' (duration: 1.02644987s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:57:00.454398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:59.427112Z","time spent":"1.027164633s","remote":"127.0.0.1:33552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T04:57:00.455599Z","caller":"traceutil/trace.go:171","msg":"trace[1051662571] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:622; }","duration":"390.644477ms","start":"2026-07-23T04:57:00.064931Z","end":"2026-07-23T04:57:00.455575Z","steps":["trace[1051662571] 'read index received' (duration: 388.413586ms)","trace[1051662571] 'applied index is now lower than readState.Index' (duration: 2.230021ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T04:57:00.455776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.821483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-23T04:57:00.455805Z","caller":"traceutil/trace.go:171","msg":"trace[1942515772] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:598; }","duration":"390.900795ms","start":"2026-07-23T04:57:00.064895Z","end":"2026-07-23T04:57:00.455796Z","steps":["trace[1942515772] 'agreement among raft nodes before linearized reading' (duration: 390.756631ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:57:00.455839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:57:00.064873Z","time spent":"390.959938ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-23T04:57:00.456006Z","caller":"traceutil/trace.go:171","msg":"trace[1863210309] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"728.68877ms","start":"2026-07-23T04:56:59.72728Z","end":"2026-07-23T04:57:00.455969Z","steps":["trace[1863210309] 'process raft request' (duration: 728.184874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:57:00.456189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:56:59.727252Z","time spent":"728.860346ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T04:57:05.387192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.555575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-23T04:57:05.387262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.467302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-23T04:57:05.387281Z","caller":"traceutil/trace.go:171","msg":"trace[1119878875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:675; }","duration":"307.677979ms","start":"2026-07-23T04:57:05.079585Z","end":"2026-07-23T04:57:05.387263Z","steps":["trace[1119878875] 'range keys from in-memory index tree' (duration: 307.38649ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:57:05.387335Z","caller":"traceutil/trace.go:171","msg":"trace[652886477] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:675; }","duration":"307.586426ms","start":"2026-07-23T04:57:05.079729Z","end":"2026-07-23T04:57:05.387316Z","steps":["trace[652886477] 'range keys from in-memory index tree' (duration: 307.277506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:57:05.387341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:57:05.079578Z","time spent":"307.752082ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-23T04:57:05.387386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:57:05.079709Z","time spent":"307.666958ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-23T04:57:05.387254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.229998ms","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-07-23T04:57:05.387557Z","caller":"traceutil/trace.go:171","msg":"trace[1421378464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:675; }","duration":"309.560659ms","start":"2026-07-23T04:57:05.07796Z","end":"2026-07-23T04:57:05.387521Z","steps":["trace[1421378464] 'range keys from in-memory index tree' (duration: 308.989301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:57:05.387676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:57:05.077941Z","time spent":"309.714084ms","remote":"127.0.0.1:33736","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":"info","ts":"2026-07-23T04:57:05.393935Z","caller":"traceutil/trace.go:171","msg":"trace[1719337505] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"124.870032ms","start":"2026-07-23T04:57:05.269044Z","end":"2026-07-23T04:57:05.393914Z","steps":["trace[1719337505] 'process raft request' (duration: 124.660555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:57:14.52167Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221364729656917,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-23T04:57:14.532497Z","caller":"traceutil/trace.go:171","msg":"trace[261226790] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:773; }","duration":"510.87934ms","start":"2026-07-23T04:57:14.021589Z","end":"2026-07-23T04:57:14.532468Z","steps":["trace[261226790] 'read index received' (duration: 510.047864ms)","trace[261226790] 'applied index is now lower than readState.Index' (duration: 829.956µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:57:14.532555Z","caller":"traceutil/trace.go:171","msg":"trace[2082576407] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"534.029112ms","start":"2026-07-23T04:57:13.998486Z","end":"2026-07-23T04:57:14.532515Z","steps":["trace[2082576407] 'process raft request' (duration: 533.164364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:57:14.532673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.074947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T04:57:14.532744Z","caller":"traceutil/trace.go:171","msg":"trace[1684088293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:744; }","duration":"511.199461ms","start":"2026-07-23T04:57:14.021533Z","end":"2026-07-23T04:57:14.532732Z","steps":["trace[1684088293] 'agreement among raft nodes before linearized reading' (duration: 511.081787ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:57:14.532791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:57:14.021512Z","time spent":"511.270833ms","remote":"127.0.0.1:33438","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-07-23T04:57:14.532956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:57:13.998474Z","time spent":"534.386564ms","remote":"127.0.0.1:33650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4856,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T04:57:17.595819Z","caller":"traceutil/trace.go:171","msg":"trace[372775501] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:793; }","duration":"423.117193ms","start":"2026-07-23T04:57:17.172677Z","end":"2026-07-23T04:57:17.595794Z","steps":["trace[372775501] 'read index received' (duration: 422.885236ms)","trace[372775501] 'applied index is now lower than readState.Index' (duration: 230.887µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:57:17.595941Z","caller":"traceutil/trace.go:171","msg":"trace[661756642] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"584.217803ms","start":"2026-07-23T04:57:17.011713Z","end":"2026-07-23T04:57:17.595931Z","steps":["trace[661756642] 'process raft request' (duration: 583.927624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:57:17.596086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:57:17.011688Z","time spent":"584.281946ms","remote":"127.0.0.1:33650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T04:57:17.59611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.936874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T04:57:17.596221Z","caller":"traceutil/trace.go:171","msg":"trace[1818079794] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:764; }","duration":"328.077438ms","start":"2026-07-23T04:57:17.268094Z","end":"2026-07-23T04:57:17.596171Z","steps":["trace[1818079794] 'agreement among raft nodes before linearized reading' (duration: 327.903383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:57:17.596257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.326308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-23T04:57:17.596318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:57:17.268073Z","time spent":"328.227263ms","remote":"127.0.0.1:33952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-07-23T04:57:17.596346Z","caller":"traceutil/trace.go:171","msg":"trace[54937924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:764; }","duration":"142.436352ms","start":"2026-07-23T04:57:17.453886Z","end":"2026-07-23T04:57:17.596322Z","steps":["trace[54937924] 'agreement among raft nodes before linearized reading' (duration: 142.208265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:57:17.596355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.65267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-23T04:57:17.596446Z","caller":"traceutil/trace.go:171","msg":"trace[624695047] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:764; }","duration":"423.804415ms","start":"2026-07-23T04:57:17.172622Z","end":"2026-07-23T04:57:17.596426Z","steps":["trace[624695047] 'agreement among raft nodes before linearized reading' (duration: 423.503626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:57:17.596497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:57:17.172602Z","time spent":"423.885677ms","remote":"127.0.0.1:33476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"warn","ts":"2026-07-23T04:57:17.596698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.68168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-23T04:57:17.59681Z","caller":"traceutil/trace.go:171","msg":"trace[1425381750] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:764; }","duration":"147.814334ms","start":"2026-07-23T04:57:17.448955Z","end":"2026-07-23T04:57:17.596769Z","steps":["trace[1425381750] 'agreement among raft nodes before linearized reading' (duration: 147.266697ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:57:17.597157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.280608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-23T04:57:17.597276Z","caller":"traceutil/trace.go:171","msg":"trace[1810110550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:764; }","duration":"143.416393ms","start":"2026-07-23T04:57:17.453843Z","end":"2026-07-23T04:57:17.597259Z","steps":["trace[1810110550] 'agreement among raft nodes before linearized reading' (duration: 143.069672ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:57:21.537386Z","caller":"traceutil/trace.go:171","msg":"trace[814052873] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"178.822448ms","start":"2026-07-23T04:57:21.358523Z","end":"2026-07-23T04:57:21.537346Z","steps":["trace[814052873] 'process raft request' (duration: 101.330186ms)","trace[814052873] 'compare' (duration: 77.233204ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:57:25.301112Z","caller":"traceutil/trace.go:171","msg":"trace[405908122] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"181.156883ms","start":"2026-07-23T04:57:25.119929Z","end":"2026-07-23T04:57:25.301086Z","steps":["trace[405908122] 'process raft request' (duration: 130.900883ms)","trace[405908122] 'compare' (duration: 50.129746ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:57:25.321427Z","caller":"traceutil/trace.go:171","msg":"trace[1217077635] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"200.570804ms","start":"2026-07-23T04:57:25.120832Z","end":"2026-07-23T04:57:25.321402Z","steps":["trace[1217077635] 'process raft request' (duration: 200.376158ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:57:25.321746Z","caller":"traceutil/trace.go:171","msg":"trace[914004120] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"185.351738ms","start":"2026-07-23T04:57:25.136375Z","end":"2026-07-23T04:57:25.321727Z","steps":["trace[914004120] 'process raft request' (duration: 184.973896ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:57:25.481598Z","caller":"traceutil/trace.go:171","msg":"trace[1618444862] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"114.449316ms","start":"2026-07-23T04:57:25.367124Z","end":"2026-07-23T04:57:25.481573Z","steps":["trace[1618444862] 'process raft request' (duration: 88.603548ms)","trace[1618444862] 'compare' (duration: 25.674303ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:57:25.516337Z","caller":"traceutil/trace.go:171","msg":"trace[859007464] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"147.540866ms","start":"2026-07-23T04:57:25.368778Z","end":"2026-07-23T04:57:25.516319Z","steps":["trace[859007464] 'process raft request' (duration: 147.434143ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:57:25.668067Z","caller":"traceutil/trace.go:171","msg":"trace[220295606] linearizableReadLoop","detail":"{readStateIndex:875; appliedIndex:874; }","duration":"124.153696ms","start":"2026-07-23T04:57:25.543887Z","end":"2026-07-23T04:57:25.668041Z","steps":["trace[220295606] 'read index received' (duration: 95.655313ms)","trace[220295606] 'applied index is now lower than readState.Index' (duration: 28.497143ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:57:25.668123Z","caller":"traceutil/trace.go:171","msg":"trace[1365264002] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"142.125822ms","start":"2026-07-23T04:57:25.52595Z","end":"2026-07-23T04:57:25.668076Z","steps":["trace[1365264002] 'process raft request' (duration: 113.703792ms)","trace[1365264002] 'compare' (duration: 28.256355ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T04:57:25.668242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.308642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"warn","ts":"2026-07-23T04:57:25.668275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.342463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-07-23T04:57:25.668346Z","caller":"traceutil/trace.go:171","msg":"trace[60859138] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:842; }","duration":"124.489258ms","start":"2026-07-23T04:57:25.543837Z","end":"2026-07-23T04:57:25.668327Z","steps":["trace[60859138] 'agreement among raft nodes before linearized reading' (duration: 124.303782ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:57:25.668374Z","caller":"traceutil/trace.go:171","msg":"trace[1925265181] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:842; }","duration":"124.478518ms","start":"2026-07-23T04:57:25.543881Z","end":"2026-07-23T04:57:25.66836Z","steps":["trace[1925265181] 'agreement among raft nodes before linearized reading' (duration: 124.342263ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:57:30.960737Z","caller":"traceutil/trace.go:171","msg":"trace[1402274424] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"101.986187ms","start":"2026-07-23T04:57:30.858731Z","end":"2026-07-23T04:57:30.960717Z","steps":["trace[1402274424] 'process raft request' (duration: 101.852442ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:57:37.129533Z","caller":"traceutil/trace.go:171","msg":"trace[537560823] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"136.516483ms","start":"2026-07-23T04:57:36.992875Z","end":"2026-07-23T04:57:37.129391Z","steps":["trace[537560823] 'process raft request' (duration: 118.429024ms)","trace[537560823] 'compare' (duration: 17.697837ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T04:57:53.050606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.534348ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-23T04:57:53.050732Z","caller":"traceutil/trace.go:171","msg":"trace[832206478] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1017; }","duration":"374.250958ms","start":"2026-07-23T04:57:52.676468Z","end":"2026-07-23T04:57:53.050719Z","steps":["trace[832206478] 'read index received' (duration: 245.515954ms)","trace[832206478] 'applied index is now lower than readState.Index' (duration: 128.733804ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T04:57:53.050828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.357412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T04:57:53.050853Z","caller":"traceutil/trace.go:171","msg":"trace[1099121828] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:979; }","duration":"374.388223ms","start":"2026-07-23T04:57:52.676456Z","end":"2026-07-23T04:57:53.050844Z","steps":["trace[1099121828] 'agreement among raft nodes before linearized reading' (duration: 374.335362ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:57:53.050876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:57:52.676409Z","time spent":"374.461635ms","remote":"127.0.0.1:33438","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-07-23T04:57:53.050972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.078181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T04:57:53.05104Z","caller":"traceutil/trace.go:171","msg":"trace[806066440] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:979; }","duration":"309.187374ms","start":"2026-07-23T04:57:52.741836Z","end":"2026-07-23T04:57:53.051023Z","steps":["trace[806066440] 'agreement among raft nodes before linearized reading' (duration: 309.07577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:57:53.051081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:57:52.741811Z","time spent":"309.259347ms","remote":"127.0.0.1:33772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-07-23T04:57:54.125619Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.057645938s","expected-duration":"1s"} {"level":"info","ts":"2026-07-23T04:57:54.126003Z","caller":"traceutil/trace.go:171","msg":"trace[1435349724] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"1.058073062s","start":"2026-07-23T04:57:53.067902Z","end":"2026-07-23T04:57:54.125975Z","steps":["trace[1435349724] 'process raft request' (duration: 1.057895746s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:57:54.126172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:57:53.067889Z","time spent":"1.058196126s","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T04:57:54.243483Z","caller":"traceutil/trace.go:171","msg":"trace[1826823409] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1018; }","duration":"562.016003ms","start":"2026-07-23T04:57:53.681445Z","end":"2026-07-23T04:57:54.243461Z","steps":["trace[1826823409] 'read index received' (duration: 444.434767ms)","trace[1826823409] 'applied index is now lower than readState.Index' (duration: 117.580576ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:57:54.243582Z","caller":"traceutil/trace.go:171","msg":"trace[1157608389] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"1.022081909s","start":"2026-07-23T04:57:53.22149Z","end":"2026-07-23T04:57:54.243572Z","steps":["trace[1157608389] 'process raft request' (duration: 1.020501268s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:57:54.243698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:57:53.22146Z","time spent":"1.022143961s","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T04:57:54.243815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.149457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T04:57:54.243893Z","caller":"traceutil/trace.go:171","msg":"trace[59302046] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:981; }","duration":"385.268961ms","start":"2026-07-23T04:57:53.858605Z","end":"2026-07-23T04:57:54.243874Z","steps":["trace[59302046] 'agreement among raft nodes before linearized reading' (duration: 385.145348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:57:54.243972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:57:53.858589Z","time spent":"385.371824ms","remote":"127.0.0.1:37844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-07-23T04:57:54.244122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.912431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-23T04:57:54.244154Z","caller":"traceutil/trace.go:171","msg":"trace[1743843907] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:981; }","duration":"198.959913ms","start":"2026-07-23T04:57:54.045186Z","end":"2026-07-23T04:57:54.244146Z","steps":["trace[1743843907] 'agreement among raft nodes before linearized reading' (duration: 198.84613ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:57:54.244262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.817079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T04:57:54.244284Z","caller":"traceutil/trace.go:171","msg":"trace[158607452] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:981; }","duration":"562.86633ms","start":"2026-07-23T04:57:53.681412Z","end":"2026-07-23T04:57:54.244278Z","steps":["trace[158607452] 'agreement among raft nodes before linearized reading' (duration: 562.830939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:57:54.244288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.434538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-23T04:57:54.244313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:57:53.681394Z","time spent":"562.914861ms","remote":"127.0.0.1:33438","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-07-23T04:57:54.244324Z","caller":"traceutil/trace.go:171","msg":"trace[1093795053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:981; }","duration":"214.510702ms","start":"2026-07-23T04:57:54.029804Z","end":"2026-07-23T04:57:54.244314Z","steps":["trace[1093795053] 'agreement among raft nodes before linearized reading' (duration: 214.383828ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:57:54.244441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.335549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-23T04:57:54.244459Z","caller":"traceutil/trace.go:171","msg":"trace[23812070] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:981; }","duration":"152.401011ms","start":"2026-07-23T04:57:54.092052Z","end":"2026-07-23T04:57:54.244453Z","steps":["trace[23812070] 'agreement among raft nodes before linearized reading' (duration: 152.346609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:57:54.24465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.199183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-23T04:57:54.244673Z","caller":"traceutil/trace.go:171","msg":"trace[882008148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:981; }","duration":"215.246965ms","start":"2026-07-23T04:57:54.029421Z","end":"2026-07-23T04:57:54.244668Z","steps":["trace[882008148] 'agreement among raft nodes before linearized reading' (duration: 215.184733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:57:54.811519Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221364729657425,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T04:57:55.257691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.191871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T04:57:55.258005Z","caller":"traceutil/trace.go:171","msg":"trace[1567309060] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1020; }","duration":"948.556345ms","start":"2026-07-23T04:57:54.309432Z","end":"2026-07-23T04:57:55.257988Z","steps":["trace[1567309060] 'read index received' (duration: 581.933211ms)","trace[1567309060] 'applied index is now lower than readState.Index' (duration: 366.622264ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:57:55.258105Z","caller":"traceutil/trace.go:171","msg":"trace[520831487] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"1.007010678s","start":"2026-07-23T04:57:54.251086Z","end":"2026-07-23T04:57:55.258097Z","steps":["trace[520831487] 'process raft request' (duration: 1.006834362s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:57:55.258161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:57:54.251067Z","time spent":"1.00706732s","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T04:57:55.25815Z","caller":"traceutil/trace.go:171","msg":"trace[132814692] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"1.007867945s","start":"2026-07-23T04:57:54.250241Z","end":"2026-07-23T04:57:55.258109Z","steps":["trace[132814692] 'process raft request' (duration: 641.145447ms)","trace[132814692] 'compare' (duration: 365.947633ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:57:55.258247Z","caller":"traceutil/trace.go:171","msg":"trace[625255250] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"1.007430961s","start":"2026-07-23T04:57:54.25077Z","end":"2026-07-23T04:57:55.258201Z","steps":["trace[625255250] 'process raft request' (duration: 1.007042228s)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:57:55.258363Z","caller":"traceutil/trace.go:171","msg":"trace[24590526] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"1.007291437s","start":"2026-07-23T04:57:54.251064Z","end":"2026-07-23T04:57:55.258355Z","steps":["trace[24590526] 'process raft request' (duration: 1.006895944s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:57:55.258561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:57:54.250222Z","time spent":"1.008041561s","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T04:57:55.258599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:57:54.251022Z","time spent":"1.007554915s","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T04:57:55.258649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:57:54.250762Z","time spent":"1.007587817s","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T04:57:55.258737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.31444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T04:57:55.258766Z","caller":"traceutil/trace.go:171","msg":"trace[1789101620] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:985; }","duration":"949.379192ms","start":"2026-07-23T04:57:54.309378Z","end":"2026-07-23T04:57:55.258757Z","steps":["trace[1789101620] 'agreement among raft nodes before linearized reading' (duration: 949.301479ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:57:55.25879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:57:54.309361Z","time spent":"949.424274ms","remote":"127.0.0.1:33600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-07-23T04:57:55.258889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.558152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T04:57:55.258977Z","caller":"traceutil/trace.go:171","msg":"trace[1856297814] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:985; }","duration":"897.697596ms","start":"2026-07-23T04:57:54.361255Z","end":"2026-07-23T04:57:55.258952Z","steps":["trace[1856297814] 'agreement among raft nodes before linearized reading' (duration: 897.564682ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:57:55.259036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:57:54.361231Z","time spent":"897.789709ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-07-23T04:57:55.259132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.944161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T04:57:55.25918Z","caller":"traceutil/trace.go:171","msg":"trace[1713843753] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:985; }","duration":"218.058005ms","start":"2026-07-23T04:57:55.041109Z","end":"2026-07-23T04:57:55.259167Z","steps":["trace[1713843753] 'agreement among raft nodes before linearized reading' (duration: 217.89669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:58:19.950733Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221364729657651,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T04:58:20.170203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.794429ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T04:58:20.170415Z","caller":"traceutil/trace.go:171","msg":"trace[1023168724] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1106; }","duration":"720.602853ms","start":"2026-07-23T04:58:19.449794Z","end":"2026-07-23T04:58:20.170397Z","steps":["trace[1023168724] 'read index received' (duration: 594.550816ms)","trace[1023168724] 'applied index is now lower than readState.Index' (duration: 126.051097ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:58:20.170665Z","caller":"traceutil/trace.go:171","msg":"trace[195679500] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"729.221469ms","start":"2026-07-23T04:58:19.441429Z","end":"2026-07-23T04:58:20.17065Z","steps":["trace[195679500] 'process raft request' (duration: 602.870242ms)","trace[195679500] 'compare' (duration: 125.656895ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T04:58:20.170731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:58:19.441411Z","time spent":"729.280412ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T04:58:20.171021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.183272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-23T04:58:20.171093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.907728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T04:58:20.171119Z","caller":"traceutil/trace.go:171","msg":"trace[750209023] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1065; }","duration":"721.359138ms","start":"2026-07-23T04:58:19.449739Z","end":"2026-07-23T04:58:20.171099Z","steps":["trace[750209023] 'agreement among raft nodes before linearized reading' (duration: 721.134821ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:58:20.171161Z","caller":"traceutil/trace.go:171","msg":"trace[754798050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1065; }","duration":"491.97ms","start":"2026-07-23T04:58:19.679173Z","end":"2026-07-23T04:58:20.171143Z","steps":["trace[754798050] 'agreement among raft nodes before linearized reading' (duration: 491.871527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:58:20.17117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:58:19.449715Z","time spent":"721.444911ms","remote":"127.0.0.1:33802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-07-23T04:58:20.171192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:58:19.679111Z","time spent":"492.074454ms","remote":"127.0.0.1:33438","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-07-23T04:58:20.171442Z","caller":"traceutil/trace.go:171","msg":"trace[1919147114] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"729.691795ms","start":"2026-07-23T04:58:19.441732Z","end":"2026-07-23T04:58:20.171423Z","steps":["trace[1919147114] 'process raft request' (duration: 728.58359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:58:20.17156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:58:19.441697Z","time spent":"729.794118ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T04:58:20.171657Z","caller":"traceutil/trace.go:171","msg":"trace[1891807084] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"729.899572ms","start":"2026-07-23T04:58:19.441732Z","end":"2026-07-23T04:58:20.171632Z","steps":["trace[1891807084] 'process raft request' (duration: 728.632191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:58:20.171824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:58:19.441697Z","time spent":"730.037996ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T04:58:20.588053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.443205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T04:58:20.588225Z","caller":"traceutil/trace.go:171","msg":"trace[201222316] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"238.667806ms","start":"2026-07-23T04:58:20.349525Z","end":"2026-07-23T04:58:20.588193Z","steps":["trace[201222316] 'process raft request' (duration: 94.944352ms)","trace[201222316] 'compare' (duration: 143.322161ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:58:21.9975Z","caller":"traceutil/trace.go:171","msg":"trace[397539616] linearizableReadLoop","detail":"{readStateIndex:1112; appliedIndex:1111; }","duration":"318.309695ms","start":"2026-07-23T04:58:21.679165Z","end":"2026-07-23T04:58:21.997475Z","steps":["trace[397539616] 'read index received' (duration: 318.071408ms)","trace[397539616] 'applied index is now lower than readState.Index' (duration: 237.537µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T04:58:21.997701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.518052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T04:58:21.99777Z","caller":"traceutil/trace.go:171","msg":"trace[1071426875] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1068; }","duration":"318.655456ms","start":"2026-07-23T04:58:21.679098Z","end":"2026-07-23T04:58:21.997753Z","steps":["trace[1071426875] 'agreement among raft nodes before linearized reading' (duration: 318.549883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:58:21.997817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:58:21.679072Z","time spent":"318.720658ms","remote":"127.0.0.1:33438","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-07-23T04:58:21.997654Z","caller":"traceutil/trace.go:171","msg":"trace[449653623] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"551.810768ms","start":"2026-07-23T04:58:21.445791Z","end":"2026-07-23T04:58:21.997601Z","steps":["trace[449653623] 'process raft request' (duration: 551.534739ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:58:21.997896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.08203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T04:58:21.997977Z","caller":"traceutil/trace.go:171","msg":"trace[301101592] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1068; }","duration":"259.239746ms","start":"2026-07-23T04:58:21.738716Z","end":"2026-07-23T04:58:21.997956Z","steps":["trace[301101592] 'agreement among raft nodes before linearized reading' (duration: 259.06302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:58:21.998021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:58:21.445765Z","time spent":"552.143369ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T04:58:22.843488Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221364729657669,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T04:58:23.343851Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221364729657669,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T04:58:23.844003Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221364729657669,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T04:58:23.84435Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.54984487s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-23T04:58:23.844667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.550071178s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T04:58:23.845613Z","caller":"traceutil/trace.go:171","msg":"trace[1112884621] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"1.599666915s","start":"2026-07-23T04:58:22.24589Z","end":"2026-07-23T04:58:23.845557Z","steps":["trace[1112884621] 'process raft request' (duration: 1.599472629s)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:58:23.845783Z","caller":"traceutil/trace.go:171","msg":"trace[1146060912] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"1.663007302s","start":"2026-07-23T04:58:22.182742Z","end":"2026-07-23T04:58:23.845749Z","steps":["trace[1146060912] 'process raft request' (duration: 111.750397ms)","trace[1146060912] 'compare' (duration: 1.549063375s)"],"step_count":2} {"level":"warn","ts":"2026-07-23T04:58:23.845919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:58:22.245859Z","time spent":"1.599919433s","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T04:58:23.846118Z","caller":"traceutil/trace.go:171","msg":"trace[1739130258] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"1.661902316s","start":"2026-07-23T04:58:22.182906Z","end":"2026-07-23T04:58:23.844808Z","steps":["trace[1739130258] 'process raft request' (duration: 1.661838584s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:58:23.846037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:58:22.182714Z","time spent":"1.663117776s","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T04:58:23.846237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:58:22.182897Z","time spent":"1.663290301s","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T04:58:23.848529Z","caller":"traceutil/trace.go:171","msg":"trace[233723193] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"1.255188711s","start":"2026-07-23T04:58:22.593326Z","end":"2026-07-23T04:58:23.848515Z","steps":["trace[233723193] 'process raft request' (duration: 1.254897111s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:58:23.848642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:58:22.593298Z","time spent":"1.255284613s","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T04:58:24.055978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.602624ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-23T04:58:24.056238Z","caller":"traceutil/trace.go:171","msg":"trace[137572367] linearizableReadLoop","detail":"{readStateIndex:1118; appliedIndex:1116; }","duration":"1.713333713s","start":"2026-07-23T04:58:22.34278Z","end":"2026-07-23T04:58:24.056113Z","steps":["trace[137572367] 'read index received' (duration: 1.503192188s)","trace[137572367] 'applied index is now lower than readState.Index' (duration: 210.139655ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T04:58:24.056552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.713753116s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-23T04:58:24.056649Z","caller":"traceutil/trace.go:171","msg":"trace[516866966] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1073; }","duration":"1.713933032s","start":"2026-07-23T04:58:22.342694Z","end":"2026-07-23T04:58:24.056627Z","steps":["trace[516866966] 'agreement among raft nodes before linearized reading' (duration: 1.713581241s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:58:24.056817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.169107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T04:58:24.056962Z","caller":"traceutil/trace.go:171","msg":"trace[2129261840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1073; }","duration":"380.516228ms","start":"2026-07-23T04:58:23.67643Z","end":"2026-07-23T04:58:24.056946Z","steps":["trace[2129261840] 'agreement among raft nodes before linearized reading' (duration: 379.98064ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:58:24.057014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:58:23.676401Z","time spent":"380.60096ms","remote":"127.0.0.1:33438","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-07-23T04:58:24.056799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:58:22.342669Z","time spent":"1.714042695s","remote":"127.0.0.1:33736","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-07-23T04:58:24.179989Z","caller":"traceutil/trace.go:171","msg":"trace[1653117239] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"118.894865ms","start":"2026-07-23T04:58:24.061064Z","end":"2026-07-23T04:58:24.179959Z","steps":["trace[1653117239] 'process raft request' (duration: 118.498772ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:58:24.2339Z","caller":"traceutil/trace.go:171","msg":"trace[352572757] linearizableReadLoop","detail":"{readStateIndex:1120; appliedIndex:1119; }","duration":"163.977697ms","start":"2026-07-23T04:58:24.069886Z","end":"2026-07-23T04:58:24.233864Z","steps":["trace[352572757] 'read index received' (duration: 110.108883ms)","trace[352572757] 'applied index is now lower than readState.Index' (duration: 53.867094ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:58:24.234053Z","caller":"traceutil/trace.go:171","msg":"trace[1852230630] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"172.410418ms","start":"2026-07-23T04:58:24.061594Z","end":"2026-07-23T04:58:24.234005Z","steps":["trace[1852230630] 'process raft request' (duration: 172.065587ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:58:24.234176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.235476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T04:58:24.234308Z","caller":"traceutil/trace.go:171","msg":"trace[25360609] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1075; }","duration":"164.423572ms","start":"2026-07-23T04:58:24.069859Z","end":"2026-07-23T04:58:24.234283Z","steps":["trace[25360609] 'agreement among raft nodes before linearized reading' (duration: 164.209285ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:58:24.358423Z","caller":"traceutil/trace.go:171","msg":"trace[286870835] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"118.960617ms","start":"2026-07-23T04:58:24.239432Z","end":"2026-07-23T04:58:24.358392Z","steps":["trace[286870835] 'process raft request' (duration: 33.75247ms)","trace[286870835] 'compare' (duration: 84.978729ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:58:26.266814Z","caller":"traceutil/trace.go:171","msg":"trace[276266284] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"412.77993ms","start":"2026-07-23T04:58:25.854003Z","end":"2026-07-23T04:58:26.266783Z","steps":["trace[276266284] 'process raft request' (duration: 412.610835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:58:26.26718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:58:25.853977Z","time spent":"412.990067ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T04:58:26.721815Z","caller":"traceutil/trace.go:171","msg":"trace[34437005] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"867.156831ms","start":"2026-07-23T04:58:25.854608Z","end":"2026-07-23T04:58:26.721765Z","steps":["trace[34437005] 'process raft request' (duration: 866.864492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:58:26.721976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:58:25.854599Z","time spent":"867.283116ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T04:58:26.721792Z","caller":"traceutil/trace.go:171","msg":"trace[1879093300] linearizableReadLoop","detail":"{readStateIndex:1124; appliedIndex:1121; }","duration":"537.44447ms","start":"2026-07-23T04:58:26.184319Z","end":"2026-07-23T04:58:26.721763Z","steps":["trace[1879093300] 'read index received' (duration: 82.408847ms)","trace[1879093300] 'applied index is now lower than readState.Index' (duration: 455.034113ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:58:26.722094Z","caller":"traceutil/trace.go:171","msg":"trace[149629181] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"866.924934ms","start":"2026-07-23T04:58:25.85512Z","end":"2026-07-23T04:58:26.722045Z","steps":["trace[149629181] 'process raft request' (duration: 866.50029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:58:26.722312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:58:25.855113Z","time spent":"867.12032ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T04:58:26.722147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.819431ms","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-07-23T04:58:26.722512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.575987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-23T04:58:26.722559Z","caller":"traceutil/trace.go:171","msg":"trace[1425651258] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1079; }","duration":"538.279746ms","start":"2026-07-23T04:58:26.18426Z","end":"2026-07-23T04:58:26.72254Z","steps":["trace[1425651258] 'agreement among raft nodes before linearized reading' (duration: 537.595084ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:58:26.722593Z","caller":"traceutil/trace.go:171","msg":"trace[779969724] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1079; }","duration":"484.727903ms","start":"2026-07-23T04:58:26.237843Z","end":"2026-07-23T04:58:26.722571Z","steps":["trace[779969724] 'agreement among raft nodes before linearized reading' (duration: 484.433743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:58:26.722595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.704252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-23T04:58:26.722647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:58:26.237822Z","time spent":"484.814135ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-23T04:58:26.722689Z","caller":"traceutil/trace.go:171","msg":"trace[1464431447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1079; }","duration":"359.874607ms","start":"2026-07-23T04:58:26.362794Z","end":"2026-07-23T04:58:26.722669Z","steps":["trace[1464431447] 'agreement among raft nodes before linearized reading' (duration: 359.576127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:58:26.722738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:58:26.362776Z","time spent":"359.95275ms","remote":"127.0.0.1:33736","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-07-23T04:58:26.722617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:58:26.184235Z","time spent":"538.365189ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-23T04:58:26.964079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.162225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T04:58:26.964268Z","caller":"traceutil/trace.go:171","msg":"trace[220091440] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"234.97815ms","start":"2026-07-23T04:58:26.729269Z","end":"2026-07-23T04:58:26.964247Z","steps":["trace[220091440] 'process raft request' (duration: 234.917778ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:58:26.964542Z","caller":"traceutil/trace.go:171","msg":"trace[229406256] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"236.090135ms","start":"2026-07-23T04:58:26.728387Z","end":"2026-07-23T04:58:26.964478Z","steps":["trace[229406256] 'process raft request' (duration: 110.406203ms)","trace[229406256] 'compare' (duration: 125.033602ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T04:58:28.024287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.332298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-23T04:58:28.024363Z","caller":"traceutil/trace.go:171","msg":"trace[1020430478] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:1082; }","duration":"847.512713ms","start":"2026-07-23T04:58:27.176833Z","end":"2026-07-23T04:58:28.024345Z","steps":["trace[1020430478] 'range keys from in-memory index tree' (duration: 847.143901ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:58:28.024395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:58:27.176792Z","time spent":"847.595516ms","remote":"127.0.0.1:33476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"warn","ts":"2026-07-23T04:58:28.409355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.308495ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T04:58:28.409657Z","caller":"traceutil/trace.go:171","msg":"trace[108451014] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"197.834721ms","start":"2026-07-23T04:58:28.211798Z","end":"2026-07-23T04:58:28.409633Z","steps":["trace[108451014] 'process raft request' (duration: 15.151895ms)","trace[108451014] 'compare' (duration: 182.142769ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:58:28.488126Z","caller":"traceutil/trace.go:171","msg":"trace[2062974745] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1129; }","duration":"153.677728ms","start":"2026-07-23T04:58:28.334418Z","end":"2026-07-23T04:58:28.488096Z","steps":["trace[2062974745] 'read index received' (duration: 153.402019ms)","trace[2062974745] 'applied index is now lower than readState.Index' (duration: 274.729µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:58:28.488376Z","caller":"traceutil/trace.go:171","msg":"trace[1753639204] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"215.816646ms","start":"2026-07-23T04:58:28.272541Z","end":"2026-07-23T04:58:28.488358Z","steps":["trace[1753639204] 'process raft request' (duration: 215.354452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:58:28.488494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.052681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T04:58:28.488577Z","caller":"traceutil/trace.go:171","msg":"trace[1170916966] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1084; }","duration":"154.189125ms","start":"2026-07-23T04:58:28.33437Z","end":"2026-07-23T04:58:28.488559Z","steps":["trace[1170916966] 'agreement among raft nodes before linearized reading' (duration: 154.05198ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:58:42.864753Z","caller":"traceutil/trace.go:171","msg":"trace[1191088427] linearizableReadLoop","detail":"{readStateIndex:1232; appliedIndex:1231; }","duration":"188.473242ms","start":"2026-07-23T04:58:42.676251Z","end":"2026-07-23T04:58:42.864724Z","steps":["trace[1191088427] 'read index received' (duration: 188.310456ms)","trace[1191088427] 'applied index is now lower than readState.Index' (duration: 162.076µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T04:58:42.864934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.656327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T04:58:42.864964Z","caller":"traceutil/trace.go:171","msg":"trace[840529335] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1182; }","duration":"188.745661ms","start":"2026-07-23T04:58:42.67621Z","end":"2026-07-23T04:58:42.864955Z","steps":["trace[840529335] 'agreement among raft nodes before linearized reading' (duration: 188.642748ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:58:42.864912Z","caller":"traceutil/trace.go:171","msg":"trace[163098760] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"330.715953ms","start":"2026-07-23T04:58:42.534124Z","end":"2026-07-23T04:58:42.86484Z","steps":["trace[163098760] 'process raft request' (duration: 330.433684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:58:42.865244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:58:42.534088Z","time spent":"330.979943ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T04:58:45.187265Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221364729657937,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T04:58:45.352088Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.158445995s","expected-duration":"1s"} {"level":"info","ts":"2026-07-23T04:58:45.352581Z","caller":"traceutil/trace.go:171","msg":"trace[1982822638] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"1.159038103s","start":"2026-07-23T04:58:44.193514Z","end":"2026-07-23T04:58:45.352552Z","steps":["trace[1982822638] 'process raft request' (duration: 1.158785575s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:58:45.352734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:58:44.193488Z","time spent":"1.159169557s","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T04:58:45.372359Z","caller":"traceutil/trace.go:171","msg":"trace[1837373517] linearizableReadLoop","detail":"{readStateIndex:1240; appliedIndex:1239; }","duration":"685.177347ms","start":"2026-07-23T04:58:44.687133Z","end":"2026-07-23T04:58:45.37231Z","steps":["trace[1837373517] 'read index received' (duration: 665.468376ms)","trace[1837373517] 'applied index is now lower than readState.Index' (duration: 19.707801ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:58:45.3725Z","caller":"traceutil/trace.go:171","msg":"trace[88952369] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"495.652971ms","start":"2026-07-23T04:58:44.876797Z","end":"2026-07-23T04:58:45.37245Z","steps":["trace[88952369] 'process raft request' (duration: 495.350022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:58:45.372775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.401904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T04:58:45.372841Z","caller":"traceutil/trace.go:171","msg":"trace[1721076106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1189; }","duration":"685.771346ms","start":"2026-07-23T04:58:44.687051Z","end":"2026-07-23T04:58:45.372822Z","steps":["trace[1721076106] 'agreement among raft nodes before linearized reading' (duration: 685.425515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:58:45.372887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:58:44.687023Z","time spent":"685.854409ms","remote":"127.0.0.1:33438","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-07-23T04:58:45.37291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:58:44.876775Z","time spent":"496.013643ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T04:58:45.373101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.565654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-23T04:58:45.373276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.650844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-23T04:58:45.373292Z","caller":"traceutil/trace.go:171","msg":"trace[933665215] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1189; }","duration":"298.809633ms","start":"2026-07-23T04:58:45.074458Z","end":"2026-07-23T04:58:45.373268Z","steps":["trace[933665215] 'agreement among raft nodes before linearized reading' (duration: 298.476092ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:58:45.373338Z","caller":"traceutil/trace.go:171","msg":"trace[265673936] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1189; }","duration":"486.696565ms","start":"2026-07-23T04:58:44.886618Z","end":"2026-07-23T04:58:45.373314Z","steps":["trace[265673936] 'agreement among raft nodes before linearized reading' (duration: 486.581101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:58:45.373388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:58:44.886596Z","time spent":"486.782958ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-23T04:58:45.37346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.409349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-23T04:58:45.373467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.360468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-23T04:58:45.37311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.450447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-23T04:58:45.373497Z","caller":"traceutil/trace.go:171","msg":"trace[235691529] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1189; }","duration":"488.466842ms","start":"2026-07-23T04:58:44.885021Z","end":"2026-07-23T04:58:45.373488Z","steps":["trace[235691529] 'agreement among raft nodes before linearized reading' (duration: 488.292716ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:58:45.37352Z","caller":"traceutil/trace.go:171","msg":"trace[650256506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1189; }","duration":"298.460071ms","start":"2026-07-23T04:58:45.075049Z","end":"2026-07-23T04:58:45.373509Z","steps":["trace[650256506] 'agreement among raft nodes before linearized reading' (duration: 298.269555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:58:45.373538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:58:44.885012Z","time spent":"488.520233ms","remote":"127.0.0.1:33736","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-07-23T04:58:45.373535Z","caller":"traceutil/trace.go:171","msg":"trace[1003064354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1189; }","duration":"491.941133ms","start":"2026-07-23T04:58:44.881582Z","end":"2026-07-23T04:58:45.373523Z","steps":["trace[1003064354] 'agreement among raft nodes before linearized reading' (duration: 491.195169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:58:45.3736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:58:44.881567Z","time spent":"492.018486ms","remote":"127.0.0.1:33736","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-07-23T04:59:01.963066Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221364729658083,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-23T04:59:01.981075Z","caller":"traceutil/trace.go:171","msg":"trace[186855115] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1303; }","duration":"518.634708ms","start":"2026-07-23T04:59:01.462417Z","end":"2026-07-23T04:59:01.981052Z","steps":["trace[186855115] 'read index received' (duration: 518.471452ms)","trace[186855115] 'applied index is now lower than readState.Index' (duration: 162.526µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:59:01.981126Z","caller":"traceutil/trace.go:171","msg":"trace[485329896] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"540.813018ms","start":"2026-07-23T04:59:01.440285Z","end":"2026-07-23T04:59:01.981098Z","steps":["trace[485329896] 'process raft request' (duration: 540.653213ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:01.981261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.364297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-23T04:59:01.981327Z","caller":"traceutil/trace.go:171","msg":"trace[1452813384] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1250; }","duration":"517.487371ms","start":"2026-07-23T04:59:01.463831Z","end":"2026-07-23T04:59:01.981318Z","steps":["trace[1452813384] 'agreement among raft nodes before linearized reading' (duration: 517.330046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:01.981343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.181091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-23T04:59:01.981329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.884065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-23T04:59:01.981385Z","caller":"traceutil/trace.go:171","msg":"trace[689822574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1250; }","duration":"352.257053ms","start":"2026-07-23T04:59:01.629119Z","end":"2026-07-23T04:59:01.981376Z","steps":["trace[689822574] 'agreement among raft nodes before linearized reading' (duration: 352.190811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:01.981395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:01.463807Z","time spent":"517.578924ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-23T04:59:01.981418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:01.629098Z","time spent":"352.314335ms","remote":"127.0.0.1:33438","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-07-23T04:59:01.981412Z","caller":"traceutil/trace.go:171","msg":"trace[590659002] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1250; }","duration":"519.003379ms","start":"2026-07-23T04:59:01.462387Z","end":"2026-07-23T04:59:01.981391Z","steps":["trace[590659002] 'agreement among raft nodes before linearized reading' (duration: 518.743851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:01.981465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:01.462378Z","time spent":"519.077822ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-23T04:59:01.981465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.816271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T04:59:01.981551Z","caller":"traceutil/trace.go:171","msg":"trace[1996510553] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1250; }","duration":"302.944185ms","start":"2026-07-23T04:59:01.678585Z","end":"2026-07-23T04:59:01.981529Z","steps":["trace[1996510553] 'agreement among raft nodes before linearized reading' (duration: 302.80286ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:01.981615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:01.678563Z","time spent":"303.040578ms","remote":"127.0.0.1:33452","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-07-23T04:59:01.98165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.753767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-23T04:59:01.981269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:01.440263Z","time spent":"540.915841ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T04:59:01.981678Z","caller":"traceutil/trace.go:171","msg":"trace[835231729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1250; }","duration":"511.808679ms","start":"2026-07-23T04:59:01.469861Z","end":"2026-07-23T04:59:01.98167Z","steps":["trace[835231729] 'agreement among raft nodes before linearized reading' (duration: 511.729207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:01.981706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:01.469846Z","time spent":"511.85525ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-23T04:59:01.981861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.797041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-23T04:59:01.981905Z","caller":"traceutil/trace.go:171","msg":"trace[1522632001] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1250; }","duration":"507.863193ms","start":"2026-07-23T04:59:01.474031Z","end":"2026-07-23T04:59:01.981894Z","steps":["trace[1522632001] 'agreement among raft nodes before linearized reading' (duration: 507.723738ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:01.981919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.989215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-23T04:59:01.981934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:01.47402Z","time spent":"507.907795ms","remote":"127.0.0.1:33736","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-07-23T04:59:01.981973Z","caller":"traceutil/trace.go:171","msg":"trace[245562199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1250; }","duration":"512.032356ms","start":"2026-07-23T04:59:01.469908Z","end":"2026-07-23T04:59:01.981941Z","steps":["trace[245562199] 'agreement among raft nodes before linearized reading' (duration: 511.950663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:01.982007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:01.469903Z","time spent":"512.097568ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-23T04:59:02.117927Z","caller":"traceutil/trace.go:171","msg":"trace[1724489294] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"128.700078ms","start":"2026-07-23T04:59:01.989204Z","end":"2026-07-23T04:59:02.117905Z","steps":["trace[1724489294] 'process raft request' (duration: 128.638206ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:59:02.118109Z","caller":"traceutil/trace.go:171","msg":"trace[1580030357] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"130.804556ms","start":"2026-07-23T04:59:01.987294Z","end":"2026-07-23T04:59:02.118099Z","steps":["trace[1580030357] 'process raft request' (duration: 111.24113ms)","trace[1580030357] 'compare' (duration: 19.137653ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:59:02.118183Z","caller":"traceutil/trace.go:171","msg":"trace[337804929] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"129.05761ms","start":"2026-07-23T04:59:01.989115Z","end":"2026-07-23T04:59:02.118172Z","steps":["trace[337804929] 'process raft request' (duration: 128.689368ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:59:02.118272Z","caller":"traceutil/trace.go:171","msg":"trace[1444333837] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"128.284226ms","start":"2026-07-23T04:59:01.989979Z","end":"2026-07-23T04:59:02.118264Z","steps":["trace[1444333837] 'process raft request' (duration: 127.894193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:02.485092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.875725ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-23T04:59:04.452262Z","caller":"traceutil/trace.go:171","msg":"trace[1619285905] linearizableReadLoop","detail":"{readStateIndex:1312; appliedIndex:1311; }","duration":"429.313909ms","start":"2026-07-23T04:59:04.022924Z","end":"2026-07-23T04:59:04.452238Z","steps":["trace[1619285905] 'read index received' (duration: 429.054491ms)","trace[1619285905] 'applied index is now lower than readState.Index' (duration: 256.118µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:59:04.452361Z","caller":"traceutil/trace.go:171","msg":"trace[1112476986] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"462.688817ms","start":"2026-07-23T04:59:03.989646Z","end":"2026-07-23T04:59:04.452334Z","steps":["trace[1112476986] 'process raft request' (duration: 462.413809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:04.452477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:03.98962Z","time spent":"462.794801ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T04:59:04.452471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.520616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-23T04:59:04.452515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.92067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-23T04:59:04.452557Z","caller":"traceutil/trace.go:171","msg":"trace[1277705787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1257; }","duration":"411.025064ms","start":"2026-07-23T04:59:04.041521Z","end":"2026-07-23T04:59:04.452546Z","steps":["trace[1277705787] 'agreement among raft nodes before linearized reading' (duration: 410.850129ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:59:04.452556Z","caller":"traceutil/trace.go:171","msg":"trace[115655732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1257; }","duration":"429.621389ms","start":"2026-07-23T04:59:04.022912Z","end":"2026-07-23T04:59:04.452533Z","steps":["trace[115655732] 'agreement among raft nodes before linearized reading' (duration: 429.440483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:04.452593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:04.0415Z","time spent":"411.084525ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-23T04:59:04.452603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:04.022866Z","time spent":"429.729453ms","remote":"127.0.0.1:33438","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-07-23T04:59:04.452597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.385703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-23T04:59:04.452591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.038694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T04:59:04.452655Z","caller":"traceutil/trace.go:171","msg":"trace[955957891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1257; }","duration":"330.463245ms","start":"2026-07-23T04:59:04.122179Z","end":"2026-07-23T04:59:04.452642Z","steps":["trace[955957891] 'agreement among raft nodes before linearized reading' (duration: 330.253739ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:59:04.452677Z","caller":"traceutil/trace.go:171","msg":"trace[288685163] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1257; }","duration":"211.178129ms","start":"2026-07-23T04:59:04.241479Z","end":"2026-07-23T04:59:04.452657Z","steps":["trace[288685163] 'agreement among raft nodes before linearized reading' (duration: 211.036514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:04.45269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:04.122173Z","time spent":"330.508708ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-23T04:59:04.452738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.042804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-23T04:59:04.452766Z","caller":"traceutil/trace.go:171","msg":"trace[1838879260] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1257; }","duration":"331.101446ms","start":"2026-07-23T04:59:04.121657Z","end":"2026-07-23T04:59:04.452758Z","steps":["trace[1838879260] 'agreement among raft nodes before linearized reading' (duration: 331.027224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:04.452792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:04.12163Z","time spent":"331.155688ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-23T04:59:04.452849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.587592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-23T04:59:04.452872Z","caller":"traceutil/trace.go:171","msg":"trace[183016057] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1257; }","duration":"331.631594ms","start":"2026-07-23T04:59:04.121235Z","end":"2026-07-23T04:59:04.452866Z","steps":["trace[183016057] 'agreement among raft nodes before linearized reading' (duration: 331.52577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:04.45289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:04.121224Z","time spent":"331.662584ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-23T04:59:04.45294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.651462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T04:59:04.452966Z","caller":"traceutil/trace.go:171","msg":"trace[1646175760] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1257; }","duration":"335.680622ms","start":"2026-07-23T04:59:04.117278Z","end":"2026-07-23T04:59:04.452959Z","steps":["trace[1646175760] 'agreement among raft nodes before linearized reading' (duration: 335.578459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:04.452984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.495947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-23T04:59:04.452992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:04.117217Z","time spent":"335.768115ms","remote":"127.0.0.1:33536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":109,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-07-23T04:59:04.45302Z","caller":"traceutil/trace.go:171","msg":"trace[43499126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1257; }","duration":"330.541838ms","start":"2026-07-23T04:59:04.122468Z","end":"2026-07-23T04:59:04.45301Z","steps":["trace[43499126] 'agreement among raft nodes before linearized reading' (duration: 330.447085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:04.453048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:04.122462Z","time spent":"330.580089ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-23T04:59:05.465451Z","caller":"traceutil/trace.go:171","msg":"trace[2082027939] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1317; }","duration":"356.135188ms","start":"2026-07-23T04:59:05.109294Z","end":"2026-07-23T04:59:05.46543Z","steps":["trace[2082027939] 'read index received' (duration: 355.970542ms)","trace[2082027939] 'applied index is now lower than readState.Index' (duration: 163.876µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:59:05.465552Z","caller":"traceutil/trace.go:171","msg":"trace[271756298] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"478.828714ms","start":"2026-07-23T04:59:04.986714Z","end":"2026-07-23T04:59:05.465543Z","steps":["trace[271756298] 'process raft request' (duration: 478.603027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:05.465631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:04.986686Z","time spent":"478.889406ms","remote":"127.0.0.1:33536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T04:59:05.465771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.994791ms","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":"warn","ts":"2026-07-23T04:59:05.465841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.498139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T04:59:05.465879Z","caller":"traceutil/trace.go:171","msg":"trace[1641925113] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1263; }","duration":"270.105675ms","start":"2026-07-23T04:59:05.195735Z","end":"2026-07-23T04:59:05.46584Z","steps":["trace[1641925113] 'agreement among raft nodes before linearized reading' (duration: 269.874997ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:59:05.465949Z","caller":"traceutil/trace.go:171","msg":"trace[1069489620] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1263; }","duration":"356.643774ms","start":"2026-07-23T04:59:05.109285Z","end":"2026-07-23T04:59:05.465929Z","steps":["trace[1069489620] 'agreement among raft nodes before linearized reading' (duration: 356.380365ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:05.465994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:05.10922Z","time spent":"356.766357ms","remote":"127.0.0.1:33952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-07-23T04:59:06.290113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.810011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-23T04:59:06.290242Z","caller":"traceutil/trace.go:171","msg":"trace[405082491] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"809.787606ms","start":"2026-07-23T04:59:05.480435Z","end":"2026-07-23T04:59:06.290222Z","steps":["trace[405082491] 'process raft request' (duration: 446.817519ms)","trace[405082491] 'compare' (duration: 362.519302ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:59:06.290281Z","caller":"traceutil/trace.go:171","msg":"trace[457107307] linearizableReadLoop","detail":"{readStateIndex:1319; appliedIndex:1318; }","duration":"779.756784ms","start":"2026-07-23T04:59:05.510518Z","end":"2026-07-23T04:59:06.290275Z","steps":["trace[457107307] 'read index received' (duration: 416.821669ms)","trace[457107307] 'applied index is now lower than readState.Index' (duration: 362.934465ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T04:59:06.290323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:05.480414Z","time spent":"809.884499ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T04:59:06.290354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.831287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T04:59:06.290375Z","caller":"traceutil/trace.go:171","msg":"trace[1306950490] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1265; }","duration":"779.857667ms","start":"2026-07-23T04:59:05.510512Z","end":"2026-07-23T04:59:06.29037Z","steps":["trace[1306950490] 'agreement among raft nodes before linearized reading' (duration: 779.803736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:06.290398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:05.510457Z","time spent":"779.9358ms","remote":"127.0.0.1:33588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-07-23T04:59:06.290257Z","caller":"traceutil/trace.go:171","msg":"trace[267089055] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"657.889275ms","start":"2026-07-23T04:59:05.63236Z","end":"2026-07-23T04:59:06.290249Z","steps":["trace[267089055] 'process raft request' (duration: 657.830123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:06.290551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:05.632335Z","time spent":"658.175834ms","remote":"127.0.0.1:33536","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-07-23T04:59:06.313539Z","caller":"traceutil/trace.go:171","msg":"trace[1137422865] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"357.104259ms","start":"2026-07-23T04:59:05.956398Z","end":"2026-07-23T04:59:06.313503Z","steps":["trace[1137422865] 'process raft request' (duration: 356.852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:06.313588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.464227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T04:59:06.31366Z","caller":"traceutil/trace.go:171","msg":"trace[771836214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1266; }","duration":"635.585641ms","start":"2026-07-23T04:59:05.67806Z","end":"2026-07-23T04:59:06.313646Z","steps":["trace[771836214] 'agreement among raft nodes before linearized reading' (duration: 635.436906ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:06.313689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:05.678042Z","time spent":"635.642652ms","remote":"127.0.0.1:33438","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-07-23T04:59:06.314345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:05.95637Z","time spent":"357.253713ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T04:59:06.407129Z","caller":"traceutil/trace.go:171","msg":"trace[1251664279] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"111.193048ms","start":"2026-07-23T04:59:06.295903Z","end":"2026-07-23T04:59:06.407096Z","steps":["trace[1251664279] 'process raft request' (duration: 110.747364ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:59:06.407174Z","caller":"traceutil/trace.go:171","msg":"trace[451002908] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"111.033023ms","start":"2026-07-23T04:59:06.29609Z","end":"2026-07-23T04:59:06.407123Z","steps":["trace[451002908] 'process raft request' (duration: 110.94427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:06.407362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.710683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-07-23T04:59:06.407445Z","caller":"traceutil/trace.go:171","msg":"trace[131689545] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1268; }","duration":"110.839448ms","start":"2026-07-23T04:59:06.296584Z","end":"2026-07-23T04:59:06.407423Z","steps":["trace[131689545] 'agreement among raft nodes before linearized reading' (duration: 110.553108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:15.986929Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221364729658267,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T04:59:16.48751Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221364729658267,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T04:59:16.748688Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.606837194s","expected-duration":"1s"} {"level":"info","ts":"2026-07-23T04:59:16.749218Z","caller":"traceutil/trace.go:171","msg":"trace[167125070] linearizableReadLoop","detail":"{readStateIndex:1408; appliedIndex:1407; }","duration":"1.262507743s","start":"2026-07-23T04:59:15.486686Z","end":"2026-07-23T04:59:16.749194Z","steps":["trace[167125070] 'read index received' (duration: 1.262238054s)","trace[167125070] 'applied index is now lower than readState.Index' (duration: 268.539µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T04:59:16.749309Z","caller":"traceutil/trace.go:171","msg":"trace[69345128] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"1.607553006s","start":"2026-07-23T04:59:15.141744Z","end":"2026-07-23T04:59:16.749297Z","steps":["trace[69345128] 'process raft request' (duration: 1.607203025s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:16.749436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:15.141724Z","time spent":"1.607620018s","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T04:59:16.75019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.263491574s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:120536"} {"level":"info","ts":"2026-07-23T04:59:16.750301Z","caller":"traceutil/trace.go:171","msg":"trace[67559277] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1351; }","duration":"1.26364805s","start":"2026-07-23T04:59:15.486637Z","end":"2026-07-23T04:59:16.750285Z","steps":["trace[67559277] 'agreement among raft nodes before linearized reading' (duration: 1.26272674s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:16.750401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:15.486619Z","time spent":"1.263766933s","remote":"127.0.0.1:33510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":120558,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-07-23T04:59:16.751677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.263222306s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-07-23T04:59:16.751755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.774859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-23T04:59:16.751818Z","caller":"traceutil/trace.go:171","msg":"trace[1176737279] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1353; }","duration":"217.891393ms","start":"2026-07-23T04:59:16.53391Z","end":"2026-07-23T04:59:16.751801Z","steps":["trace[1176737279] 'agreement among raft nodes before linearized reading' (duration: 217.734278ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:59:16.751782Z","caller":"traceutil/trace.go:171","msg":"trace[993248162] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1353; }","duration":"1.263340499s","start":"2026-07-23T04:59:15.488429Z","end":"2026-07-23T04:59:16.751769Z","steps":["trace[993248162] 'agreement among raft nodes before linearized reading' (duration: 1.263194615s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:16.751951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:15.488424Z","time spent":"1.263513995s","remote":"127.0.0.1:33524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":140,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-07-23T04:59:16.75242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.313399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-23T04:59:16.752493Z","caller":"traceutil/trace.go:171","msg":"trace[1348931997] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"177.761409ms","start":"2026-07-23T04:59:16.57472Z","end":"2026-07-23T04:59:16.752481Z","steps":["trace[1348931997] 'process raft request' (duration: 176.85562ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:59:16.752513Z","caller":"traceutil/trace.go:171","msg":"trace[1811954551] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1353; }","duration":"229.453444ms","start":"2026-07-23T04:59:16.523048Z","end":"2026-07-23T04:59:16.752501Z","steps":["trace[1811954551] 'agreement among raft nodes before linearized reading' (duration: 229.297189ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:16.752705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.326645ms","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-07-23T04:59:16.752754Z","caller":"traceutil/trace.go:171","msg":"trace[1100279389] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1353; }","duration":"317.442129ms","start":"2026-07-23T04:59:16.435305Z","end":"2026-07-23T04:59:16.752747Z","steps":["trace[1100279389] 'agreement among raft nodes before linearized reading' (duration: 317.323685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:16.752843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:16.435268Z","time spent":"317.563992ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-23T04:59:16.752807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.229956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-23T04:59:16.753094Z","caller":"traceutil/trace.go:171","msg":"trace[684987524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1353; }","duration":"214.536476ms","start":"2026-07-23T04:59:16.538547Z","end":"2026-07-23T04:59:16.753084Z","steps":["trace[684987524] 'agreement among raft nodes before linearized reading' (duration: 214.184575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:16.752882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.35711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-23T04:59:16.753112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.730846ms","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-07-23T04:59:16.753163Z","caller":"traceutil/trace.go:171","msg":"trace[1992424667] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1353; }","duration":"214.65545ms","start":"2026-07-23T04:59:16.538496Z","end":"2026-07-23T04:59:16.753151Z","steps":["trace[1992424667] 'agreement among raft nodes before linearized reading' (duration: 214.328869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:16.753189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075669585s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T04:59:16.753215Z","caller":"traceutil/trace.go:171","msg":"trace[691291746] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1353; }","duration":"1.075730916s","start":"2026-07-23T04:59:15.677475Z","end":"2026-07-23T04:59:16.753206Z","steps":["trace[691291746] 'agreement among raft nodes before linearized reading' (duration: 1.075688795s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:16.753246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:15.677456Z","time spent":"1.075782838s","remote":"127.0.0.1:33438","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-07-23T04:59:16.752939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.906704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-23T04:59:16.753282Z","caller":"traceutil/trace.go:171","msg":"trace[1384324201] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1353; }","duration":"218.270825ms","start":"2026-07-23T04:59:16.535001Z","end":"2026-07-23T04:59:16.753272Z","steps":["trace[1384324201] 'agreement among raft nodes before linearized reading' (duration: 217.896903ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:59:16.75244Z","caller":"traceutil/trace.go:171","msg":"trace[2133531341] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"263.09576ms","start":"2026-07-23T04:59:16.489322Z","end":"2026-07-23T04:59:16.752417Z","steps":["trace[2133531341] 'process raft request' (duration: 262.114599ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:16.752927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.416543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T04:59:16.753249Z","caller":"traceutil/trace.go:171","msg":"trace[1375336027] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1353; }","duration":"706.003714ms","start":"2026-07-23T04:59:16.047193Z","end":"2026-07-23T04:59:16.753197Z","steps":["trace[1375336027] 'agreement among raft nodes before linearized reading' (duration: 705.751066ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T04:59:16.753455Z","caller":"traceutil/trace.go:171","msg":"trace[566380115] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1353; }","duration":"845.092255ms","start":"2026-07-23T04:59:15.908354Z","end":"2026-07-23T04:59:16.753446Z","steps":["trace[566380115] 'agreement among raft nodes before linearized reading' (duration: 844.431184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:16.753512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:15.908337Z","time spent":"845.161668ms","remote":"127.0.0.1:33600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-07-23T04:59:16.753515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:16.047173Z","time spent":"706.311534ms","remote":"127.0.0.1:33812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":17,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-23T04:59:16.753823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.123854ms","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:524"} {"level":"info","ts":"2026-07-23T04:59:16.75386Z","caller":"traceutil/trace.go:171","msg":"trace[1732444551] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1353; }","duration":"724.214307ms","start":"2026-07-23T04:59:16.029636Z","end":"2026-07-23T04:59:16.75385Z","steps":["trace[1732444551] 'agreement among raft nodes before linearized reading' (duration: 724.097973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T04:59:16.753887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T04:59:16.029617Z","time spent":"724.262299ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":546,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-23T05:02:48.091683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.631512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T05:02:48.091767Z","caller":"traceutil/trace.go:171","msg":"trace[302931239] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2322; }","duration":"413.758156ms","start":"2026-07-23T05:02:47.677992Z","end":"2026-07-23T05:02:48.09175Z","steps":["trace[302931239] 'range keys from in-memory index tree' (duration: 413.521589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T05:02:48.091784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.169846ms","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:531"} {"level":"warn","ts":"2026-07-23T05:02:48.092845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.947976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-23T05:02:48.092875Z","caller":"traceutil/trace.go:171","msg":"trace[247128452] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2322; }","duration":"330.305863ms","start":"2026-07-23T05:02:47.762537Z","end":"2026-07-23T05:02:48.092843Z","steps":["trace[247128452] 'range keys from in-memory index tree' (duration: 329.007041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T05:02:48.093002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T05:02:47.76252Z","time spent":"330.435017ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-23T05:02:48.093422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.444113ms","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-07-23T05:02:48.093476Z","caller":"traceutil/trace.go:171","msg":"trace[469101959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2322; }","duration":"323.495004ms","start":"2026-07-23T05:02:47.769953Z","end":"2026-07-23T05:02:48.093448Z","steps":["trace[469101959] 'range keys from in-memory index tree' (duration: 323.351709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T05:02:48.093508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T05:02:47.769947Z","time spent":"323.553126ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-23T05:02:48.092915Z","caller":"traceutil/trace.go:171","msg":"trace[1034154102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2322; }","duration":"323.038419ms","start":"2026-07-23T05:02:47.769859Z","end":"2026-07-23T05:02:48.092898Z","steps":["trace[1034154102] 'range keys from in-memory index tree' (duration: 322.812942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T05:02:48.093896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T05:02:47.769846Z","time spent":"324.035542ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-23T05:02:48.093925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.951967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-23T05:02:48.09405Z","caller":"traceutil/trace.go:171","msg":"trace[769322642] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2322; }","duration":"353.086013ms","start":"2026-07-23T05:02:47.740912Z","end":"2026-07-23T05:02:48.093998Z","steps":["trace[769322642] 'range keys from in-memory index tree' (duration: 352.796643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T05:02:48.094141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T05:02:47.740893Z","time spent":"353.227547ms","remote":"127.0.0.1:33736","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-07-23T05:02:48.094159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.235512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-23T05:02:48.094207Z","caller":"traceutil/trace.go:171","msg":"trace[1263139504] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2322; }","duration":"351.319236ms","start":"2026-07-23T05:02:47.742865Z","end":"2026-07-23T05:02:48.094184Z","steps":["trace[1263139504] 'range keys from in-memory index tree' (duration: 351.14869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T05:02:48.094237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T05:02:47.742852Z","time spent":"351.377257ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-23T05:02:48.091809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T05:02:47.677976Z","time spent":"413.824229ms","remote":"127.0.0.1:33438","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-07-23T05:02:48.095356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.240291ms","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-07-23T05:02:48.095439Z","caller":"traceutil/trace.go:171","msg":"trace[1302717360] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2322; }","duration":"355.345185ms","start":"2026-07-23T05:02:47.740056Z","end":"2026-07-23T05:02:48.095401Z","steps":["trace[1302717360] 'range keys from in-memory index tree' (duration: 351.509372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T05:02:48.095488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T05:02:47.740032Z","time spent":"355.441508ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-23T05:02:48.095556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.443091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050264"} {"level":"info","ts":"2026-07-23T05:02:48.095638Z","caller":"traceutil/trace.go:171","msg":"trace[1051162299] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:2322; }","duration":"111.613076ms","start":"2026-07-23T05:02:47.98399Z","end":"2026-07-23T05:02:48.095603Z","steps":["trace[1051162299] 'range keys from in-memory index tree' (duration: 107.388641ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T05:03:42.101742Z","caller":"traceutil/trace.go:171","msg":"trace[600675971] linearizableReadLoop","detail":"{readStateIndex:2706; appliedIndex:2705; }","duration":"422.61687ms","start":"2026-07-23T05:03:41.679104Z","end":"2026-07-23T05:03:42.10172Z","steps":["trace[600675971] 'read index received' (duration: 422.404743ms)","trace[600675971] 'applied index is now lower than readState.Index' (duration: 211.237µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T05:03:42.101804Z","caller":"traceutil/trace.go:171","msg":"trace[66191952] transaction","detail":"{read_only:false; response_revision:2595; number_of_response:1; }","duration":"457.91262ms","start":"2026-07-23T05:03:41.643855Z","end":"2026-07-23T05:03:42.101768Z","steps":["trace[66191952] 'process raft request' (duration: 457.725034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T05:03:42.101855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.734663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T05:03:42.101898Z","caller":"traceutil/trace.go:171","msg":"trace[218921300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2595; }","duration":"422.824866ms","start":"2026-07-23T05:03:41.679062Z","end":"2026-07-23T05:03:42.101887Z","steps":["trace[218921300] 'agreement among raft nodes before linearized reading' (duration: 422.752864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T05:03:42.101928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T05:03:41.679046Z","time spent":"422.876398ms","remote":"127.0.0.1:33438","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-07-23T05:03:42.102865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T05:03:41.643833Z","time spent":"458.090006ms","remote":"127.0.0.1:33736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}