{"level":"warn","ts":"2026-06-24T00:48:31.801382Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-24T00:48:31.801464Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89: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.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89: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-06-24T00:48:31.801532Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-24T00:48:31.801545Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-06-24T00:48:31.801562Z","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-06-24T00:48:31.802172Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-06-24T00:48:31.802245Z","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.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89: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-06-24T00:48:31.812389Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.959037ms"} {"level":"info","ts":"2026-06-24T00:48:31.822517Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-06-24T00:48:31.822627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-06-24T00:48:31.822654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-06-24T00:48:31.822668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-24T00:48:31.82268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-06-24T00:48:31.822723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-06-24T00:48:31.833443Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-24T00:48:31.835583Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-24T00:48:31.837105Z","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-06-24T00:48:31.840338Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-24T00:48:31.840783Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-24T00:48:31.840788Z","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-06-24T00:48:31.841211Z","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-06-24T00:48:31.841357Z","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-06-24T00:48:31.842551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-06-24T00:48:31.842794Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-06-24T00:48:31.847207Z","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-06-24T00:48:31.847372Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-24T00:48:31.847393Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-24T00:48:31.84746Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-06-24T00:48:31.847472Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-06-24T00:48:32.823626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-06-24T00:48:32.823722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-24T00:48:32.823759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-06-24T00:48:32.823787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-06-24T00:48:32.823805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-06-24T00:48:32.823821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-06-24T00:48:32.823834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-06-24T00:48:32.825015Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T00:48:32.826324Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-06-24T00:48:32.826331Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-24T00:48:32.826396Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-24T00:48:32.826888Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-24T00:48:32.826976Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-24T00:48:32.827579Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T00:48:32.827739Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T00:48:32.827802Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T00:48:32.829726Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-06-24T00:48:32.831046Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-06-24T00:48:41.880332Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096757607813310,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T00:48:42.382027Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096757607813310,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T00:48:42.539348Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.203215326s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-24T00:48:43.086292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.494168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T00:48:43.086511Z","caller":"traceutil/trace.go:171","msg":"trace[1135056719] linearizableReadLoop","detail":"{readStateIndex:305; appliedIndex:304; }","duration":"1.706569132s","start":"2026-06-24T00:48:41.379918Z","end":"2026-06-24T00:48:43.086487Z","steps":["trace[1135056719] 'read index received' (duration: 1.159591659s)","trace[1135056719] 'applied index is now lower than readState.Index' (duration: 546.975703ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:48:43.086521Z","caller":"traceutil/trace.go:171","msg":"trace[2050313291] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"1.750449496s","start":"2026-06-24T00:48:41.336032Z","end":"2026-06-24T00:48:43.086482Z","steps":["trace[2050313291] 'process raft request' (duration: 1.203572216s)","trace[2050313291] 'compare' (duration: 545.311943ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:48:43.086717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.21416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T00:48:43.086886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.534662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-24T00:48:43.087138Z","caller":"traceutil/trace.go:171","msg":"trace[1329094429] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:292; }","duration":"844.81285ms","start":"2026-06-24T00:48:42.24231Z","end":"2026-06-24T00:48:43.087123Z","steps":["trace[1329094429] 'agreement among raft nodes before linearized reading' (duration: 844.503771ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:48:43.087296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:48:42.242294Z","time spent":"844.928563ms","remote":"127.0.0.1:43490","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-06-24T00:48:43.086916Z","caller":"traceutil/trace.go:171","msg":"trace[826007393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:292; }","duration":"988.440828ms","start":"2026-06-24T00:48:42.098443Z","end":"2026-06-24T00:48:43.086883Z","steps":["trace[826007393] 'agreement among raft nodes before linearized reading' (duration: 988.19679ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:48:43.087393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:48:42.098427Z","time spent":"988.952994ms","remote":"127.0.0.1:43192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-24T00:48:43.086772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.407354ms","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-06-24T00:48:43.087488Z","caller":"traceutil/trace.go:171","msg":"trace[1983668077] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:292; }","duration":"633.152156ms","start":"2026-06-24T00:48:42.454312Z","end":"2026-06-24T00:48:43.087464Z","steps":["trace[1983668077] 'agreement among raft nodes before linearized reading' (duration: 632.367402ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:48:43.087548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:48:42.454286Z","time spent":"633.245719ms","remote":"127.0.0.1:43490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-24T00:48:43.086782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.706770189s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-06-24T00:48:43.087662Z","caller":"traceutil/trace.go:171","msg":"trace[627065373] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:292; }","duration":"1.70777401s","start":"2026-06-24T00:48:41.379868Z","end":"2026-06-24T00:48:43.087642Z","steps":["trace[627065373] 'agreement among raft nodes before linearized reading' (duration: 1.706714437s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:48:43.087724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:48:41.379848Z","time spent":"1.707859501s","remote":"127.0.0.1:43318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-24T00:48:43.086701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:48:41.336014Z","time spent":"1.750593131s","remote":"127.0.0.1:43490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T00:48:43.593676Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096757607813313,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T00:48:44.656416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.3291ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-24T00:48:44.656697Z","caller":"traceutil/trace.go:171","msg":"trace[1454429166] linearizableReadLoop","detail":"{readStateIndex:308; appliedIndex:305; }","duration":"1.563986448s","start":"2026-06-24T00:48:43.092696Z","end":"2026-06-24T00:48:44.656683Z","steps":["trace[1454429166] 'read index received' (duration: 646.426232ms)","trace[1454429166] 'applied index is now lower than readState.Index' (duration: 917.559296ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:48:44.656791Z","caller":"traceutil/trace.go:171","msg":"trace[150776967] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"1.564343098s","start":"2026-06-24T00:48:43.092439Z","end":"2026-06-24T00:48:44.656782Z","steps":["trace[150776967] 'process raft request' (duration: 1.564087151s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:48:44.656872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:48:43.092417Z","time spent":"1.56441333s","remote":"127.0.0.1:43490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T00:48:44.656976Z","caller":"traceutil/trace.go:171","msg":"trace[1494759688] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"1.565206334s","start":"2026-06-24T00:48:43.091752Z","end":"2026-06-24T00:48:44.656959Z","steps":["trace[1494759688] 'process raft request' (duration: 647.280127ms)","trace[1494759688] 'compare' (duration: 915.967832ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:48:44.657054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:48:43.091723Z","time spent":"1.565303207s","remote":"127.0.0.1:43410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T00:48:44.657091Z","caller":"traceutil/trace.go:171","msg":"trace[2085379286] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"1.562910444s","start":"2026-06-24T00:48:43.09417Z","end":"2026-06-24T00:48:44.657081Z","steps":["trace[2085379286] 'process raft request' (duration: 1.562477972s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:48:44.657152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:48:43.094157Z","time spent":"1.562964755s","remote":"127.0.0.1:43490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T00:48:44.657249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.564547324s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"info","ts":"2026-06-24T00:48:44.657281Z","caller":"traceutil/trace.go:171","msg":"trace[542061976] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:295; }","duration":"1.564618885s","start":"2026-06-24T00:48:43.092654Z","end":"2026-06-24T00:48:44.657273Z","steps":["trace[542061976] 'agreement among raft nodes before linearized reading' (duration: 1.564558034s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:48:44.657307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:48:43.092641Z","time spent":"1.564659706s","remote":"127.0.0.1:43394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7318,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-06-24T00:48:44.666411Z","caller":"traceutil/trace.go:171","msg":"trace[1550447790] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"377.448723ms","start":"2026-06-24T00:48:44.288931Z","end":"2026-06-24T00:48:44.666379Z","steps":["trace[1550447790] 'process raft request' (duration: 377.356061ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:48:44.666464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.505302465s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-24T00:48:44.666544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.570789145s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T00:48:44.666537Z","caller":"traceutil/trace.go:171","msg":"trace[2007009166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:296; }","duration":"1.505404168s","start":"2026-06-24T00:48:43.161117Z","end":"2026-06-24T00:48:44.666521Z","steps":["trace[2007009166] 'agreement among raft nodes before linearized reading' (duration: 1.505272205s)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:48:44.666593Z","caller":"traceutil/trace.go:171","msg":"trace[1570391257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:296; }","duration":"1.570872938s","start":"2026-06-24T00:48:43.095696Z","end":"2026-06-24T00:48:44.666569Z","steps":["trace[1570391257] 'agreement among raft nodes before linearized reading' (duration: 1.570795516s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:48:44.66664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:48:43.095687Z","time spent":"1.57094647s","remote":"127.0.0.1:43194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-24T00:48:44.666647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:48:44.288905Z","time spent":"377.560847ms","remote":"127.0.0.1:43490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T00:48:44.666593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:48:43.161105Z","time spent":"1.50548008s","remote":"127.0.0.1:43490","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-06-24T00:49:14.995717Z","caller":"traceutil/trace.go:171","msg":"trace[1721334080] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"102.256225ms","start":"2026-06-24T00:49:14.893433Z","end":"2026-06-24T00:49:14.995689Z","steps":["trace[1721334080] 'process raft request' (duration: 102.088362ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:49:14.997602Z","caller":"traceutil/trace.go:171","msg":"trace[1595817584] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"101.066492ms","start":"2026-06-24T00:49:14.896495Z","end":"2026-06-24T00:49:14.997562Z","steps":["trace[1595817584] 'process raft request' (duration: 100.781927ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:49:15.76173Z","caller":"traceutil/trace.go:171","msg":"trace[469473469] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"439.085712ms","start":"2026-06-24T00:49:15.322614Z","end":"2026-06-24T00:49:15.7617Z","steps":["trace[469473469] 'process raft request' (duration: 438.797746ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:15.761891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:15.322595Z","time spent":"439.227195ms","remote":"127.0.0.1:43256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T00:49:15.771302Z","caller":"traceutil/trace.go:171","msg":"trace[206636724] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"438.48133ms","start":"2026-06-24T00:49:15.332804Z","end":"2026-06-24T00:49:15.771285Z","steps":["trace[206636724] 'process raft request' (duration: 438.357968ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:49:15.771355Z","caller":"traceutil/trace.go:171","msg":"trace[1467666713] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"439.461629ms","start":"2026-06-24T00:49:15.331873Z","end":"2026-06-24T00:49:15.771334Z","steps":["trace[1467666713] 'process raft request' (duration: 439.134323ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:49:15.771348Z","caller":"traceutil/trace.go:171","msg":"trace[1715129463] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"437.915608ms","start":"2026-06-24T00:49:15.333403Z","end":"2026-06-24T00:49:15.771319Z","steps":["trace[1715129463] 'process raft request' (duration: 437.795975ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:15.771413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:15.331852Z","time spent":"439.53712ms","remote":"127.0.0.1:43256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T00:49:15.771448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:15.333397Z","time spent":"438.003389ms","remote":"127.0.0.1:43256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T00:49:15.771405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:15.332797Z","time spent":"438.553771ms","remote":"127.0.0.1:43256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T00:49:15.771606Z","caller":"traceutil/trace.go:171","msg":"trace[1424049287] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"428.290076ms","start":"2026-06-24T00:49:15.343281Z","end":"2026-06-24T00:49:15.771571Z","steps":["trace[1424049287] 'process raft request' (duration: 427.953949ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:15.771741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:15.343257Z","time spent":"428.414529ms","remote":"127.0.0.1:43256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T00:49:15.771822Z","caller":"traceutil/trace.go:171","msg":"trace[1137353074] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"428.43701ms","start":"2026-06-24T00:49:15.343375Z","end":"2026-06-24T00:49:15.771812Z","steps":["trace[1137353074] 'process raft request' (duration: 428.207406ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:15.771882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:15.343369Z","time spent":"428.486751ms","remote":"127.0.0.1:43256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T00:49:15.771857Z","caller":"traceutil/trace.go:171","msg":"trace[685479493] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"428.363218ms","start":"2026-06-24T00:49:15.343462Z","end":"2026-06-24T00:49:15.771825Z","steps":["trace[685479493] 'process raft request' (duration: 428.242896ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:49:15.77199Z","caller":"traceutil/trace.go:171","msg":"trace[1614346420] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"427.109183ms","start":"2026-06-24T00:49:15.344856Z","end":"2026-06-24T00:49:15.771965Z","steps":["trace[1614346420] 'process raft request' (duration: 426.913459ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:15.772042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:15.343457Z","time spent":"428.514321ms","remote":"127.0.0.1:43256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T00:49:15.772071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:15.344847Z","time spent":"427.192674ms","remote":"127.0.0.1:43256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T00:49:15.772078Z","caller":"traceutil/trace.go:171","msg":"trace[912753773] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"415.41731ms","start":"2026-06-24T00:49:15.356638Z","end":"2026-06-24T00:49:15.772055Z","steps":["trace[912753773] 'process raft request' (duration: 415.192365ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:15.772129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:15.356612Z","time spent":"415.494942ms","remote":"127.0.0.1:43256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T00:49:15.772169Z","caller":"traceutil/trace.go:171","msg":"trace[1756512420] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"410.495251ms","start":"2026-06-24T00:49:15.361662Z","end":"2026-06-24T00:49:15.772158Z","steps":["trace[1756512420] 'process raft request' (duration: 410.344488ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:49:15.772312Z","caller":"traceutil/trace.go:171","msg":"trace[203648460] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"403.176005ms","start":"2026-06-24T00:49:15.369124Z","end":"2026-06-24T00:49:15.7723Z","steps":["trace[203648460] 'process raft request' (duration: 402.950351ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:15.772377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:15.369105Z","time spent":"403.245116ms","remote":"127.0.0.1:43256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T00:49:15.772394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:15.361651Z","time spent":"410.644614ms","remote":"127.0.0.1:43256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T00:49:15.772429Z","caller":"traceutil/trace.go:171","msg":"trace[692046329] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"274.496278ms","start":"2026-06-24T00:49:15.497915Z","end":"2026-06-24T00:49:15.772411Z","steps":["trace[692046329] 'process raft request' (duration: 274.302024ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:49:15.77253Z","caller":"traceutil/trace.go:171","msg":"trace[1507170498] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"229.852926ms","start":"2026-06-24T00:49:15.542501Z","end":"2026-06-24T00:49:15.772354Z","steps":["trace[1507170498] 'process raft request' (duration: 229.781255ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:49:18.693206Z","caller":"traceutil/trace.go:171","msg":"trace[1649036799] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"149.742712ms","start":"2026-06-24T00:49:18.543434Z","end":"2026-06-24T00:49:18.693176Z","steps":["trace[1649036799] 'process raft request' (duration: 101.160803ms)","trace[1649036799] 'compare' (duration: 48.12114ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:49:18.693165Z","caller":"traceutil/trace.go:171","msg":"trace[1876584732] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"148.588619ms","start":"2026-06-24T00:49:18.544548Z","end":"2026-06-24T00:49:18.693136Z","steps":["trace[1876584732] 'process raft request' (duration: 148.494557ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:49:22.745495Z","caller":"traceutil/trace.go:171","msg":"trace[1001569755] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"247.796333ms","start":"2026-06-24T00:49:22.497672Z","end":"2026-06-24T00:49:22.745468Z","steps":["trace[1001569755] 'process raft request' (duration: 247.246142ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:23.464061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.599356ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-24T00:49:23.46417Z","caller":"traceutil/trace.go:171","msg":"trace[389279623] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"566.620003ms","start":"2026-06-24T00:49:22.897533Z","end":"2026-06-24T00:49:23.464153Z","steps":["trace[389279623] 'read index received' (duration: 429.784963ms)","trace[389279623] 'applied index is now lower than readState.Index' (duration: 136.83379ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:49:23.464303Z","caller":"traceutil/trace.go:171","msg":"trace[849106378] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"665.492361ms","start":"2026-06-24T00:49:22.798758Z","end":"2026-06-24T00:49:23.46425Z","steps":["trace[849106378] 'process raft request' (duration: 528.651851ms)","trace[849106378] 'compare' (duration: 136.429052ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:49:23.464385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.843638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mtkws\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-24T00:49:23.464424Z","caller":"traceutil/trace.go:171","msg":"trace[1339275375] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mtkws; range_end:; response_count:1; response_revision:610; }","duration":"566.92373ms","start":"2026-06-24T00:49:22.897491Z","end":"2026-06-24T00:49:23.464414Z","steps":["trace[1339275375] 'agreement among raft nodes before linearized reading' (duration: 566.749486ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:23.464463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:22.89747Z","time spent":"566.98517ms","remote":"127.0.0.1:43394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-mtkws\" "} {"level":"warn","ts":"2026-06-24T00:49:23.464459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:22.79873Z","time spent":"665.655894ms","remote":"127.0.0.1:43458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T00:49:23.464716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.851912ms","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-06-24T00:49:23.464806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.163816ms","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-06-24T00:49:23.464825Z","caller":"traceutil/trace.go:171","msg":"trace[919718696] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:610; }","duration":"424.950064ms","start":"2026-06-24T00:49:23.039827Z","end":"2026-06-24T00:49:23.464778Z","steps":["trace[919718696] 'agreement among raft nodes before linearized reading' (duration: 424.75786ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:23.464841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.720814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-24T00:49:23.464862Z","caller":"traceutil/trace.go:171","msg":"trace[1379772286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:610; }","duration":"194.277758ms","start":"2026-06-24T00:49:23.270571Z","end":"2026-06-24T00:49:23.464848Z","steps":["trace[1379772286] 'agreement among raft nodes before linearized reading' (duration: 194.012803ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:23.464888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:23.039816Z","time spent":"425.057447ms","remote":"127.0.0.1:43490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-24T00:49:23.464902Z","caller":"traceutil/trace.go:171","msg":"trace[1239053540] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:610; }","duration":"428.824536ms","start":"2026-06-24T00:49:23.036062Z","end":"2026-06-24T00:49:23.464887Z","steps":["trace[1239053540] 'agreement among raft nodes before linearized reading' (duration: 428.54451ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:23.46494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:23.03605Z","time spent":"428.880467ms","remote":"127.0.0.1:43490","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-06-24T00:49:23.464989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.746286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-24T00:49:23.465044Z","caller":"traceutil/trace.go:171","msg":"trace[924655202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:610; }","duration":"430.844678ms","start":"2026-06-24T00:49:23.034185Z","end":"2026-06-24T00:49:23.465029Z","steps":["trace[924655202] 'agreement among raft nodes before linearized reading' (duration: 430.648734ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:23.465094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:23.034154Z","time spent":"430.93283ms","remote":"127.0.0.1:43490","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-06-24T00:49:23.46737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.725719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T00:49:23.467762Z","caller":"traceutil/trace.go:171","msg":"trace[735696204] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"434.844742ms","start":"2026-06-24T00:49:23.032891Z","end":"2026-06-24T00:49:23.467736Z","steps":["trace[735696204] 'agreement among raft nodes before linearized reading' (duration: 431.706106ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:23.468056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:23.03287Z","time spent":"435.16713ms","remote":"127.0.0.1:43192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-24T00:49:24.734129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091745603s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-24T00:49:24.73424Z","caller":"traceutil/trace.go:171","msg":"trace[1783494626] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"1.179993571s","start":"2026-06-24T00:49:23.554228Z","end":"2026-06-24T00:49:24.734221Z","steps":["trace[1783494626] 'process raft request' (duration: 87.977662ms)","trace[1783494626] 'compare' (duration: 1.091545229s)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:49:24.734378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:23.554206Z","time spent":"1.180132273s","remote":"127.0.0.1:43394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T00:49:26.576108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.371715069s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-24T00:49:26.576233Z","caller":"traceutil/trace.go:171","msg":"trace[400757987] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"1.837739333s","start":"2026-06-24T00:49:24.738475Z","end":"2026-06-24T00:49:26.576214Z","steps":["trace[400757987] 'process raft request' (duration: 465.83787ms)","trace[400757987] 'compare' (duration: 1.371591066s)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:49:26.57691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:24.738452Z","time spent":"1.838273204s","remote":"127.0.0.1:43278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T00:49:26.677295Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.472868787s","expected-duration":"1s"} {"level":"info","ts":"2026-06-24T00:49:26.6968Z","caller":"traceutil/trace.go:171","msg":"trace[1403506634] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"583.472281ms","start":"2026-06-24T00:49:26.113303Z","end":"2026-06-24T00:49:26.696775Z","steps":["trace[1403506634] 'process raft request' (duration: 583.39655ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:49:26.696896Z","caller":"traceutil/trace.go:171","msg":"trace[565574041] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"1.958178954s","start":"2026-06-24T00:49:24.738657Z","end":"2026-06-24T00:49:26.696836Z","steps":["trace[565574041] 'process raft request' (duration: 1.938808039s)","trace[565574041] 'compare' (duration: 18.918524ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:49:26.697099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:24.738646Z","time spent":"1.958347577s","remote":"127.0.0.1:43278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T00:49:26.697112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:26.113277Z","time spent":"583.645184ms","remote":"127.0.0.1:43490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T00:49:26.6974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.665485383s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T00:49:26.69748Z","caller":"traceutil/trace.go:171","msg":"trace[445013628] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"1.665601225s","start":"2026-06-24T00:49:25.031856Z","end":"2026-06-24T00:49:26.697457Z","steps":["trace[445013628] 'agreement among raft nodes before linearized reading' (duration: 1.665485843s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:26.697534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:25.031806Z","time spent":"1.665717198s","remote":"127.0.0.1:43192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-24T00:49:26.69767Z","caller":"traceutil/trace.go:171","msg":"trace[1910539493] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"1.956745862s","start":"2026-06-24T00:49:24.740855Z","end":"2026-06-24T00:49:26.697601Z","steps":["trace[1910539493] 'process raft request' (duration: 1.95572339s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:26.697775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:24.740843Z","time spent":"1.956881365s","remote":"127.0.0.1:43458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T00:49:26.697313Z","caller":"traceutil/trace.go:171","msg":"trace[1386696088] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"1.955508047s","start":"2026-06-24T00:49:24.741369Z","end":"2026-06-24T00:49:26.696877Z","steps":["trace[1386696088] 'process raft request' (duration: 1.955271771s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:26.697946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:24.741358Z","time spent":"1.956527068s","remote":"127.0.0.1:43394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T00:49:26.698053Z","caller":"traceutil/trace.go:171","msg":"trace[438452978] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:641; }","duration":"1.664983021s","start":"2026-06-24T00:49:25.031908Z","end":"2026-06-24T00:49:26.696891Z","steps":["trace[438452978] 'read index received' (duration: 172.503764ms)","trace[438452978] 'applied index is now lower than readState.Index' (duration: 1.492476047s)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:49:26.698504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148694945s","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-06-24T00:49:26.698557Z","caller":"traceutil/trace.go:171","msg":"trace[7367174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:621; }","duration":"1.148773276s","start":"2026-06-24T00:49:25.549772Z","end":"2026-06-24T00:49:26.698545Z","steps":["trace[7367174] 'agreement among raft nodes before linearized reading' (duration: 1.148622453s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:26.698573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.887033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-06-24T00:49:26.69859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:25.549758Z","time spent":"1.148824777s","remote":"127.0.0.1:43490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-24T00:49:26.698595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.149375019s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-24T00:49:26.698515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.212096735s","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-06-24T00:49:26.698642Z","caller":"traceutil/trace.go:171","msg":"trace[2062840209] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:621; }","duration":"891.989406ms","start":"2026-06-24T00:49:25.806633Z","end":"2026-06-24T00:49:26.698622Z","steps":["trace[2062840209] 'agreement among raft nodes before linearized reading' (duration: 891.802002ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:26.698836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.149664755s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-06-24T00:49:26.698878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:25.806609Z","time spent":"892.252412ms","remote":"127.0.0.1:43234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.89\" "} {"level":"info","ts":"2026-06-24T00:49:26.698914Z","caller":"traceutil/trace.go:171","msg":"trace[269289191] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"1.149831758s","start":"2026-06-24T00:49:25.54906Z","end":"2026-06-24T00:49:26.698891Z","steps":["trace[269289191] 'agreement among raft nodes before linearized reading' (duration: 1.149491761s)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:49:26.698833Z","caller":"traceutil/trace.go:171","msg":"trace[524181475] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"1.212391361s","start":"2026-06-24T00:49:25.486357Z","end":"2026-06-24T00:49:26.698749Z","steps":["trace[524181475] 'agreement among raft nodes before linearized reading' (duration: 1.212038644s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:26.698979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:25.549031Z","time spent":"1.14993656s","remote":"127.0.0.1:43490","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-06-24T00:49:26.698654Z","caller":"traceutil/trace.go:171","msg":"trace[1652065345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"1.14945221s","start":"2026-06-24T00:49:25.549185Z","end":"2026-06-24T00:49:26.698638Z","steps":["trace[1652065345] 'agreement among raft nodes before linearized reading' (duration: 1.149302447s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:26.699062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:25.486337Z","time spent":"1.212709857s","remote":"127.0.0.1:43490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-24T00:49:26.699067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:25.549172Z","time spent":"1.149865918s","remote":"127.0.0.1:43490","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-06-24T00:49:28.579863Z","caller":"traceutil/trace.go:171","msg":"trace[1684750036] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"370.18937ms","start":"2026-06-24T00:49:28.209652Z","end":"2026-06-24T00:49:28.579841Z","steps":["trace[1684750036] 'process raft request' (duration: 369.178399ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:28.580009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:28.209623Z","time spent":"370.292873ms","remote":"127.0.0.1:43256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T00:49:28.588978Z","caller":"traceutil/trace.go:171","msg":"trace[483146010] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"179.55022ms","start":"2026-06-24T00:49:28.409413Z","end":"2026-06-24T00:49:28.588963Z","steps":["trace[483146010] 'process raft request' (duration: 179.447217ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:49:29.80162Z","caller":"traceutil/trace.go:171","msg":"trace[740867980] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"191.405389ms","start":"2026-06-24T00:49:29.610177Z","end":"2026-06-24T00:49:29.801583Z","steps":["trace[740867980] 'process raft request' (duration: 191.204245ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:49:29.96231Z","caller":"traceutil/trace.go:171","msg":"trace[1154663144] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"153.394972ms","start":"2026-06-24T00:49:29.808873Z","end":"2026-06-24T00:49:29.962268Z","steps":["trace[1154663144] 'process raft request' (duration: 153.024904ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:30.253894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.17556ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-24T00:49:30.254141Z","caller":"traceutil/trace.go:171","msg":"trace[1620431855] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"313.085309ms","start":"2026-06-24T00:49:29.941028Z","end":"2026-06-24T00:49:30.254113Z","steps":["trace[1620431855] 'process raft request' (duration: 77.586642ms)","trace[1620431855] 'compare' (duration: 234.896174ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:49:30.254239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:29.941004Z","time spent":"313.195191ms","remote":"127.0.0.1:43278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":771,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T00:49:30.255412Z","caller":"traceutil/trace.go:171","msg":"trace[643187869] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"285.645191ms","start":"2026-06-24T00:49:29.969754Z","end":"2026-06-24T00:49:30.255399Z","steps":["trace[643187869] 'process raft request' (duration: 285.477398ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:49:30.255545Z","caller":"traceutil/trace.go:171","msg":"trace[1684305295] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:707; }","duration":"247.417977ms","start":"2026-06-24T00:49:30.008079Z","end":"2026-06-24T00:49:30.255497Z","steps":["trace[1684305295] 'read index received' (duration: 10.52645ms)","trace[1684305295] 'applied index is now lower than readState.Index' (duration: 236.889337ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:49:30.255681Z","caller":"traceutil/trace.go:171","msg":"trace[1022201460] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"278.355932ms","start":"2026-06-24T00:49:29.977315Z","end":"2026-06-24T00:49:30.255671Z","steps":["trace[1022201460] 'process raft request' (duration: 278.018305ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:30.255855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.765316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T00:49:30.255897Z","caller":"traceutil/trace.go:171","msg":"trace[939137321] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:684; }","duration":"247.850867ms","start":"2026-06-24T00:49:30.008035Z","end":"2026-06-24T00:49:30.255886Z","steps":["trace[939137321] 'agreement among raft nodes before linearized reading' (duration: 247.769715ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:30.256165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.994663ms","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-06-24T00:49:30.256231Z","caller":"traceutil/trace.go:171","msg":"trace[955993736] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:684; }","duration":"228.112125ms","start":"2026-06-24T00:49:30.028102Z","end":"2026-06-24T00:49:30.256214Z","steps":["trace[955993736] 'agreement among raft nodes before linearized reading' (duration: 227.945622ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:49:38.892466Z","caller":"traceutil/trace.go:171","msg":"trace[955746992] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:790; }","duration":"114.017836ms","start":"2026-06-24T00:49:38.778425Z","end":"2026-06-24T00:49:38.892443Z","steps":["trace[955746992] 'read index received' (duration: 113.72795ms)","trace[955746992] 'applied index is now lower than readState.Index' (duration: 286.976µs)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:49:38.892679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.230561ms","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-06-24T00:49:38.892647Z","caller":"traceutil/trace.go:171","msg":"trace[1408057489] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"186.835273ms","start":"2026-06-24T00:49:38.705764Z","end":"2026-06-24T00:49:38.892599Z","steps":["trace[1408057489] 'process raft request' (duration: 186.466154ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:49:38.892726Z","caller":"traceutil/trace.go:171","msg":"trace[1993700731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:761; }","duration":"114.349483ms","start":"2026-06-24T00:49:38.778366Z","end":"2026-06-24T00:49:38.892716Z","steps":["trace[1993700731] 'agreement among raft nodes before linearized reading' (duration: 114.20318ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:38.89309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.788676ms","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-06-24T00:49:38.89315Z","caller":"traceutil/trace.go:171","msg":"trace[1150894137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:761; }","duration":"108.918848ms","start":"2026-06-24T00:49:38.784218Z","end":"2026-06-24T00:49:38.893137Z","steps":["trace[1150894137] 'agreement among raft nodes before linearized reading' (duration: 108.656733ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:38.893185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.541582ms","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-06-24T00:49:38.893301Z","caller":"traceutil/trace.go:171","msg":"trace[550565491] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:761; }","duration":"112.750397ms","start":"2026-06-24T00:49:38.780524Z","end":"2026-06-24T00:49:38.893274Z","steps":["trace[550565491] 'agreement among raft nodes before linearized reading' (duration: 112.355378ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:38.89309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.702015ms","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-06-24T00:49:38.893989Z","caller":"traceutil/trace.go:171","msg":"trace[2119017723] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:761; }","duration":"113.653837ms","start":"2026-06-24T00:49:38.780324Z","end":"2026-06-24T00:49:38.893978Z","steps":["trace[2119017723] 'agreement among raft nodes before linearized reading' (duration: 112.540051ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:44.892169Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096757607814263,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-24T00:49:44.954738Z","caller":"traceutil/trace.go:171","msg":"trace[871043645] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:816; }","duration":"563.517124ms","start":"2026-06-24T00:49:44.391189Z","end":"2026-06-24T00:49:44.954706Z","steps":["trace[871043645] 'read index received' (duration: 563.279448ms)","trace[871043645] 'applied index is now lower than readState.Index' (duration: 236.326µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:49:44.954809Z","caller":"traceutil/trace.go:171","msg":"trace[2002059281] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"915.275315ms","start":"2026-06-24T00:49:44.039511Z","end":"2026-06-24T00:49:44.954786Z","steps":["trace[2002059281] 'process raft request' (duration: 915.029179ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:44.954928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.73705ms","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-06-24T00:49:44.954937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:44.039494Z","time spent":"915.361657ms","remote":"127.0.0.1:43458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T00:49:44.954998Z","caller":"traceutil/trace.go:171","msg":"trace[901977284] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:786; }","duration":"563.845342ms","start":"2026-06-24T00:49:44.391135Z","end":"2026-06-24T00:49:44.95498Z","steps":["trace[901977284] 'agreement among raft nodes before linearized reading' (duration: 563.677988ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:49:44.955047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:49:44.391118Z","time spent":"563.919864ms","remote":"127.0.0.1:43490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-24T00:50:07.344549Z","caller":"traceutil/trace.go:171","msg":"trace[1531230302] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"184.403713ms","start":"2026-06-24T00:50:07.160111Z","end":"2026-06-24T00:50:07.344515Z","steps":["trace[1531230302] 'process raft request' (duration: 184.27592ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:50:07.344552Z","caller":"traceutil/trace.go:171","msg":"trace[1281081956] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1022; }","duration":"129.204786ms","start":"2026-06-24T00:50:07.215307Z","end":"2026-06-24T00:50:07.344512Z","steps":["trace[1281081956] 'read index received' (duration: 129.178685ms)","trace[1281081956] 'applied index is now lower than readState.Index' (duration: 22.441µs)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:50:07.344922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.527985ms","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-06-24T00:50:07.344983Z","caller":"traceutil/trace.go:171","msg":"trace[2023518405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:987; }","duration":"129.707789ms","start":"2026-06-24T00:50:07.215264Z","end":"2026-06-24T00:50:07.344971Z","steps":["trace[2023518405] 'agreement among raft nodes before linearized reading' (duration: 129.354381ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:50:07.349561Z","caller":"traceutil/trace.go:171","msg":"trace[1960677889] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"120.24486ms","start":"2026-06-24T00:50:07.229287Z","end":"2026-06-24T00:50:07.349532Z","steps":["trace[1960677889] 'process raft request' (duration: 120.123747ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:50:07.349587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.721132ms","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-06-24T00:50:07.349709Z","caller":"traceutil/trace.go:171","msg":"trace[354618666] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:988; }","duration":"122.857455ms","start":"2026-06-24T00:50:07.226832Z","end":"2026-06-24T00:50:07.349689Z","steps":["trace[354618666] 'agreement among raft nodes before linearized reading' (duration: 122.66136ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:50:09.478591Z","caller":"traceutil/trace.go:171","msg":"trace[154201966] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"123.319874ms","start":"2026-06-24T00:50:09.355241Z","end":"2026-06-24T00:50:09.478561Z","steps":["trace[154201966] 'process raft request' (duration: 117.784894ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:50:09.523072Z","caller":"traceutil/trace.go:171","msg":"trace[1754290197] linearizableReadLoop","detail":"{readStateIndex:1032; appliedIndex:1030; }","duration":"118.4322ms","start":"2026-06-24T00:50:09.404619Z","end":"2026-06-24T00:50:09.523052Z","steps":["trace[1754290197] 'read index received' (duration: 68.417297ms)","trace[1754290197] 'applied index is now lower than readState.Index' (duration: 50.014053ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:50:09.523429Z","caller":"traceutil/trace.go:171","msg":"trace[1861115706] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"126.963456ms","start":"2026-06-24T00:50:09.396439Z","end":"2026-06-24T00:50:09.523402Z","steps":["trace[1861115706] 'process raft request' (duration: 126.389282ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:50:09.523496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.601135ms","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-06-24T00:50:09.523552Z","caller":"traceutil/trace.go:171","msg":"trace[1022714510] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:997; }","duration":"118.682586ms","start":"2026-06-24T00:50:09.404852Z","end":"2026-06-24T00:50:09.523534Z","steps":["trace[1022714510] 'agreement among raft nodes before linearized reading' (duration: 118.535403ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:50:09.523558Z","caller":"traceutil/trace.go:171","msg":"trace[1321510463] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"104.411795ms","start":"2026-06-24T00:50:09.41913Z","end":"2026-06-24T00:50:09.523542Z","steps":["trace[1321510463] 'process raft request' (duration: 103.874072ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:50:09.524008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.361993ms","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-06-24T00:50:09.524071Z","caller":"traceutil/trace.go:171","msg":"trace[1388861406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:997; }","duration":"119.471466ms","start":"2026-06-24T00:50:09.404584Z","end":"2026-06-24T00:50:09.524056Z","steps":["trace[1388861406] 'agreement among raft nodes before linearized reading' (duration: 118.754268ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:51:24.090964Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096757607815408,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T00:51:24.403088Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.342418966s","expected-duration":"1s"} {"level":"info","ts":"2026-06-24T00:51:24.403452Z","caller":"traceutil/trace.go:171","msg":"trace[749591234] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"1.342859098s","start":"2026-06-24T00:51:23.060562Z","end":"2026-06-24T00:51:24.403421Z","steps":["trace[749591234] 'process raft request' (duration: 1.342728744s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:51:24.403592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:51:23.060545Z","time spent":"1.342984342s","remote":"127.0.0.1:43394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1475,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T00:51:24.405215Z","caller":"traceutil/trace.go:171","msg":"trace[1175733811] linearizableReadLoop","detail":"{readStateIndex:1296; appliedIndex:1295; }","duration":"814.861115ms","start":"2026-06-24T00:51:23.590325Z","end":"2026-06-24T00:51:24.405186Z","steps":["trace[1175733811] 'read index received' (duration: 813.26533ms)","trace[1175733811] 'applied index is now lower than readState.Index' (duration: 1.594655ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:51:24.405513Z","caller":"traceutil/trace.go:171","msg":"trace[1157978921] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"653.05913ms","start":"2026-06-24T00:51:23.75244Z","end":"2026-06-24T00:51:24.405499Z","steps":["trace[1157978921] 'process raft request' (duration: 652.625158ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:51:24.405622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:51:23.752424Z","time spent":"653.124743ms","remote":"127.0.0.1:43490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T00:51:24.405929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.608186ms","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-06-24T00:51:24.405969Z","caller":"traceutil/trace.go:171","msg":"trace[2019035339] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1244; }","duration":"815.689018ms","start":"2026-06-24T00:51:23.590271Z","end":"2026-06-24T00:51:24.40596Z","steps":["trace[2019035339] 'agreement among raft nodes before linearized reading' (duration: 815.522123ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:51:24.405998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:51:23.590244Z","time spent":"815.74766ms","remote":"127.0.0.1:43490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-24T00:51:24.406376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.063009ms","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-06-24T00:51:24.406412Z","caller":"traceutil/trace.go:171","msg":"trace[519186314] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1244; }","duration":"526.10703ms","start":"2026-06-24T00:51:23.880296Z","end":"2026-06-24T00:51:24.406403Z","steps":["trace[519186314] 'agreement among raft nodes before linearized reading' (duration: 526.018608ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:51:24.406437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:51:23.880292Z","time spent":"526.139651ms","remote":"127.0.0.1:43490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-24T00:51:24.40648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.013346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T00:51:24.406559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.24716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T00:51:24.406601Z","caller":"traceutil/trace.go:171","msg":"trace[1548885395] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1244; }","duration":"439.287371ms","start":"2026-06-24T00:51:23.967303Z","end":"2026-06-24T00:51:24.40659Z","steps":["trace[1548885395] 'agreement among raft nodes before linearized reading' (duration: 439.23064ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:51:24.406545Z","caller":"traceutil/trace.go:171","msg":"trace[13720459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1244; }","duration":"374.115968ms","start":"2026-06-24T00:51:24.032413Z","end":"2026-06-24T00:51:24.406529Z","steps":["trace[13720459] 'agreement among raft nodes before linearized reading' (duration: 374.001885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:51:24.406638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:51:23.967272Z","time spent":"439.355593ms","remote":"127.0.0.1:43636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-06-24T00:51:24.406643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:51:24.032388Z","time spent":"374.247532ms","remote":"127.0.0.1:43192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-24T00:51:24.406856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.024119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-24T00:51:24.407008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.410247ms","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-06-24T00:51:24.407047Z","caller":"traceutil/trace.go:171","msg":"trace[596072874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1244; }","duration":"527.457978ms","start":"2026-06-24T00:51:23.87958Z","end":"2026-06-24T00:51:24.407038Z","steps":["trace[596072874] 'agreement among raft nodes before linearized reading' (duration: 527.364796ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:51:24.407057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.373252ms","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-06-24T00:51:24.407074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:51:23.87957Z","time spent":"527.49753ms","remote":"127.0.0.1:43490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-24T00:51:24.407092Z","caller":"traceutil/trace.go:171","msg":"trace[1436766141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1244; }","duration":"529.419943ms","start":"2026-06-24T00:51:23.87766Z","end":"2026-06-24T00:51:24.40708Z","steps":["trace[1436766141] 'agreement among raft nodes before linearized reading' (duration: 529.288219ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:51:24.407131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:51:23.877654Z","time spent":"529.470615ms","remote":"127.0.0.1:43490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-24T00:51:24.40697Z","caller":"traceutil/trace.go:171","msg":"trace[2020782408] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1244; }","duration":"663.147363ms","start":"2026-06-24T00:51:23.743762Z","end":"2026-06-24T00:51:24.406909Z","steps":["trace[2020782408] 'agreement among raft nodes before linearized reading' (duration: 662.308899ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:51:24.407459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:51:23.743743Z","time spent":"663.697087ms","remote":"127.0.0.1:43490","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":"info","ts":"2026-06-24T00:51:40.696104Z","caller":"traceutil/trace.go:171","msg":"trace[745731352] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"137.496475ms","start":"2026-06-24T00:51:40.558583Z","end":"2026-06-24T00:51:40.696079Z","steps":["trace[745731352] 'process raft request' (duration: 137.35935ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:51:41.551376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.541016ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-24T00:51:41.551746Z","caller":"traceutil/trace.go:171","msg":"trace[751856088] linearizableReadLoop","detail":"{readStateIndex:1393; appliedIndex:1392; }","duration":"476.1645ms","start":"2026-06-24T00:51:41.075551Z","end":"2026-06-24T00:51:41.551716Z","steps":["trace[751856088] 'read index received' (duration: 112.293714ms)","trace[751856088] 'applied index is now lower than readState.Index' (duration: 363.869006ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:51:41.551958Z","caller":"traceutil/trace.go:171","msg":"trace[1552272307] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"401.400017ms","start":"2026-06-24T00:51:41.150502Z","end":"2026-06-24T00:51:41.551902Z","steps":["trace[1552272307] 'process raft request' (duration: 400.990406ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:51:41.552203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:51:41.15048Z","time spent":"401.563302ms","remote":"127.0.0.1:43490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T00:51:41.581773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.198328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T00:51:41.581854Z","caller":"traceutil/trace.go:171","msg":"trace[1650091714] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1338; }","duration":"506.340542ms","start":"2026-06-24T00:51:41.075495Z","end":"2026-06-24T00:51:41.581835Z","steps":["trace[1650091714] 'agreement among raft nodes before linearized reading' (duration: 476.308163ms)","trace[1650091714] 'count revisions from in-memory index tree' (duration: 29.901965ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T00:51:41.581889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T00:51:41.075472Z","time spent":"506.410134ms","remote":"127.0.0.1:59064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-06-24T00:51:41.590919Z","caller":"traceutil/trace.go:171","msg":"trace[689020278] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"149.211048ms","start":"2026-06-24T00:51:41.441697Z","end":"2026-06-24T00:51:41.590908Z","steps":["trace[689020278] 'process raft request' (duration: 149.096705ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:52:04.718405Z","caller":"traceutil/trace.go:171","msg":"trace[517667667] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"113.15242ms","start":"2026-06-24T00:52:04.60519Z","end":"2026-06-24T00:52:04.718342Z","steps":["trace[517667667] 'process raft request' (duration: 113.022456ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:52:08.976282Z","caller":"traceutil/trace.go:171","msg":"trace[162038720] linearizableReadLoop","detail":"{readStateIndex:1491; appliedIndex:1490; }","duration":"231.913161ms","start":"2026-06-24T00:52:08.744342Z","end":"2026-06-24T00:52:08.976255Z","steps":["trace[162038720] 'read index received' (duration: 231.709724ms)","trace[162038720] 'applied index is now lower than readState.Index' (duration: 202.077µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T00:52:08.976401Z","caller":"traceutil/trace.go:171","msg":"trace[1962255499] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"241.870715ms","start":"2026-06-24T00:52:08.734492Z","end":"2026-06-24T00:52:08.976363Z","steps":["trace[1962255499] 'process raft request' (duration: 241.592466ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T00:52:08.976535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.164268ms","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-06-24T00:52:08.976607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.32559ms","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-06-24T00:52:08.976622Z","caller":"traceutil/trace.go:171","msg":"trace[1637190328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1429; }","duration":"232.309681ms","start":"2026-06-24T00:52:08.744289Z","end":"2026-06-24T00:52:08.976599Z","steps":["trace[1637190328] 'agreement among raft nodes before linearized reading' (duration: 232.083755ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T00:52:08.976652Z","caller":"traceutil/trace.go:171","msg":"trace[798776413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1429; }","duration":"163.403571ms","start":"2026-06-24T00:52:08.813235Z","end":"2026-06-24T00:52:08.976638Z","steps":["trace[798776413] 'agreement among raft nodes before linearized reading' (duration: 163.224326ms)"],"step_count":1}