{"level":"warn","ts":"2026-07-30T20:57:20.844665Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T20:57:20.844775Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.29:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.29:2380","--initial-cluster=instance=https://199.19.213.29:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.29:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.29:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-30T20:57:20.844844Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T20:57:20.844856Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.29:2380"]} {"level":"info","ts":"2026-07-30T20:57:20.844873Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-30T20:57:20.845583Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.29:2379"]} {"level":"info","ts":"2026-07-30T20:57:20.845691Z","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":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.29:2380"],"listen-peer-urls":["https://199.19.213.29:2380"],"advertise-client-urls":["https://199.19.213.29:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.29:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.29:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-30T20:57:20.862117Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"16.212127ms"} {"level":"info","ts":"2026-07-30T20:57:20.924031Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b1ecbac2f2214dbd","cluster-id":"94b127ce9c24156a"} {"level":"info","ts":"2026-07-30T20:57:20.924151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd switched to configuration voters=()"} {"level":"info","ts":"2026-07-30T20:57:20.924177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd became follower at term 0"} {"level":"info","ts":"2026-07-30T20:57:20.924189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b1ecbac2f2214dbd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-30T20:57:20.924199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd became follower at term 1"} {"level":"info","ts":"2026-07-30T20:57:20.92424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd switched to configuration voters=(12820827585665650109)"} {"level":"warn","ts":"2026-07-30T20:57:20.937785Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-30T20:57:20.94044Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-30T20:57:20.941945Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-30T20:57:20.946587Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b1ecbac2f2214dbd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-30T20:57:20.946773Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b1ecbac2f2214dbd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-30T20:57:20.947367Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-30T20:57:20.947979Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-30T20:57:20.948083Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-30T20:57:20.949777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd switched to configuration voters=(12820827585665650109)"} {"level":"info","ts":"2026-07-30T20:57:20.950019Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"94b127ce9c24156a","local-member-id":"b1ecbac2f2214dbd","added-peer-id":"b1ecbac2f2214dbd","added-peer-peer-urls":["https://199.19.213.29:2380"]} {"level":"info","ts":"2026-07-30T20:57:20.951563Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-30T20:57:20.951784Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.29:2380"} {"level":"info","ts":"2026-07-30T20:57:20.95185Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.29:2380"} {"level":"info","ts":"2026-07-30T20:57:20.951933Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b1ecbac2f2214dbd","initial-advertise-peer-urls":["https://199.19.213.29:2380"],"listen-peer-urls":["https://199.19.213.29:2380"],"advertise-client-urls":["https://199.19.213.29:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.29:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-30T20:57:20.951991Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-30T20:57:21.624969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd is starting a new election at term 1"} {"level":"info","ts":"2026-07-30T20:57:21.625091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd became pre-candidate at term 1"} {"level":"info","ts":"2026-07-30T20:57:21.625138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd received MsgPreVoteResp from b1ecbac2f2214dbd at term 1"} {"level":"info","ts":"2026-07-30T20:57:21.625164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd became candidate at term 2"} {"level":"info","ts":"2026-07-30T20:57:21.625176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd received MsgVoteResp from b1ecbac2f2214dbd at term 2"} {"level":"info","ts":"2026-07-30T20:57:21.625199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1ecbac2f2214dbd became leader at term 2"} {"level":"info","ts":"2026-07-30T20:57:21.625215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b1ecbac2f2214dbd elected leader b1ecbac2f2214dbd at term 2"} {"level":"info","ts":"2026-07-30T20:57:21.644901Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T20:57:21.657414Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T20:57:21.657421Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b1ecbac2f2214dbd","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.29:2379]}","request-path":"/0/members/b1ecbac2f2214dbd/attributes","cluster-id":"94b127ce9c24156a","publish-timeout":"7s"} {"level":"info","ts":"2026-07-30T20:57:21.657567Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T20:57:21.65781Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-30T20:57:21.657863Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-30T20:57:21.661936Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-30T20:57:21.662105Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.29:2379"} {"level":"info","ts":"2026-07-30T20:57:21.680542Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"94b127ce9c24156a","local-member-id":"b1ecbac2f2214dbd","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T20:57:21.680767Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T20:57:21.680844Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-07-30T20:57:25.515768Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601809110472531322,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T20:57:25.989604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.083955184s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-30T20:57:25.989829Z","caller":"traceutil/trace.go:171","msg":"trace[1384406502] transaction","detail":"{read_only:false; response_revision:153; number_of_response:1; }","duration":"1.141526788s","start":"2026-07-30T20:57:24.848272Z","end":"2026-07-30T20:57:25.989799Z","steps":["trace[1384406502] 'process raft request' (duration: 56.695114ms)","trace[1384406502] 'compare' (duration: 1.083757075s)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:57:25.989948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:57:24.848263Z","time spent":"1.141648943s","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":752,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T20:57:25.993967Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.088666543s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T20:57:25.994328Z","caller":"traceutil/trace.go:171","msg":"trace[1189987607] transaction","detail":"{read_only:false; response_revision:154; number_of_response:1; }","duration":"1.117617127s","start":"2026-07-30T20:57:24.876696Z","end":"2026-07-30T20:57:25.994313Z","steps":["trace[1189987607] 'process raft request' (duration: 1.117525553s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:25.994429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:57:24.876668Z","time spent":"1.117712012s","remote":"127.0.0.1:54550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T20:57:26.006522Z","caller":"traceutil/trace.go:171","msg":"trace[584582098] linearizableReadLoop","detail":"{readStateIndex:159; appliedIndex:158; }","duration":"991.201807ms","start":"2026-07-30T20:57:25.01529Z","end":"2026-07-30T20:57:26.006492Z","steps":["trace[584582098] 'read index received' (duration: 979.063535ms)","trace[584582098] 'applied index is now lower than readState.Index' (duration: 12.136892ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:57:26.006633Z","caller":"traceutil/trace.go:171","msg":"trace[284554518] transaction","detail":"{read_only:false; response_revision:155; number_of_response:1; }","duration":"809.907452ms","start":"2026-07-30T20:57:25.196715Z","end":"2026-07-30T20:57:26.006622Z","steps":["trace[284554518] 'process raft request' (duration: 809.65434ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:26.006778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.47699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-30T20:57:26.006844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:57:25.196681Z","time spent":"809.982455ms","remote":"127.0.0.1:54550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T20:57:26.006856Z","caller":"traceutil/trace.go:171","msg":"trace[2070402513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:155; }","duration":"272.591245ms","start":"2026-07-30T20:57:25.734244Z","end":"2026-07-30T20:57:26.006835Z","steps":["trace[2070402513] 'agreement among raft nodes before linearized reading' (duration: 272.396967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:26.006928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.028908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T20:57:26.006927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.635026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:57:26.006991Z","caller":"traceutil/trace.go:171","msg":"trace[709979704] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:155; }","duration":"120.147193ms","start":"2026-07-30T20:57:25.886826Z","end":"2026-07-30T20:57:26.006973Z","steps":["trace[709979704] 'agreement among raft nodes before linearized reading' (duration: 120.047359ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:57:26.007013Z","caller":"traceutil/trace.go:171","msg":"trace[842938527] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:155; }","duration":"991.758702ms","start":"2026-07-30T20:57:25.015228Z","end":"2026-07-30T20:57:26.006987Z","steps":["trace[842938527] 'agreement among raft nodes before linearized reading' (duration: 991.597245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:26.007053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:57:25.015204Z","time spent":"991.841186ms","remote":"127.0.0.1:54396","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-07-30T20:57:26.007113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.796425ms","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-07-30T20:57:26.007222Z","caller":"traceutil/trace.go:171","msg":"trace[403487081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:155; }","duration":"272.91375ms","start":"2026-07-30T20:57:25.734276Z","end":"2026-07-30T20:57:26.007189Z","steps":["trace[403487081] 'agreement among raft nodes before linearized reading' (duration: 272.670269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:28.079608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.249909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:57:28.079774Z","caller":"traceutil/trace.go:171","msg":"trace[833230297] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:0; response_revision:246; }","duration":"240.436018ms","start":"2026-07-30T20:57:27.839309Z","end":"2026-07-30T20:57:28.079745Z","steps":["trace[833230297] 'range keys from in-memory index tree' (duration: 240.067261ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:28.284961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.086979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:57:28.285068Z","caller":"traceutil/trace.go:171","msg":"trace[755729262] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:250; }","duration":"105.250526ms","start":"2026-07-30T20:57:28.17979Z","end":"2026-07-30T20:57:28.28504Z","steps":["trace[755729262] 'range keys from in-memory index tree' (duration: 104.985484ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:57:28.285099Z","caller":"traceutil/trace.go:171","msg":"trace[25004500] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"102.982191ms","start":"2026-07-30T20:57:28.182088Z","end":"2026-07-30T20:57:28.28507Z","steps":["trace[25004500] 'process raft request' (duration: 102.935159ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:57:28.285133Z","caller":"traceutil/trace.go:171","msg":"trace[2122699495] linearizableReadLoop","detail":"{readStateIndex:257; appliedIndex:256; }","duration":"103.144909ms","start":"2026-07-30T20:57:28.181943Z","end":"2026-07-30T20:57:28.285088Z","steps":["trace[2122699495] 'read index received' (duration: 76.50525ms)","trace[2122699495] 'applied index is now lower than readState.Index' (duration: 26.633739ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:57:28.285228Z","caller":"traceutil/trace.go:171","msg":"trace[1682059823] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"105.293278ms","start":"2026-07-30T20:57:28.179832Z","end":"2026-07-30T20:57:28.285125Z","steps":["trace[1682059823] 'process raft request' (duration: 78.638498ms)","trace[1682059823] 'compare' (duration: 26.362467ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:57:28.285357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.38869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:57:28.28541Z","caller":"traceutil/trace.go:171","msg":"trace[1693574921] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:0; response_revision:252; }","duration":"103.483114ms","start":"2026-07-30T20:57:28.181911Z","end":"2026-07-30T20:57:28.285394Z","steps":["trace[1693574921] 'agreement among raft nodes before linearized reading' (duration: 103.348938ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:57:28.457254Z","caller":"traceutil/trace.go:171","msg":"trace[531279373] linearizableReadLoop","detail":"{readStateIndex:259; appliedIndex:258; }","duration":"172.005805ms","start":"2026-07-30T20:57:28.285219Z","end":"2026-07-30T20:57:28.457225Z","steps":["trace[531279373] 'read index received' (duration: 163.200989ms)","trace[531279373] 'applied index is now lower than readState.Index' (duration: 8.803726ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:57:28.457288Z","caller":"traceutil/trace.go:171","msg":"trace[784712426] transaction","detail":"{read_only:false; number_of_response:0; response_revision:252; }","duration":"185.368412ms","start":"2026-07-30T20:57:28.271892Z","end":"2026-07-30T20:57:28.45726Z","steps":["trace[784712426] 'process raft request' (duration: 176.512093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:28.45744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.131266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-07-30T20:57:28.457481Z","caller":"traceutil/trace.go:171","msg":"trace[105140280] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:252; }","duration":"274.21345ms","start":"2026-07-30T20:57:28.183255Z","end":"2026-07-30T20:57:28.457468Z","steps":["trace[105140280] 'agreement among raft nodes before linearized reading' (duration: 274.098145ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:57:28.497316Z","caller":"traceutil/trace.go:171","msg":"trace[908916433] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"207.830828ms","start":"2026-07-30T20:57:28.289468Z","end":"2026-07-30T20:57:28.497299Z","steps":["trace[908916433] 'process raft request' (duration: 207.64714ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:57:28.497473Z","caller":"traceutil/trace.go:171","msg":"trace[1516277752] transaction","detail":"{read_only:false; number_of_response:0; response_revision:254; }","duration":"188.296147ms","start":"2026-07-30T20:57:28.30916Z","end":"2026-07-30T20:57:28.497456Z","steps":["trace[1516277752] 'process raft request' (duration: 188.006933ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:57:28.497556Z","caller":"traceutil/trace.go:171","msg":"trace[1818684770] transaction","detail":"{read_only:false; number_of_response:0; response_revision:254; }","duration":"187.289521ms","start":"2026-07-30T20:57:28.310237Z","end":"2026-07-30T20:57:28.497527Z","steps":["trace[1818684770] 'process raft request' (duration: 187.102932ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:57:28.497499Z","caller":"traceutil/trace.go:171","msg":"trace[193840226] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"208.471238ms","start":"2026-07-30T20:57:28.289017Z","end":"2026-07-30T20:57:28.497488Z","steps":["trace[193840226] 'process raft request' (duration: 207.978096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:28.49777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.409251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-30T20:57:28.497825Z","caller":"traceutil/trace.go:171","msg":"trace[1852665144] transaction","detail":"{read_only:false; number_of_response:0; response_revision:254; }","duration":"188.103198ms","start":"2026-07-30T20:57:28.309713Z","end":"2026-07-30T20:57:28.497817Z","steps":["trace[1852665144] 'process raft request' (duration: 187.500271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:28.497822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.001048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4020"} {"level":"info","ts":"2026-07-30T20:57:28.49785Z","caller":"traceutil/trace.go:171","msg":"trace[374811495] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:254; }","duration":"209.518986ms","start":"2026-07-30T20:57:28.288311Z","end":"2026-07-30T20:57:28.49783Z","steps":["trace[374811495] 'agreement among raft nodes before linearized reading' (duration: 209.297216ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:57:28.497884Z","caller":"traceutil/trace.go:171","msg":"trace[915881023] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:254; }","duration":"210.115353ms","start":"2026-07-30T20:57:28.287752Z","end":"2026-07-30T20:57:28.497867Z","steps":["trace[915881023] 'agreement among raft nodes before linearized reading' (duration: 209.798779ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:57:28.497699Z","caller":"traceutil/trace.go:171","msg":"trace[1749201165] transaction","detail":"{read_only:false; number_of_response:0; response_revision:254; }","duration":"187.93541ms","start":"2026-07-30T20:57:28.309734Z","end":"2026-07-30T20:57:28.49767Z","steps":["trace[1749201165] 'process raft request' (duration: 187.521021ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:57:28.497793Z","caller":"traceutil/trace.go:171","msg":"trace[1875715142] transaction","detail":"{read_only:false; number_of_response:0; response_revision:254; }","duration":"187.680808ms","start":"2026-07-30T20:57:28.31001Z","end":"2026-07-30T20:57:28.497691Z","steps":["trace[1875715142] 'process raft request' (duration: 187.301291ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:57:28.639617Z","caller":"traceutil/trace.go:171","msg":"trace[112908439] linearizableReadLoop","detail":"{readStateIndex:272; appliedIndex:270; }","duration":"100.720996ms","start":"2026-07-30T20:57:28.538872Z","end":"2026-07-30T20:57:28.639593Z","steps":["trace[112908439] 'read index received' (duration: 42.779074ms)","trace[112908439] 'applied index is now lower than readState.Index' (duration: 57.940922ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:57:28.63964Z","caller":"traceutil/trace.go:171","msg":"trace[314537079] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"102.698287ms","start":"2026-07-30T20:57:28.536915Z","end":"2026-07-30T20:57:28.639614Z","steps":["trace[314537079] 'process raft request' (duration: 102.507139ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:28.639774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.874044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-30T20:57:28.639822Z","caller":"traceutil/trace.go:171","msg":"trace[375630425] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:261; }","duration":"100.99217ms","start":"2026-07-30T20:57:28.538816Z","end":"2026-07-30T20:57:28.639809Z","steps":["trace[375630425] 'agreement among raft nodes before linearized reading' (duration: 100.873784ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:57:28.764668Z","caller":"traceutil/trace.go:171","msg":"trace[1607727611] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"115.596983ms","start":"2026-07-30T20:57:28.649052Z","end":"2026-07-30T20:57:28.764649Z","steps":["trace[1607727611] 'process raft request' (duration: 115.553341ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:57:28.76476Z","caller":"traceutil/trace.go:171","msg":"trace[75460571] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"120.888257ms","start":"2026-07-30T20:57:28.643815Z","end":"2026-07-30T20:57:28.764703Z","steps":["trace[75460571] 'process raft request' (duration: 108.535447ms)","trace[75460571] 'compare' (duration: 12.13098ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:57:32.166958Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601809110472531653,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T20:57:32.2858Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.446439386s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T20:57:32.286208Z","caller":"traceutil/trace.go:171","msg":"trace[1903677343] linearizableReadLoop","detail":"{readStateIndex:309; appliedIndex:308; }","duration":"619.57967ms","start":"2026-07-30T20:57:31.666601Z","end":"2026-07-30T20:57:32.28618Z","steps":["trace[1903677343] 'read index received' (duration: 619.37334ms)","trace[1903677343] 'applied index is now lower than readState.Index' (duration: 205.01µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:57:32.286313Z","caller":"traceutil/trace.go:171","msg":"trace[903404459] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"1.447043886s","start":"2026-07-30T20:57:30.839256Z","end":"2026-07-30T20:57:32.2863Z","steps":["trace[903404459] 'process raft request' (duration: 1.446759052s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:32.286421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:57:30.839229Z","time spent":"1.447127239s","remote":"127.0.0.1:54570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T20:57:32.286547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.939827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-30T20:57:32.28652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.927186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4321"} {"level":"warn","ts":"2026-07-30T20:57:32.286581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.315557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-30T20:57:32.286612Z","caller":"traceutil/trace.go:171","msg":"trace[122334916] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:297; }","duration":"620.056223ms","start":"2026-07-30T20:57:31.666541Z","end":"2026-07-30T20:57:32.286597Z","steps":["trace[122334916] 'agreement among raft nodes before linearized reading' (duration: 619.921527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:32.286643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:57:31.666515Z","time spent":"620.120796ms","remote":"127.0.0.1:54660","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":"info","ts":"2026-07-30T20:57:32.286647Z","caller":"traceutil/trace.go:171","msg":"trace[1176845308] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:297; }","duration":"149.431053ms","start":"2026-07-30T20:57:32.137199Z","end":"2026-07-30T20:57:32.28663Z","steps":["trace[1176845308] 'agreement among raft nodes before linearized reading' (duration: 149.274785ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:57:32.286625Z","caller":"traceutil/trace.go:171","msg":"trace[872803449] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:297; }","duration":"365.071503ms","start":"2026-07-30T20:57:31.921535Z","end":"2026-07-30T20:57:32.286607Z","steps":["trace[872803449] 'agreement among raft nodes before linearized reading' (duration: 364.803531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:32.286724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:57:31.921515Z","time spent":"365.193259ms","remote":"127.0.0.1:54550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4345,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-07-30T20:57:42.565285Z","caller":"traceutil/trace.go:171","msg":"trace[1928165346] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"160.826493ms","start":"2026-07-30T20:57:42.404391Z","end":"2026-07-30T20:57:42.565218Z","steps":["trace[1928165346] 'process raft request' (duration: 160.548998ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:57:42.565378Z","caller":"traceutil/trace.go:171","msg":"trace[1570414101] linearizableReadLoop","detail":"{readStateIndex:397; appliedIndex:396; }","duration":"141.357518ms","start":"2026-07-30T20:57:42.423738Z","end":"2026-07-30T20:57:42.565095Z","steps":["trace[1570414101] 'read index received' (duration: 141.122566ms)","trace[1570414101] 'applied index is now lower than readState.Index' (duration: 234.202µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:57:42.565413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.657884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-30T20:57:42.565546Z","caller":"traceutil/trace.go:171","msg":"trace[1414944580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:381; }","duration":"141.856735ms","start":"2026-07-30T20:57:42.423666Z","end":"2026-07-30T20:57:42.565523Z","steps":["trace[1414944580] 'agreement among raft nodes before linearized reading' (duration: 141.633923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:42.565611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.754907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-30T20:57:42.56569Z","caller":"traceutil/trace.go:171","msg":"trace[195711801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:381; }","duration":"125.876953ms","start":"2026-07-30T20:57:42.439793Z","end":"2026-07-30T20:57:42.56567Z","steps":["trace[195711801] 'agreement among raft nodes before linearized reading' (duration: 125.765087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:42.565816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.213526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-30T20:57:42.565859Z","caller":"traceutil/trace.go:171","msg":"trace[1883917635] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:381; }","duration":"124.25635ms","start":"2026-07-30T20:57:42.441593Z","end":"2026-07-30T20:57:42.565849Z","steps":["trace[1883917635] 'agreement among raft nodes before linearized reading' (duration: 124.184956ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:57:48.761382Z","caller":"traceutil/trace.go:171","msg":"trace[1614573642] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:411; }","duration":"140.921259ms","start":"2026-07-30T20:57:48.620428Z","end":"2026-07-30T20:57:48.761349Z","steps":["trace[1614573642] 'read index received' (duration: 140.647594ms)","trace[1614573642] 'applied index is now lower than readState.Index' (duration: 272.605µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:57:48.761465Z","caller":"traceutil/trace.go:171","msg":"trace[442638505] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"164.073483ms","start":"2026-07-30T20:57:48.597357Z","end":"2026-07-30T20:57:48.76143Z","steps":["trace[442638505] 'process raft request' (duration: 163.826869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:48.76158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.133901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-30T20:57:48.761691Z","caller":"traceutil/trace.go:171","msg":"trace[781002089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:395; }","duration":"141.262508ms","start":"2026-07-30T20:57:48.620361Z","end":"2026-07-30T20:57:48.761623Z","steps":["trace[781002089] 'agreement among raft nodes before linearized reading' (duration: 141.150932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:57:48.761744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.567955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-30T20:57:48.761821Z","caller":"traceutil/trace.go:171","msg":"trace[577244446] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:395; }","duration":"137.680971ms","start":"2026-07-30T20:57:48.624121Z","end":"2026-07-30T20:57:48.761802Z","steps":["trace[577244446] 'agreement among raft nodes before linearized reading' (duration: 137.47974ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:57:57.017233Z","caller":"traceutil/trace.go:171","msg":"trace[1405304616] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"123.168177ms","start":"2026-07-30T20:57:56.894025Z","end":"2026-07-30T20:57:57.017193Z","steps":["trace[1405304616] 'process raft request' (duration: 42.824756ms)","trace[1405304616] 'compare' (duration: 80.222154ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:57:57.080728Z","caller":"traceutil/trace.go:171","msg":"trace[1222919768] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"184.465922ms","start":"2026-07-30T20:57:56.896232Z","end":"2026-07-30T20:57:57.080698Z","steps":["trace[1222919768] 'process raft request' (duration: 184.42528ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:57:57.080754Z","caller":"traceutil/trace.go:171","msg":"trace[227471641] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"185.47124ms","start":"2026-07-30T20:57:56.895239Z","end":"2026-07-30T20:57:57.08071Z","steps":["trace[227471641] 'process raft request' (duration: 185.273529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:01.406065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.173313ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T20:58:01.406211Z","caller":"traceutil/trace.go:171","msg":"trace[168336350] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"264.647276ms","start":"2026-07-30T20:58:01.141529Z","end":"2026-07-30T20:58:01.406176Z","steps":["trace[168336350] 'process raft request' (duration: 59.07602ms)","trace[168336350] 'compare' (duration: 205.054946ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:06.325923Z","caller":"traceutil/trace.go:171","msg":"trace[352883296] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"153.426084ms","start":"2026-07-30T20:58:06.172471Z","end":"2026-07-30T20:58:06.325897Z","steps":["trace[352883296] 'process raft request' (duration: 153.307837ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:06.331703Z","caller":"traceutil/trace.go:171","msg":"trace[1530789514] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"154.291356ms","start":"2026-07-30T20:58:06.177389Z","end":"2026-07-30T20:58:06.331681Z","steps":["trace[1530789514] 'process raft request' (duration: 154.140427ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:06.331687Z","caller":"traceutil/trace.go:171","msg":"trace[1437877408] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"154.678469ms","start":"2026-07-30T20:58:06.176971Z","end":"2026-07-30T20:58:06.331649Z","steps":["trace[1437877408] 'process raft request' (duration: 154.258274ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:06.331816Z","caller":"traceutil/trace.go:171","msg":"trace[937405191] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"148.543116ms","start":"2026-07-30T20:58:06.183223Z","end":"2026-07-30T20:58:06.331766Z","steps":["trace[937405191] 'process raft request' (duration: 148.377906ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:06.331884Z","caller":"traceutil/trace.go:171","msg":"trace[210282543] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"148.44548ms","start":"2026-07-30T20:58:06.183408Z","end":"2026-07-30T20:58:06.331853Z","steps":["trace[210282543] 'process raft request' (duration: 148.256389ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:06.332028Z","caller":"traceutil/trace.go:171","msg":"trace[966076973] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"147.429528ms","start":"2026-07-30T20:58:06.184571Z","end":"2026-07-30T20:58:06.332Z","steps":["trace[966076973] 'process raft request' (duration: 147.160182ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:06.332167Z","caller":"traceutil/trace.go:171","msg":"trace[1930380419] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"146.543254ms","start":"2026-07-30T20:58:06.185616Z","end":"2026-07-30T20:58:06.332159Z","steps":["trace[1930380419] 'process raft request' (duration: 146.30686ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:06.332284Z","caller":"traceutil/trace.go:171","msg":"trace[1395437750] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"142.429464ms","start":"2026-07-30T20:58:06.189836Z","end":"2026-07-30T20:58:06.332265Z","steps":["trace[1395437750] 'process raft request' (duration: 142.216141ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:06.332307Z","caller":"traceutil/trace.go:171","msg":"trace[51872998] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"118.248991ms","start":"2026-07-30T20:58:06.214043Z","end":"2026-07-30T20:58:06.332292Z","steps":["trace[51872998] 'process raft request' (duration: 118.07138ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:06.332432Z","caller":"traceutil/trace.go:171","msg":"trace[692185527] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"145.582256ms","start":"2026-07-30T20:58:06.186821Z","end":"2026-07-30T20:58:06.332403Z","steps":["trace[692185527] 'process raft request' (duration: 145.16649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:06.919073Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601809110472532083,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T20:58:07.419757Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601809110472532083,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T20:58:07.782813Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.445305093s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-30T20:58:07.883577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.410534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T20:58:07.883808Z","caller":"traceutil/trace.go:171","msg":"trace[1898271765] transaction","detail":"{read_only:false; number_of_response:1; response_revision:514; }","duration":"1.54676387s","start":"2026-07-30T20:58:06.337028Z","end":"2026-07-30T20:58:07.883792Z","steps":["trace[1898271765] 'process raft request' (duration: 1.546657513s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:07.883884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:06.336991Z","time spent":"1.546857915s","remote":"127.0.0.1:54442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7367,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:58:07.884354Z","caller":"traceutil/trace.go:171","msg":"trace[1339672927] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.54791281s","start":"2026-07-30T20:58:06.335845Z","end":"2026-07-30T20:58:07.883758Z","steps":["trace[1339672927] 'process raft request' (duration: 1.44722877s)","trace[1339672927] 'compare' (duration: 99.773274ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:07.884519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:06.33583Z","time spent":"1.548607062s","remote":"127.0.0.1:54442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:58:07.888633Z","caller":"traceutil/trace.go:171","msg":"trace[650031915] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.541067564s","start":"2026-07-30T20:58:06.347534Z","end":"2026-07-30T20:58:07.888602Z","steps":["trace[650031915] 'process raft request' (duration: 1.540812789s)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:07.888934Z","caller":"traceutil/trace.go:171","msg":"trace[1273537181] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:533; }","duration":"1.470651846s","start":"2026-07-30T20:58:06.418255Z","end":"2026-07-30T20:58:07.888906Z","steps":["trace[1273537181] 'read index received' (duration: 1.364874094s)","trace[1273537181] 'applied index is now lower than readState.Index' (duration: 105.776842ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:07.889009Z","caller":"traceutil/trace.go:171","msg":"trace[185021471] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"1.543226346s","start":"2026-07-30T20:58:06.345762Z","end":"2026-07-30T20:58:07.888988Z","steps":["trace[185021471] 'process raft request' (duration: 1.542540474s)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:07.889061Z","caller":"traceutil/trace.go:171","msg":"trace[1155067209] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.539675559s","start":"2026-07-30T20:58:06.349376Z","end":"2026-07-30T20:58:07.889051Z","steps":["trace[1155067209] 'process raft request' (duration: 1.53903019s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:07.889116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:06.345738Z","time spent":"1.543319341s","remote":"127.0.0.1:54920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T20:58:07.889173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:06.349365Z","time spent":"1.539725343s","remote":"127.0.0.1:54920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T20:58:07.889267Z","caller":"traceutil/trace.go:171","msg":"trace[617144526] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.543753318s","start":"2026-07-30T20:58:06.345503Z","end":"2026-07-30T20:58:07.889256Z","steps":["trace[617144526] 'process raft request' (duration: 1.542635669s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:07.889328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:06.345492Z","time spent":"1.543814041s","remote":"127.0.0.1:54920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T20:58:07.889432Z","caller":"traceutil/trace.go:171","msg":"trace[1209235433] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.536993366s","start":"2026-07-30T20:58:06.352427Z","end":"2026-07-30T20:58:07.88942Z","steps":["trace[1209235433] 'process raft request' (duration: 1.536004576s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:07.889474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:06.352405Z","time spent":"1.53704937s","remote":"127.0.0.1:54920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T20:58:07.888954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:06.347513Z","time spent":"1.541187441s","remote":"127.0.0.1:54442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:58:07.88976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.4229396s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:58:07.889793Z","caller":"traceutil/trace.go:171","msg":"trace[1553589829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"1.423024846s","start":"2026-07-30T20:58:06.466758Z","end":"2026-07-30T20:58:07.889783Z","steps":["trace[1553589829] 'agreement among raft nodes before linearized reading' (duration: 1.422947691s)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:07.889784Z","caller":"traceutil/trace.go:171","msg":"trace[1719814140] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"1.532715665s","start":"2026-07-30T20:58:06.357049Z","end":"2026-07-30T20:58:07.889765Z","steps":["trace[1719814140] 'process raft request' (duration: 1.531422406s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:07.889818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:06.466734Z","time spent":"1.423077529s","remote":"127.0.0.1:54396","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-07-30T20:58:07.889866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:06.357035Z","time spent":"1.532783709s","remote":"127.0.0.1:54442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:58:07.889978Z","caller":"traceutil/trace.go:171","msg":"trace[250389444] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"1.532835902s","start":"2026-07-30T20:58:06.357131Z","end":"2026-07-30T20:58:07.889967Z","steps":["trace[250389444] 'process raft request' (duration: 1.531396835s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:07.890029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:06.357126Z","time spent":"1.532873465s","remote":"127.0.0.1:54442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:58:07.890069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.175262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-30T20:58:07.890098Z","caller":"traceutil/trace.go:171","msg":"trace[415078116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:523; }","duration":"443.244777ms","start":"2026-07-30T20:58:07.446845Z","end":"2026-07-30T20:58:07.890089Z","steps":["trace[415078116] 'agreement among raft nodes before linearized reading' (duration: 443.183993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:07.890119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:07.446824Z","time spent":"443.290009ms","remote":"127.0.0.1:54660","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-07-30T20:58:07.890165Z","caller":"traceutil/trace.go:171","msg":"trace[1876761518] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"1.53180808s","start":"2026-07-30T20:58:06.358338Z","end":"2026-07-30T20:58:07.890146Z","steps":["trace[1876761518] 'process raft request' (duration: 1.530240625s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:07.890278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.516559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-30T20:58:07.890303Z","caller":"traceutil/trace.go:171","msg":"trace[128345945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:523; }","duration":"711.574812ms","start":"2026-07-30T20:58:07.17872Z","end":"2026-07-30T20:58:07.890294Z","steps":["trace[128345945] 'agreement among raft nodes before linearized reading' (duration: 711.522699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:07.890324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:07.178696Z","time spent":"711.623366ms","remote":"127.0.0.1:54660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T20:58:07.890493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.529941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-30T20:58:07.890387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:06.358317Z","time spent":"1.531893236s","remote":"127.0.0.1:54442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:58:07.890531Z","caller":"traceutil/trace.go:171","msg":"trace[1407276229] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:523; }","duration":"729.605205ms","start":"2026-07-30T20:58:07.160917Z","end":"2026-07-30T20:58:07.890522Z","steps":["trace[1407276229] 'agreement among raft nodes before linearized reading' (duration: 729.485248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:07.890565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:07.160886Z","time spent":"729.666109ms","remote":"127.0.0.1:54660","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-07-30T20:58:07.890737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.858055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T20:58:07.890743Z","caller":"traceutil/trace.go:171","msg":"trace[273244463] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.527359119s","start":"2026-07-30T20:58:06.363364Z","end":"2026-07-30T20:58:07.890723Z","steps":["trace[273244463] 'process raft request' (duration: 1.525420761s)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:07.89077Z","caller":"traceutil/trace.go:171","msg":"trace[339397304] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:523; }","duration":"953.920808ms","start":"2026-07-30T20:58:06.936839Z","end":"2026-07-30T20:58:07.89076Z","steps":["trace[339397304] 'agreement among raft nodes before linearized reading' (duration: 953.823872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:07.890793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:06.93682Z","time spent":"953.968492ms","remote":"127.0.0.1:54660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T20:58:07.89101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.472751884s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.29\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-30T20:58:07.891061Z","caller":"traceutil/trace.go:171","msg":"trace[2589257] range","detail":"{range_begin:/registry/masterleases/199.19.213.29; range_end:; response_count:1; response_revision:523; }","duration":"1.472828869s","start":"2026-07-30T20:58:06.418217Z","end":"2026-07-30T20:58:07.891046Z","steps":["trace[2589257] 'agreement among raft nodes before linearized reading' (duration: 1.472731722s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:07.8911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:06.418197Z","time spent":"1.472893584s","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.29\" "} {"level":"warn","ts":"2026-07-30T20:58:07.891044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:06.363341Z","time spent":"1.527447445s","remote":"127.0.0.1:54442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T20:58:08.365819Z","caller":"traceutil/trace.go:171","msg":"trace[2035435865] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"123.903914ms","start":"2026-07-30T20:58:08.241875Z","end":"2026-07-30T20:58:08.365779Z","steps":["trace[2035435865] 'process raft request' (duration: 94.148025ms)","trace[2035435865] 'compare' (duration: 29.579338ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:08.369912Z","caller":"traceutil/trace.go:171","msg":"trace[1550394131] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"120.465762ms","start":"2026-07-30T20:58:08.249413Z","end":"2026-07-30T20:58:08.369879Z","steps":["trace[1550394131] 'process raft request' (duration: 120.357326ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:08.369964Z","caller":"traceutil/trace.go:171","msg":"trace[884802638] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"121.568981ms","start":"2026-07-30T20:58:08.248363Z","end":"2026-07-30T20:58:08.369932Z","steps":["trace[884802638] 'process raft request' (duration: 120.90728ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:09.433525Z","caller":"traceutil/trace.go:171","msg":"trace[1402219695] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"112.318563ms","start":"2026-07-30T20:58:09.321148Z","end":"2026-07-30T20:58:09.433466Z","steps":["trace[1402219695] 'read index received' (duration: 103.222662ms)","trace[1402219695] 'applied index is now lower than readState.Index' (duration: 9.093941ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:09.433689Z","caller":"traceutil/trace.go:171","msg":"trace[1843719032] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"113.947235ms","start":"2026-07-30T20:58:09.319676Z","end":"2026-07-30T20:58:09.433623Z","steps":["trace[1843719032] 'process raft request' (duration: 104.661972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:09.433898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.708167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31126"} {"level":"info","ts":"2026-07-30T20:58:09.433966Z","caller":"traceutil/trace.go:171","msg":"trace[1147996697] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:581; }","duration":"112.814023ms","start":"2026-07-30T20:58:09.321133Z","end":"2026-07-30T20:58:09.433947Z","steps":["trace[1147996697] 'agreement among raft nodes before linearized reading' (duration: 112.545247ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:11.581994Z","caller":"traceutil/trace.go:171","msg":"trace[679558340] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"115.522242ms","start":"2026-07-30T20:58:11.466452Z","end":"2026-07-30T20:58:11.581974Z","steps":["trace[679558340] 'read index received' (duration: 114.346209ms)","trace[679558340] 'applied index is now lower than readState.Index' (duration: 1.175053ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:11.582141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.684392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:58:11.582125Z","caller":"traceutil/trace.go:171","msg":"trace[1972866544] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"201.855038ms","start":"2026-07-30T20:58:11.380192Z","end":"2026-07-30T20:58:11.582047Z","steps":["trace[1972866544] 'process raft request' (duration: 201.283362ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:11.582181Z","caller":"traceutil/trace.go:171","msg":"trace[856650095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"115.796349ms","start":"2026-07-30T20:58:11.466373Z","end":"2026-07-30T20:58:11.58217Z","steps":["trace[856650095] 'agreement among raft nodes before linearized reading' (duration: 115.680502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:12.465029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.228786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T20:58:12.465269Z","caller":"traceutil/trace.go:171","msg":"trace[1738165985] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"505.502112ms","start":"2026-07-30T20:58:11.959731Z","end":"2026-07-30T20:58:12.465233Z","steps":["trace[1738165985] 'process raft request' (duration: 158.933604ms)","trace[1738165985] 'compare' (duration: 346.012943ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:12.465363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:11.959699Z","time spent":"505.624759ms","remote":"127.0.0.1:54660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:58:12.46836Z","caller":"traceutil/trace.go:171","msg":"trace[1645456662] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"508.50704ms","start":"2026-07-30T20:58:11.95982Z","end":"2026-07-30T20:58:12.468327Z","steps":["trace[1645456662] 'process raft request' (duration: 508.296397ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:12.468464Z","caller":"traceutil/trace.go:171","msg":"trace[1991496149] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"507.512058ms","start":"2026-07-30T20:58:11.960925Z","end":"2026-07-30T20:58:12.468437Z","steps":["trace[1991496149] 'process raft request' (duration: 507.293644ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:12.468609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:11.960898Z","time spent":"507.638756ms","remote":"127.0.0.1:54660","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-07-30T20:58:12.468605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:11.959789Z","time spent":"508.651319ms","remote":"127.0.0.1:54660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:58:12.469306Z","caller":"traceutil/trace.go:171","msg":"trace[23560501] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"416.272176ms","start":"2026-07-30T20:58:12.053015Z","end":"2026-07-30T20:58:12.469287Z","steps":["trace[23560501] 'process raft request' (duration: 415.5443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:12.469401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:12.052986Z","time spent":"416.367542ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:58:13.130206Z","caller":"traceutil/trace.go:171","msg":"trace[702168363] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"186.46127ms","start":"2026-07-30T20:58:12.943726Z","end":"2026-07-30T20:58:13.130187Z","steps":["trace[702168363] 'process raft request' (duration: 185.9914ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:13.845136Z","caller":"traceutil/trace.go:171","msg":"trace[1691320914] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"227.252986ms","start":"2026-07-30T20:58:13.617866Z","end":"2026-07-30T20:58:13.845119Z","steps":["trace[1691320914] 'process raft request' (duration: 226.35282ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:14.175453Z","caller":"traceutil/trace.go:171","msg":"trace[1406722629] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"215.540724ms","start":"2026-07-30T20:58:13.959885Z","end":"2026-07-30T20:58:14.175425Z","steps":["trace[1406722629] 'read index received' (duration: 143.648702ms)","trace[1406722629] 'applied index is now lower than readState.Index' (duration: 71.891052ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:14.175491Z","caller":"traceutil/trace.go:171","msg":"trace[236914484] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"272.96495ms","start":"2026-07-30T20:58:13.90251Z","end":"2026-07-30T20:58:14.175475Z","steps":["trace[236914484] 'process raft request' (duration: 201.129042ms)","trace[236914484] 'compare' (duration: 71.656857ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:14.175636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.745507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-30T20:58:14.175663Z","caller":"traceutil/trace.go:171","msg":"trace[1292707278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:600; }","duration":"215.816191ms","start":"2026-07-30T20:58:13.959838Z","end":"2026-07-30T20:58:14.175654Z","steps":["trace[1292707278] 'agreement among raft nodes before linearized reading' (duration: 215.684683ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:14.710445Z","caller":"traceutil/trace.go:171","msg":"trace[1816965420] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"236.3831ms","start":"2026-07-30T20:58:14.474035Z","end":"2026-07-30T20:58:14.710418Z","steps":["trace[1816965420] 'process raft request' (duration: 177.407552ms)","trace[1816965420] 'compare' (duration: 58.769705ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:14.710681Z","caller":"traceutil/trace.go:171","msg":"trace[1660032110] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"157.620814ms","start":"2026-07-30T20:58:14.553013Z","end":"2026-07-30T20:58:14.710634Z","steps":["trace[1660032110] 'process raft request' (duration: 157.363498ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:14.885294Z","caller":"traceutil/trace.go:171","msg":"trace[925854121] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"231.728416ms","start":"2026-07-30T20:58:14.653528Z","end":"2026-07-30T20:58:14.885256Z","steps":["trace[925854121] 'process raft request' (duration: 162.173842ms)","trace[925854121] 'compare' (duration: 69.154529ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:16.72367Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601809110472532268,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T20:58:16.869581Z","caller":"traceutil/trace.go:171","msg":"trace[1307803094] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"646.499416ms","start":"2026-07-30T20:58:16.223047Z","end":"2026-07-30T20:58:16.869546Z","steps":["trace[1307803094] 'read index received' (duration: 644.894945ms)","trace[1307803094] 'applied index is now lower than readState.Index' (duration: 1.603221ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:16.869688Z","caller":"traceutil/trace.go:171","msg":"trace[285125259] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"867.984624ms","start":"2026-07-30T20:58:16.001669Z","end":"2026-07-30T20:58:16.869654Z","steps":["trace[285125259] 'process raft request' (duration: 866.49838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:16.869914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.332349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gk2qc\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-30T20:58:16.869981Z","caller":"traceutil/trace.go:171","msg":"trace[1348513346] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gk2qc; range_end:; response_count:1; response_revision:613; }","duration":"519.452977ms","start":"2026-07-30T20:58:16.35051Z","end":"2026-07-30T20:58:16.869963Z","steps":["trace[1348513346] 'agreement among raft nodes before linearized reading' (duration: 519.321069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:16.870033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:16.350488Z","time spent":"519.528842ms","remote":"127.0.0.1:54550","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-gk2qc\" "} {"level":"warn","ts":"2026-07-30T20:58:16.870026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:16.001596Z","time spent":"868.182927ms","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:58:16.870032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.967306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-30T20:58:16.870101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.527227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-30T20:58:16.870124Z","caller":"traceutil/trace.go:171","msg":"trace[393258516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:613; }","duration":"647.137407ms","start":"2026-07-30T20:58:16.222965Z","end":"2026-07-30T20:58:16.870102Z","steps":["trace[393258516] 'agreement among raft nodes before linearized reading' (duration: 646.742361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:16.870107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.079952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-30T20:58:16.870177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:16.222939Z","time spent":"647.229012ms","remote":"127.0.0.1:54660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T20:58:16.870196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.190932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T20:58:16.870278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.46817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-30T20:58:16.870241Z","caller":"traceutil/trace.go:171","msg":"trace[349503611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:613; }","duration":"155.252843ms","start":"2026-07-30T20:58:16.71495Z","end":"2026-07-30T20:58:16.870203Z","steps":["trace[349503611] 'agreement among raft nodes before linearized reading' (duration: 154.904221ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:16.870333Z","caller":"traceutil/trace.go:171","msg":"trace[983580908] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:613; }","duration":"318.559436ms","start":"2026-07-30T20:58:16.551761Z","end":"2026-07-30T20:58:16.870321Z","steps":["trace[983580908] 'agreement among raft nodes before linearized reading' (duration: 318.445319ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:16.87017Z","caller":"traceutil/trace.go:171","msg":"trace[1379206334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:613; }","duration":"154.622872ms","start":"2026-07-30T20:58:16.71553Z","end":"2026-07-30T20:58:16.870152Z","steps":["trace[1379206334] 'agreement among raft nodes before linearized reading' (duration: 154.398218ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:16.870373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:16.55174Z","time spent":"318.62616ms","remote":"127.0.0.1:54660","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":"info","ts":"2026-07-30T20:58:16.870367Z","caller":"traceutil/trace.go:171","msg":"trace[515210364] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"403.439837ms","start":"2026-07-30T20:58:16.466879Z","end":"2026-07-30T20:58:16.870318Z","steps":["trace[515210364] 'agreement among raft nodes before linearized reading' (duration: 403.121457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:16.870436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:16.46685Z","time spent":"403.574946ms","remote":"127.0.0.1:54400","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-07-30T20:58:16.870597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.18653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.29\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-30T20:58:16.870756Z","caller":"traceutil/trace.go:171","msg":"trace[425417096] range","detail":"{range_begin:/registry/masterleases/199.19.213.29; range_end:; response_count:1; response_revision:613; }","duration":"451.423395ms","start":"2026-07-30T20:58:16.419288Z","end":"2026-07-30T20:58:16.870712Z","steps":["trace[425417096] 'agreement among raft nodes before linearized reading' (duration: 450.860469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:16.870822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:16.41926Z","time spent":"451.551333ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.29\" "} {"level":"warn","ts":"2026-07-30T20:58:17.732406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.268327ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-07-30T20:58:17.732579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:16.872493Z","time spent":"860.079088ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-30T20:58:18.413611Z","caller":"traceutil/trace.go:171","msg":"trace[12364861] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"1.536921923s","start":"2026-07-30T20:58:16.87665Z","end":"2026-07-30T20:58:18.413572Z","steps":["trace[12364861] 'process raft request' (duration: 1.520522473s)","trace[12364861] 'compare' (duration: 15.664202ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:18.413877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:16.876627Z","time spent":"1.537124736s","remote":"127.0.0.1:54660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:58:18.414144Z","caller":"traceutil/trace.go:171","msg":"trace[176135948] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"1.537389723s","start":"2026-07-30T20:58:16.876718Z","end":"2026-07-30T20:58:18.414108Z","steps":["trace[176135948] 'process raft request' (duration: 1.536774854s)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:18.414152Z","caller":"traceutil/trace.go:171","msg":"trace[1778045559] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"1.537313878s","start":"2026-07-30T20:58:16.87681Z","end":"2026-07-30T20:58:18.414124Z","steps":["trace[1778045559] 'process raft request' (duration: 1.537013249s)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:18.414264Z","caller":"traceutil/trace.go:171","msg":"trace[126902187] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"1.536933864s","start":"2026-07-30T20:58:16.877296Z","end":"2026-07-30T20:58:18.41423Z","steps":["trace[126902187] 'process raft request' (duration: 1.536640126s)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:18.414309Z","caller":"traceutil/trace.go:171","msg":"trace[1521288337] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"1.524709177s","start":"2026-07-30T20:58:16.889561Z","end":"2026-07-30T20:58:18.41427Z","steps":["trace[1521288337] 'process raft request' (duration: 1.524551807s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:18.414391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:16.876691Z","time spent":"1.537554994s","remote":"127.0.0.1:54660","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-07-30T20:58:18.41441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:16.876771Z","time spent":"1.537571045s","remote":"127.0.0.1:54660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:58:18.414434Z","caller":"traceutil/trace.go:171","msg":"trace[37524459] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:638; }","duration":"946.485266ms","start":"2026-07-30T20:58:17.46793Z","end":"2026-07-30T20:58:18.414415Z","steps":["trace[37524459] 'read index received' (duration: 131.367426ms)","trace[37524459] 'applied index is now lower than readState.Index' (duration: 815.11611ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:18.414482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:16.889529Z","time spent":"1.524868517s","remote":"127.0.0.1:54550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:58:18.414531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:16.877257Z","time spent":"1.537127176s","remote":"127.0.0.1:54660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:58:18.414535Z","caller":"traceutil/trace.go:171","msg":"trace[544708831] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"1.486417876s","start":"2026-07-30T20:58:16.928069Z","end":"2026-07-30T20:58:18.414487Z","steps":["trace[544708831] 'process raft request' (duration: 1.486134898s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:18.414597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.669768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:58:18.41464Z","caller":"traceutil/trace.go:171","msg":"trace[186943763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"946.747823ms","start":"2026-07-30T20:58:17.46788Z","end":"2026-07-30T20:58:18.414628Z","steps":["trace[186943763] 'agreement among raft nodes before linearized reading' (duration: 946.602544ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:18.414684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:17.467856Z","time spent":"946.821458ms","remote":"127.0.0.1:54396","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-07-30T20:58:18.414724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:16.92805Z","time spent":"1.486582316s","remote":"127.0.0.1:54912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T20:58:19.060581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.070416ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T20:58:19.060761Z","caller":"traceutil/trace.go:171","msg":"trace[1757737971] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"643.551189ms","start":"2026-07-30T20:58:18.417185Z","end":"2026-07-30T20:58:19.060737Z","steps":["trace[1757737971] 'read index received' (duration: 138.051044ms)","trace[1757737971] 'applied index is now lower than readState.Index' (duration: 505.497485ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:19.060896Z","caller":"traceutil/trace.go:171","msg":"trace[770454830] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"1.326349153s","start":"2026-07-30T20:58:17.734533Z","end":"2026-07-30T20:58:19.060882Z","steps":["trace[770454830] 'process raft request' (duration: 820.803976ms)","trace[770454830] 'compare' (duration: 504.745935ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:19.060973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:17.734497Z","time spent":"1.32644018s","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:58:19.061095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.879101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T20:58:19.061114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.008259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:58:19.061207Z","caller":"traceutil/trace.go:171","msg":"trace[1445254586] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"643.986337ms","start":"2026-07-30T20:58:18.417178Z","end":"2026-07-30T20:58:19.061164Z","steps":["trace[1445254586] 'agreement among raft nodes before linearized reading' (duration: 643.784444ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:19.061231Z","caller":"traceutil/trace.go:171","msg":"trace[32096848] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:620; }","duration":"634.147978ms","start":"2026-07-30T20:58:18.427054Z","end":"2026-07-30T20:58:19.061202Z","steps":["trace[32096848] 'agreement among raft nodes before linearized reading' (duration: 633.935594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:19.061247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:18.417123Z","time spent":"644.117405ms","remote":"127.0.0.1:54400","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-07-30T20:58:19.061235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.234109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gk2qc\" ","response":"range_response_count:1 size:17145"} {"level":"warn","ts":"2026-07-30T20:58:19.061293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:18.427047Z","time spent":"634.230414ms","remote":"127.0.0.1:54618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-07-30T20:58:19.061345Z","caller":"traceutil/trace.go:171","msg":"trace[1747985795] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gk2qc; range_end:; response_count:1; response_revision:620; }","duration":"633.40851ms","start":"2026-07-30T20:58:18.427909Z","end":"2026-07-30T20:58:19.061317Z","steps":["trace[1747985795] 'agreement among raft nodes before linearized reading' (duration: 633.09694ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:19.061402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:18.427882Z","time spent":"633.509016ms","remote":"127.0.0.1:54550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17169,"request content":"key:\"/registry/pods/kube-system/cilium-gk2qc\" "} {"level":"warn","ts":"2026-07-30T20:58:19.965887Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601809110472532287,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T20:58:20.466408Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601809110472532287,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T20:58:20.96668Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601809110472532287,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T20:58:21.464719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999858883s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-30T20:58:21.464799Z","caller":"traceutil/trace.go:171","msg":"trace[1972714910] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000007352s","start":"2026-07-30T20:58:19.464773Z","end":"2026-07-30T20:58:21.46478Z","steps":["trace[1972714910] 'agreement among raft nodes before linearized reading' (duration: 1.999911386s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:21.464857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:19.464754Z","time spent":"2.000092698s","remote":"127.0.0.1:54400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/30 20:58:21 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-30T20:58:21.466811Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601809110472532287,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T20:58:21.723831Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.273337477s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T20:58:21.725081Z","caller":"traceutil/trace.go:171","msg":"trace[2133011322] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"2.27616898s","start":"2026-07-30T20:58:19.448885Z","end":"2026-07-30T20:58:21.725054Z","steps":["trace[2133011322] 'process raft request' (duration: 2.275398939s)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:21.725439Z","caller":"traceutil/trace.go:171","msg":"trace[1411766664] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"2.257373212s","start":"2026-07-30T20:58:19.46786Z","end":"2026-07-30T20:58:21.725234Z","steps":["trace[1411766664] 'process raft request' (duration: 2.257241134s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:21.725459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:19.448801Z","time spent":"2.276351181s","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:58:21.725561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:19.467822Z","time spent":"2.257668641s","remote":"127.0.0.1:54660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:58:21.725795Z","caller":"traceutil/trace.go:171","msg":"trace[689465017] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"257.846209ms","start":"2026-07-30T20:58:21.467917Z","end":"2026-07-30T20:58:21.725764Z","steps":["trace[689465017] 'process raft request' (duration: 257.291314ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:21.725767Z","caller":"traceutil/trace.go:171","msg":"trace[997050512] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:646; }","duration":"2.260875517s","start":"2026-07-30T20:58:19.46484Z","end":"2026-07-30T20:58:21.725716Z","steps":["trace[997050512] 'read index received' (duration: 2.259213149s)","trace[997050512] 'applied index is now lower than readState.Index' (duration: 1.661168ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:21.726118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.743044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T20:58:21.726182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.306237874s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-30T20:58:21.726193Z","caller":"traceutil/trace.go:171","msg":"trace[286339085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"253.872063ms","start":"2026-07-30T20:58:21.472292Z","end":"2026-07-30T20:58:21.726164Z","steps":["trace[286339085] 'agreement among raft nodes before linearized reading' (duration: 253.736534ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:21.726243Z","caller":"traceutil/trace.go:171","msg":"trace[658998174] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"1.30634069s","start":"2026-07-30T20:58:20.419886Z","end":"2026-07-30T20:58:21.726227Z","steps":["trace[658998174] 'agreement among raft nodes before linearized reading' (duration: 1.306198921s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:21.726293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:20.419858Z","time spent":"1.306423636s","remote":"127.0.0.1:54660","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-07-30T20:58:21.726315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.306384543s","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-07-30T20:58:21.726174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.3064946s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T20:58:21.726386Z","caller":"traceutil/trace.go:171","msg":"trace[338630819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"1.306784849s","start":"2026-07-30T20:58:20.419593Z","end":"2026-07-30T20:58:21.726378Z","steps":["trace[338630819] 'agreement among raft nodes before linearized reading' (duration: 1.306391154s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:21.72634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.30632304s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-30T20:58:21.726485Z","caller":"traceutil/trace.go:171","msg":"trace[1383678584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"1.306537893s","start":"2026-07-30T20:58:20.41991Z","end":"2026-07-30T20:58:21.726448Z","steps":["trace[1383678584] 'agreement among raft nodes before linearized reading' (duration: 1.306098685s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:21.726555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:20.419889Z","time spent":"1.306655681s","remote":"127.0.0.1:54660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-30T20:58:21.726388Z","caller":"traceutil/trace.go:171","msg":"trace[480136155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:623; }","duration":"1.306463018s","start":"2026-07-30T20:58:20.419902Z","end":"2026-07-30T20:58:21.726365Z","steps":["trace[480136155] 'agreement among raft nodes before linearized reading' (duration: 1.306323509s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:21.72666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:20.419893Z","time spent":"1.306749027s","remote":"127.0.0.1:54660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T20:58:21.72642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:20.419551Z","time spent":"1.306860574s","remote":"127.0.0.1:54660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T20:58:22.683322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.754609ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:58:22.683477Z","caller":"traceutil/trace.go:171","msg":"trace[671053574] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:637; }","duration":"116.92161ms","start":"2026-07-30T20:58:22.566524Z","end":"2026-07-30T20:58:22.683446Z","steps":["trace[671053574] 'range keys from in-memory index tree' (duration: 116.732478ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:23.579571Z","caller":"traceutil/trace.go:171","msg":"trace[1829733927] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"206.906376ms","start":"2026-07-30T20:58:23.372625Z","end":"2026-07-30T20:58:23.579532Z","steps":["trace[1829733927] 'process raft request' (duration: 144.925118ms)","trace[1829733927] 'compare' (duration: 61.85587ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:23.58024Z","caller":"traceutil/trace.go:171","msg":"trace[1413360410] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:667; }","duration":"195.725137ms","start":"2026-07-30T20:58:23.384481Z","end":"2026-07-30T20:58:23.580206Z","steps":["trace[1413360410] 'read index received' (duration: 133.084587ms)","trace[1413360410] 'applied index is now lower than readState.Index' (duration: 62.63927ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:23.580348Z","caller":"traceutil/trace.go:171","msg":"trace[1384442639] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"202.389331ms","start":"2026-07-30T20:58:23.377918Z","end":"2026-07-30T20:58:23.580307Z","steps":["trace[1384442639] 'process raft request' (duration: 202.05987ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:23.580467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.050962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T20:58:23.580407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.9091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-30T20:58:23.580586Z","caller":"traceutil/trace.go:171","msg":"trace[1414845239] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:642; }","duration":"196.124443ms","start":"2026-07-30T20:58:23.384431Z","end":"2026-07-30T20:58:23.580555Z","steps":["trace[1414845239] 'agreement among raft nodes before linearized reading' (duration: 195.903509ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:23.580518Z","caller":"traceutil/trace.go:171","msg":"trace[167735764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"113.16794ms","start":"2026-07-30T20:58:23.467337Z","end":"2026-07-30T20:58:23.580505Z","steps":["trace[167735764] 'agreement among raft nodes before linearized reading' (duration: 113.053882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:24.264139Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601809110472532356,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T20:58:24.317922Z","caller":"traceutil/trace.go:171","msg":"trace[1179902078] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:694; }","duration":"554.839923ms","start":"2026-07-30T20:58:23.763048Z","end":"2026-07-30T20:58:24.317888Z","steps":["trace[1179902078] 'read index received' (duration: 549.638373ms)","trace[1179902078] 'applied index is now lower than readState.Index' (duration: 5.19968ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:24.317951Z","caller":"traceutil/trace.go:171","msg":"trace[716611586] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"561.470475ms","start":"2026-07-30T20:58:23.756458Z","end":"2026-07-30T20:58:24.317928Z","steps":["trace[716611586] 'process raft request' (duration: 556.232852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:24.318121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:23.756421Z","time spent":"561.635145ms","remote":"127.0.0.1:54660","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-07-30T20:58:24.318141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.079129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-30T20:58:24.31838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.63947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T20:58:24.318416Z","caller":"traceutil/trace.go:171","msg":"trace[1792856243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:666; }","duration":"555.374158ms","start":"2026-07-30T20:58:23.763021Z","end":"2026-07-30T20:58:24.318395Z","steps":["trace[1792856243] 'agreement among raft nodes before linearized reading' (duration: 554.972592ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:24.318255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.555605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-30T20:58:24.318473Z","caller":"traceutil/trace.go:171","msg":"trace[1013592934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:666; }","duration":"554.832873ms","start":"2026-07-30T20:58:23.763631Z","end":"2026-07-30T20:58:24.318464Z","steps":["trace[1013592934] 'agreement among raft nodes before linearized reading' (duration: 554.452638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:24.318478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:23.763001Z","time spent":"555.462924ms","remote":"127.0.0.1:54660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T20:58:24.318503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:23.763614Z","time spent":"554.880966ms","remote":"127.0.0.1:54660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-30T20:58:24.318438Z","caller":"traceutil/trace.go:171","msg":"trace[468928408] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:666; }","duration":"554.729636ms","start":"2026-07-30T20:58:23.763693Z","end":"2026-07-30T20:58:24.318423Z","steps":["trace[468928408] 'agreement among raft nodes before linearized reading' (duration: 554.591567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:24.318551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:23.763663Z","time spent":"554.873905ms","remote":"127.0.0.1:54660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T20:58:24.318306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.678598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T20:58:24.318669Z","caller":"traceutil/trace.go:171","msg":"trace[1462517217] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:666; }","duration":"288.119017ms","start":"2026-07-30T20:58:24.030543Z","end":"2026-07-30T20:58:24.318662Z","steps":["trace[1462517217] 'agreement among raft nodes before linearized reading' (duration: 287.609514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:24.318303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.389441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:58:24.318806Z","caller":"traceutil/trace.go:171","msg":"trace[69366146] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:666; }","duration":"497.950628ms","start":"2026-07-30T20:58:23.820839Z","end":"2026-07-30T20:58:24.31879Z","steps":["trace[69366146] 'agreement among raft nodes before linearized reading' (duration: 497.385121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:24.318839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:23.820816Z","time spent":"498.013481ms","remote":"127.0.0.1:54442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-07-30T20:58:24.318351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.904271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:58:24.318878Z","caller":"traceutil/trace.go:171","msg":"trace[1638525195] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:666; }","duration":"297.489829ms","start":"2026-07-30T20:58:24.021377Z","end":"2026-07-30T20:58:24.318866Z","steps":["trace[1638525195] 'agreement among raft nodes before linearized reading' (duration: 296.936933ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:26.812272Z","caller":"traceutil/trace.go:171","msg":"trace[786416220] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"185.145557ms","start":"2026-07-30T20:58:26.627096Z","end":"2026-07-30T20:58:26.812242Z","steps":["trace[786416220] 'process raft request' (duration: 184.966865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:34.888591Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601809110472532637,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T20:58:35.258405Z","caller":"traceutil/trace.go:171","msg":"trace[145753222] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:797; }","duration":"870.361059ms","start":"2026-07-30T20:58:34.388014Z","end":"2026-07-30T20:58:35.258376Z","steps":["trace[145753222] 'read index received' (duration: 870.054208ms)","trace[145753222] 'applied index is now lower than readState.Index' (duration: 305.291µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:35.258454Z","caller":"traceutil/trace.go:171","msg":"trace[116038754] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"881.132154ms","start":"2026-07-30T20:58:34.377289Z","end":"2026-07-30T20:58:35.258421Z","steps":["trace[116038754] 'process raft request' (duration: 880.887117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:35.25862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:34.377264Z","time spent":"881.269283ms","remote":"127.0.0.1:54660","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-07-30T20:58:35.258697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.392869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:58:35.258758Z","caller":"traceutil/trace.go:171","msg":"trace[835769209] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"792.534618ms","start":"2026-07-30T20:58:34.466209Z","end":"2026-07-30T20:58:35.258743Z","steps":["trace[835769209] 'agreement among raft nodes before linearized reading' (duration: 792.41441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:35.258782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.274805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-84pct\" ","response":"range_response_count:1 size:5049"} {"level":"warn","ts":"2026-07-30T20:58:35.258803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:34.466163Z","time spent":"792.632976ms","remote":"127.0.0.1:54396","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-07-30T20:58:35.258754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.720113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T20:58:35.25883Z","caller":"traceutil/trace.go:171","msg":"trace[2134185693] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-84pct; range_end:; response_count:1; response_revision:765; }","duration":"806.358921ms","start":"2026-07-30T20:58:34.45246Z","end":"2026-07-30T20:58:35.258819Z","steps":["trace[2134185693] 'agreement among raft nodes before linearized reading' (duration: 806.191198ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:35.258871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:34.452441Z","time spent":"806.421205ms","remote":"127.0.0.1:54550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5073,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-84pct\" "} {"level":"info","ts":"2026-07-30T20:58:35.258885Z","caller":"traceutil/trace.go:171","msg":"trace[1543746034] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:765; }","duration":"870.884605ms","start":"2026-07-30T20:58:34.387958Z","end":"2026-07-30T20:58:35.258843Z","steps":["trace[1543746034] 'agreement among raft nodes before linearized reading' (duration: 870.541731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:35.258903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.146873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-30T20:58:35.259003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.179676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-30T20:58:35.258967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:34.38792Z","time spent":"871.012373ms","remote":"127.0.0.1:54660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-30T20:58:35.259147Z","caller":"traceutil/trace.go:171","msg":"trace[1014396480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:765; }","duration":"867.342076ms","start":"2026-07-30T20:58:34.391768Z","end":"2026-07-30T20:58:35.25911Z","steps":["trace[1014396480] 'agreement among raft nodes before linearized reading' (duration: 866.96155ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T20:58:35.259033Z","caller":"traceutil/trace.go:171","msg":"trace[1207416296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:765; }","duration":"867.328035ms","start":"2026-07-30T20:58:34.391679Z","end":"2026-07-30T20:58:35.259007Z","steps":["trace[1207416296] 'agreement among raft nodes before linearized reading' (duration: 866.941258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:35.259221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:34.391759Z","time spent":"867.447444ms","remote":"127.0.0.1:54660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T20:58:35.259236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:34.391666Z","time spent":"867.55405ms","remote":"127.0.0.1:54660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T20:58:35.531351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.419109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T20:58:35.531501Z","caller":"traceutil/trace.go:171","msg":"trace[1966870057] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"225.332621ms","start":"2026-07-30T20:58:35.306144Z","end":"2026-07-30T20:58:35.531477Z","steps":["trace[1966870057] 'process raft request' (duration: 76.665206ms)","trace[1966870057] 'compare' (duration: 148.270838ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:35.893582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.789436ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T20:58:35.893906Z","caller":"traceutil/trace.go:171","msg":"trace[1120273803] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"586.679201ms","start":"2026-07-30T20:58:35.307175Z","end":"2026-07-30T20:58:35.893855Z","steps":["trace[1120273803] 'process raft request' (duration: 470.387602ms)","trace[1120273803] 'compare' (duration: 115.643446ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T20:58:35.894049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.020694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:58:35.893924Z","caller":"traceutil/trace.go:171","msg":"trace[1384851756] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:804; }","duration":"426.869165ms","start":"2026-07-30T20:58:35.467034Z","end":"2026-07-30T20:58:35.893903Z","steps":["trace[1384851756] 'read index received' (duration: 310.410764ms)","trace[1384851756] 'applied index is now lower than readState.Index' (duration: 116.4569ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:35.894108Z","caller":"traceutil/trace.go:171","msg":"trace[1713867211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:773; }","duration":"427.144472ms","start":"2026-07-30T20:58:35.466948Z","end":"2026-07-30T20:58:35.894093Z","steps":["trace[1713867211] 'agreement among raft nodes before linearized reading' (duration: 427.011973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:35.894097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:35.307165Z","time spent":"586.837112ms","remote":"127.0.0.1:54534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":781,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T20:58:35.894086Z","caller":"traceutil/trace.go:171","msg":"trace[653468347] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"584.503514ms","start":"2026-07-30T20:58:35.309531Z","end":"2026-07-30T20:58:35.894034Z","steps":["trace[653468347] 'process raft request' (duration: 584.253168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:35.894151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:35.466909Z","time spent":"427.231768ms","remote":"127.0.0.1:54396","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-07-30T20:58:35.894376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:35.309511Z","time spent":"584.695128ms","remote":"127.0.0.1:54900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3852,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T20:58:35.894372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.009181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-30T20:58:35.894458Z","caller":"traceutil/trace.go:171","msg":"trace[288821940] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:773; }","duration":"188.15277ms","start":"2026-07-30T20:58:35.706282Z","end":"2026-07-30T20:58:35.894434Z","steps":["trace[288821940] 'agreement among raft nodes before linearized reading' (duration: 187.929105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:39.803777Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601809110472532691,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T20:58:40.304177Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5601809110472532691,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T20:58:40.586255Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.303913343s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T20:58:40.586758Z","caller":"traceutil/trace.go:171","msg":"trace[470191038] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"1.283852185s","start":"2026-07-30T20:58:39.302881Z","end":"2026-07-30T20:58:40.586733Z","steps":["trace[470191038] 'read index received' (duration: 1.283527433s)","trace[470191038] 'applied index is now lower than readState.Index' (duration: 323.902µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T20:58:40.586895Z","caller":"traceutil/trace.go:171","msg":"trace[1520404789] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"1.304655933s","start":"2026-07-30T20:58:39.282213Z","end":"2026-07-30T20:58:40.586869Z","steps":["trace[1520404789] 'process raft request' (duration: 1.304297959s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:40.587002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.284097181s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-30T20:58:40.587045Z","caller":"traceutil/trace.go:171","msg":"trace[1736953445] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:790; }","duration":"1.284201938s","start":"2026-07-30T20:58:39.302832Z","end":"2026-07-30T20:58:40.587034Z","steps":["trace[1736953445] 'agreement among raft nodes before linearized reading' (duration: 1.284028007s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:40.587038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.276890759s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-30T20:58:40.587041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:39.282191Z","time spent":"1.304751189s","remote":"127.0.0.1:54660","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-07-30T20:58:40.587082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:39.302813Z","time spent":"1.284261542s","remote":"127.0.0.1:54660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-30T20:58:40.587088Z","caller":"traceutil/trace.go:171","msg":"trace[1112041697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:790; }","duration":"1.276968414s","start":"2026-07-30T20:58:39.310107Z","end":"2026-07-30T20:58:40.587075Z","steps":["trace[1112041697] 'agreement among raft nodes before linearized reading' (duration: 1.276832465s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:40.587122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:39.310096Z","time spent":"1.277018088s","remote":"127.0.0.1:54660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T20:58:40.587159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1112107s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-h892f\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-07-30T20:58:40.58724Z","caller":"traceutil/trace.go:171","msg":"trace[887210450] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-h892f; range_end:; response_count:1; response_revision:790; }","duration":"1.11133204s","start":"2026-07-30T20:58:39.475886Z","end":"2026-07-30T20:58:40.587218Z","steps":["trace[887210450] 'agreement among raft nodes before linearized reading' (duration: 1.111136536s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:40.587276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.276337433s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-30T20:58:40.587336Z","caller":"traceutil/trace.go:171","msg":"trace[1685295796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:790; }","duration":"1.276514125s","start":"2026-07-30T20:58:39.310808Z","end":"2026-07-30T20:58:40.587322Z","steps":["trace[1685295796] 'agreement among raft nodes before linearized reading' (duration: 1.276273908s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:40.587365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.850943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T20:58:40.5875Z","caller":"traceutil/trace.go:171","msg":"trace[1133714304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:790; }","duration":"121.109771ms","start":"2026-07-30T20:58:40.466374Z","end":"2026-07-30T20:58:40.587483Z","steps":["trace[1133714304] 'agreement among raft nodes before linearized reading' (duration: 120.763766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:40.587557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.980568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-30T20:58:40.587628Z","caller":"traceutil/trace.go:171","msg":"trace[2119897810] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:790; }","duration":"642.048953ms","start":"2026-07-30T20:58:39.945566Z","end":"2026-07-30T20:58:40.587615Z","steps":["trace[2119897810] 'agreement among raft nodes before linearized reading' (duration: 641.453651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T20:58:40.587675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:39.945516Z","time spent":"642.148059ms","remote":"127.0.0.1:54660","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-07-30T20:58:40.587451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:39.310789Z","time spent":"1.276617942s","remote":"127.0.0.1:54660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T20:58:40.587287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T20:58:39.475865Z","time spent":"1.111412695s","remote":"127.0.0.1:54550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-h892f\" "}