{"level":"warn","ts":"2026-06-16T01:15:44.9581Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-16T01:15:44.958232Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-16T01:15:44.958829Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-16T01:15:44.958857Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-06-16T01:15:44.95894Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-16T01:15:44.960055Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-06-16T01:15:44.960179Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-16T01:15:44.962717Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.253105ms"} {"level":"info","ts":"2026-06-16T01:15:44.968346Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-06-16T01:15:44.968443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-06-16T01:15:44.968463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-06-16T01:15:44.968473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-16T01:15:44.96921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-06-16T01:15:44.969273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-06-16T01:15:44.974584Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-16T01:15:44.976075Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-16T01:15:44.977449Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-16T01:15:44.97948Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-16T01:15:44.9796Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-16T01:15:44.979704Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-16T01:15:44.979716Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-16T01:15:44.980007Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-16T01:15:44.981289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-06-16T01:15:44.981864Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-06-16T01:15:44.98293Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-16T01:15:44.983094Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-06-16T01:15:44.983141Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-16T01:15:44.983142Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-06-16T01:15:44.983172Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-16T01:15:45.970224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-06-16T01:15:45.970278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-16T01:15:45.970315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-06-16T01:15:45.97033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-06-16T01:15:45.970341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-06-16T01:15:45.970353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-06-16T01:15:45.970368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-06-16T01:15:45.971088Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T01:15:45.971751Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T01:15:45.971746Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-06-16T01:15:45.971789Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T01:15:45.971975Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T01:15:45.972075Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T01:15:45.972119Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T01:15:45.972212Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-16T01:15:45.972233Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-16T01:15:45.974289Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-16T01:15:45.974683Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-06-16T01:15:56.524934Z","caller":"traceutil/trace.go:171","msg":"trace[419459435] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"103.737795ms","start":"2026-06-16T01:15:56.42118Z","end":"2026-06-16T01:15:56.524918Z","steps":["trace[419459435] 'process raft request' (duration: 103.623112ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:16:08.42743Z","caller":"traceutil/trace.go:171","msg":"trace[1358315164] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"105.810256ms","start":"2026-06-16T01:16:08.3216Z","end":"2026-06-16T01:16:08.42741Z","steps":["trace[1358315164] 'compare' (duration: 96.60544ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:16:09.61588Z","caller":"traceutil/trace.go:171","msg":"trace[1079379297] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:443; }","duration":"431.69578ms","start":"2026-06-16T01:16:09.184168Z","end":"2026-06-16T01:16:09.615864Z","steps":["trace[1079379297] 'read index received' (duration: 431.582577ms)","trace[1079379297] 'applied index is now lower than readState.Index' (duration: 110.573µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:16:09.615926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:08.992729Z","time spent":"623.188316ms","remote":"127.0.0.1:58258","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-16T01:16:09.61599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.834372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T01:16:09.616042Z","caller":"traceutil/trace.go:171","msg":"trace[1262445486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:428; }","duration":"431.932755ms","start":"2026-06-16T01:16:09.184099Z","end":"2026-06-16T01:16:09.616032Z","steps":["trace[1262445486] 'agreement among raft nodes before linearized reading' (duration: 431.849933ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:09.616074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:09.184079Z","time spent":"431.986617ms","remote":"127.0.0.1:58184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-16T01:16:09.764919Z","caller":"traceutil/trace.go:171","msg":"trace[1982811954] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"147.425486ms","start":"2026-06-16T01:16:09.617462Z","end":"2026-06-16T01:16:09.764888Z","steps":["trace[1982811954] 'process raft request' (duration: 139.350767ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:16:09.77049Z","caller":"traceutil/trace.go:171","msg":"trace[1368618683] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"141.868689ms","start":"2026-06-16T01:16:09.62856Z","end":"2026-06-16T01:16:09.770429Z","steps":["trace[1368618683] 'process raft request' (duration: 141.636494ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:16:12.00143Z","caller":"traceutil/trace.go:171","msg":"trace[2128584881] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"166.650118ms","start":"2026-06-16T01:16:11.834745Z","end":"2026-06-16T01:16:12.001395Z","steps":["trace[2128584881] 'process raft request' (duration: 83.422366ms)","trace[2128584881] 'compare' (duration: 83.092319ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:16:18.398282Z","caller":"traceutil/trace.go:171","msg":"trace[1098392860] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"103.735225ms","start":"2026-06-16T01:16:18.294528Z","end":"2026-06-16T01:16:18.398263Z","steps":["trace[1098392860] 'process raft request' (duration: 103.645442ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:16:18.403127Z","caller":"traceutil/trace.go:171","msg":"trace[838920179] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"106.841491ms","start":"2026-06-16T01:16:18.296268Z","end":"2026-06-16T01:16:18.403109Z","steps":["trace[838920179] 'process raft request' (duration: 106.768709ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:16:20.213765Z","caller":"traceutil/trace.go:171","msg":"trace[944721170] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:499; }","duration":"126.212366ms","start":"2026-06-16T01:16:20.087529Z","end":"2026-06-16T01:16:20.213742Z","steps":["trace[944721170] 'read index received' (duration: 126.096564ms)","trace[944721170] 'applied index is now lower than readState.Index' (duration: 115.002µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:16:20.213812Z","caller":"traceutil/trace.go:171","msg":"trace[2088663896] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"262.734706ms","start":"2026-06-16T01:16:19.951053Z","end":"2026-06-16T01:16:20.213788Z","steps":["trace[2088663896] 'process raft request' (duration: 262.573662ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:20.213915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.36587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-16T01:16:20.213996Z","caller":"traceutil/trace.go:171","msg":"trace[976741305] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:482; }","duration":"126.488323ms","start":"2026-06-16T01:16:20.087487Z","end":"2026-06-16T01:16:20.213975Z","steps":["trace[976741305] 'agreement among raft nodes before linearized reading' (duration: 126.329129ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:24.684543Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096581078699395,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-16T01:16:24.706665Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.049912625s","expected-duration":"1s"} {"level":"info","ts":"2026-06-16T01:16:24.707036Z","caller":"traceutil/trace.go:171","msg":"trace[1293551524] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"1.050354626s","start":"2026-06-16T01:16:23.656658Z","end":"2026-06-16T01:16:24.707012Z","steps":["trace[1293551524] 'process raft request' (duration: 1.050228743s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:24.707539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:23.656639Z","time spent":"1.050446939s","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T01:16:24.715007Z","caller":"traceutil/trace.go:171","msg":"trace[1852579930] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"1.041796747s","start":"2026-06-16T01:16:23.673181Z","end":"2026-06-16T01:16:24.714978Z","steps":["trace[1852579930] 'process raft request' (duration: 1.04151135s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:24.715145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:23.673175Z","time spent":"1.041910029s","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T01:16:24.715257Z","caller":"traceutil/trace.go:171","msg":"trace[1892117324] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"1.053179396s","start":"2026-06-16T01:16:23.662071Z","end":"2026-06-16T01:16:24.71525Z","steps":["trace[1892117324] 'process raft request' (duration: 1.052361467s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:24.715287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:23.661999Z","time spent":"1.053272149s","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T01:16:24.715341Z","caller":"traceutil/trace.go:171","msg":"trace[1155112186] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"1.052277854s","start":"2026-06-16T01:16:23.663057Z","end":"2026-06-16T01:16:24.715335Z","steps":["trace[1155112186] 'process raft request' (duration: 1.051543907s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:24.715368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:23.663048Z","time spent":"1.052306565s","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T01:16:24.715546Z","caller":"traceutil/trace.go:171","msg":"trace[240853620] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"1.042423942s","start":"2026-06-16T01:16:23.673116Z","end":"2026-06-16T01:16:24.71554Z","steps":["trace[240853620] 'process raft request' (duration: 1.041533561s)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:16:24.717432Z","caller":"traceutil/trace.go:171","msg":"trace[102636401] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"974.560358ms","start":"2026-06-16T01:16:23.740994Z","end":"2026-06-16T01:16:24.715554Z","steps":["trace[102636401] 'process raft request' (duration: 974.406454ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:24.717514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:23.740976Z","time spent":"976.495995ms","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T01:16:24.717916Z","caller":"traceutil/trace.go:171","msg":"trace[672898659] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"1.043058887s","start":"2026-06-16T01:16:23.674827Z","end":"2026-06-16T01:16:24.717886Z","steps":["trace[672898659] 'process raft request' (duration: 1.03990788s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:24.717979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:23.674821Z","time spent":"1.043127629s","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-16T01:16:24.718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:23.673099Z","time spent":"1.042464553s","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T01:16:24.71809Z","caller":"traceutil/trace.go:171","msg":"trace[582048439] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.039238454s","start":"2026-06-16T01:16:23.678828Z","end":"2026-06-16T01:16:24.718067Z","steps":["trace[582048439] 'process raft request' (duration: 1.035943194s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:24.718188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:23.678819Z","time spent":"1.039333606s","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T01:16:24.718396Z","caller":"traceutil/trace.go:171","msg":"trace[625805448] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.028163792s","start":"2026-06-16T01:16:23.690218Z","end":"2026-06-16T01:16:24.718382Z","steps":["trace[625805448] 'process raft request' (duration: 1.024657126s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:24.718453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:23.690199Z","time spent":"1.028222044s","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T01:16:24.718773Z","caller":"traceutil/trace.go:171","msg":"trace[1722665224] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.028298665s","start":"2026-06-16T01:16:23.690292Z","end":"2026-06-16T01:16:24.71859Z","steps":["trace[1722665224] 'process raft request' (duration: 1.024643866s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:24.718886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:23.690287Z","time spent":"1.028539941s","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T01:16:24.719286Z","caller":"traceutil/trace.go:171","msg":"trace[1958279163] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.010261613s","start":"2026-06-16T01:16:23.708938Z","end":"2026-06-16T01:16:24.7192Z","steps":["trace[1958279163] 'process raft request' (duration: 1.00604227s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:24.719329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:23.7089Z","time spent":"1.010403536s","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T01:16:24.728909Z","caller":"traceutil/trace.go:171","msg":"trace[446778847] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"881.70845ms","start":"2026-06-16T01:16:23.847184Z","end":"2026-06-16T01:16:24.728893Z","steps":["trace[446778847] 'process raft request' (duration: 868.361622ms)","trace[446778847] 'compare' (duration: 13.00803ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:16:24.730144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:23.847159Z","time spent":"882.938469ms","remote":"127.0.0.1:58282","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-06-16T01:16:24.729862Z","caller":"traceutil/trace.go:171","msg":"trace[707246331] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:543; }","duration":"545.558114ms","start":"2026-06-16T01:16:24.183771Z","end":"2026-06-16T01:16:24.72933Z","steps":["trace[707246331] 'read index received' (duration: 523.219435ms)","trace[707246331] 'applied index is now lower than readState.Index' (duration: 22.337879ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:16:24.729963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.171428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T01:16:24.730364Z","caller":"traceutil/trace.go:171","msg":"trace[1659222043] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"546.608209ms","start":"2026-06-16T01:16:24.183742Z","end":"2026-06-16T01:16:24.730351Z","steps":["trace[1659222043] 'agreement among raft nodes before linearized reading' (duration: 546.155558ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:24.730433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:24.183731Z","time spent":"546.689051ms","remote":"127.0.0.1:58184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T01:16:24.735501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.608062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-16T01:16:24.735605Z","caller":"traceutil/trace.go:171","msg":"trace[1924444785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:541; }","duration":"509.719494ms","start":"2026-06-16T01:16:24.225841Z","end":"2026-06-16T01:16:24.735561Z","steps":["trace[1924444785] 'agreement among raft nodes before linearized reading' (duration: 509.614012ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:24.735655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:24.225824Z","time spent":"509.822376ms","remote":"127.0.0.1:58508","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-06-16T01:16:24.735944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.327168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-16T01:16:24.736003Z","caller":"traceutil/trace.go:171","msg":"trace[369286411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:541; }","duration":"243.38473ms","start":"2026-06-16T01:16:24.49261Z","end":"2026-06-16T01:16:24.735995Z","steps":["trace[369286411] 'agreement among raft nodes before linearized reading' (duration: 243.308608ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:24.736185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.232045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-16T01:16:24.736646Z","caller":"traceutil/trace.go:171","msg":"trace[1081424894] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:541; }","duration":"300.727898ms","start":"2026-06-16T01:16:24.435908Z","end":"2026-06-16T01:16:24.736636Z","steps":["trace[1081424894] 'agreement among raft nodes before linearized reading' (duration: 300.224615ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:24.736718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:24.435889Z","time spent":"300.81947ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-16T01:16:24.736425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.374918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-16T01:16:24.736865Z","caller":"traceutil/trace.go:171","msg":"trace[1802384446] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:541; }","duration":"507.86011ms","start":"2026-06-16T01:16:24.228997Z","end":"2026-06-16T01:16:24.736857Z","steps":["trace[1802384446] 'agreement among raft nodes before linearized reading' (duration: 507.324087ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:24.736909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:24.228984Z","time spent":"507.916751ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-16T01:16:28.280369Z","caller":"traceutil/trace.go:171","msg":"trace[1048010180] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"235.033296ms","start":"2026-06-16T01:16:28.045297Z","end":"2026-06-16T01:16:28.28033Z","steps":["trace[1048010180] 'process raft request' (duration: 234.046791ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:16:28.823539Z","caller":"traceutil/trace.go:171","msg":"trace[2788610] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"124.182447ms","start":"2026-06-16T01:16:28.699329Z","end":"2026-06-16T01:16:28.823511Z","steps":["trace[2788610] 'process raft request' (duration: 65.453236ms)","trace[2788610] 'compare' (duration: 58.403962ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:16:28.960522Z","caller":"traceutil/trace.go:171","msg":"trace[1226992066] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"127.302903ms","start":"2026-06-16T01:16:28.833196Z","end":"2026-06-16T01:16:28.960499Z","steps":["trace[1226992066] 'process raft request' (duration: 127.235781ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:16:28.960845Z","caller":"traceutil/trace.go:171","msg":"trace[2121450008] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"137.349011ms","start":"2026-06-16T01:16:28.823482Z","end":"2026-06-16T01:16:28.960831Z","steps":["trace[2121450008] 'read index received' (duration: 132.979913ms)","trace[2121450008] 'applied index is now lower than readState.Index' (duration: 4.367838ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:16:28.960997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.776075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-16T01:16:28.961037Z","caller":"traceutil/trace.go:171","msg":"trace[2126701425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"169.849957ms","start":"2026-06-16T01:16:28.791174Z","end":"2026-06-16T01:16:28.961024Z","steps":["trace[2126701425] 'agreement among raft nodes before linearized reading' (duration: 169.703004ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:28.961352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.14444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-16T01:16:28.961386Z","caller":"traceutil/trace.go:171","msg":"trace[70893433] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"169.188671ms","start":"2026-06-16T01:16:28.792185Z","end":"2026-06-16T01:16:28.961374Z","steps":["trace[70893433] 'agreement among raft nodes before linearized reading' (duration: 169.096319ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:16:28.960535Z","caller":"traceutil/trace.go:171","msg":"trace[867894858] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"142.596477ms","start":"2026-06-16T01:16:28.817922Z","end":"2026-06-16T01:16:28.960518Z","steps":["trace[867894858] 'process raft request' (duration: 138.740413ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:28.961555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.256937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-16T01:16:28.961586Z","caller":"traceutil/trace.go:171","msg":"trace[972260504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:616; }","duration":"170.295798ms","start":"2026-06-16T01:16:28.79128Z","end":"2026-06-16T01:16:28.961576Z","steps":["trace[972260504] 'agreement among raft nodes before linearized reading' (duration: 170.218916ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:16:29.630793Z","caller":"traceutil/trace.go:171","msg":"trace[453866912] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"119.297867ms","start":"2026-06-16T01:16:29.511469Z","end":"2026-06-16T01:16:29.630767Z","steps":["trace[453866912] 'process raft request' (duration: 118.786373ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:16:33.019416Z","caller":"traceutil/trace.go:171","msg":"trace[1506791379] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"185.683985ms","start":"2026-06-16T01:16:32.83371Z","end":"2026-06-16T01:16:33.019394Z","steps":["trace[1506791379] 'process raft request' (duration: 185.50514ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:33.685944Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096581078699611,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-16T01:16:33.95237Z","caller":"traceutil/trace.go:171","msg":"trace[23850496] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:668; }","duration":"766.776019ms","start":"2026-06-16T01:16:33.18557Z","end":"2026-06-16T01:16:33.952346Z","steps":["trace[23850496] 'read index received' (duration: 730.55926ms)","trace[23850496] 'applied index is now lower than readState.Index' (duration: 36.215809ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:16:33.952533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.955684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T01:16:33.952528Z","caller":"traceutil/trace.go:171","msg":"trace[1242730491] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"928.273961ms","start":"2026-06-16T01:16:33.024217Z","end":"2026-06-16T01:16:33.952491Z","steps":["trace[1242730491] 'process raft request' (duration: 891.899679ms)","trace[1242730491] 'compare' (duration: 35.557632ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:16:33.95255Z","caller":"traceutil/trace.go:171","msg":"trace[2130117522] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"927.84705ms","start":"2026-06-16T01:16:33.024655Z","end":"2026-06-16T01:16:33.952502Z","steps":["trace[2130117522] 'process raft request' (duration: 927.599064ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:16:33.952583Z","caller":"traceutil/trace.go:171","msg":"trace[1235019568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"767.062666ms","start":"2026-06-16T01:16:33.185509Z","end":"2026-06-16T01:16:33.952572Z","steps":["trace[1235019568] 'agreement among raft nodes before linearized reading' (duration: 766.926683ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:16:33.952588Z","caller":"traceutil/trace.go:171","msg":"trace[41471591] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"926.718282ms","start":"2026-06-16T01:16:33.025842Z","end":"2026-06-16T01:16:33.95256Z","steps":["trace[41471591] 'process raft request' (duration: 926.464906ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:33.952623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:33.185487Z","time spent":"767.128037ms","remote":"127.0.0.1:58196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T01:16:33.952796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:33.024199Z","time spent":"928.390514ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:16:33.952893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:33.024571Z","time spent":"928.046285ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:16:33.952982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:33.025823Z","time spent":"926.833645ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:16:34.680964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.456604ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-16T01:16:34.681125Z","caller":"traceutil/trace.go:171","msg":"trace[1620999925] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"499.883572ms","start":"2026-06-16T01:16:34.181223Z","end":"2026-06-16T01:16:34.681107Z","steps":["trace[1620999925] 'read index received' (duration: 79.114141ms)","trace[1620999925] 'applied index is now lower than readState.Index' (duration: 420.767851ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:16:34.681201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.961825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T01:16:34.681242Z","caller":"traceutil/trace.go:171","msg":"trace[1754119996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"500.035217ms","start":"2026-06-16T01:16:34.181195Z","end":"2026-06-16T01:16:34.68123Z","steps":["trace[1754119996] 'agreement among raft nodes before linearized reading' (duration: 499.960344ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:34.681297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:34.181182Z","time spent":"500.103399ms","remote":"127.0.0.1:58184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T01:16:35.687713Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096581078699620,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-16T01:16:36.188605Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096581078699620,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-16T01:16:36.689408Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096581078699620,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-16T01:16:36.822155Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.704296935s","expected-duration":"1s"} {"level":"info","ts":"2026-06-16T01:16:36.822562Z","caller":"traceutil/trace.go:171","msg":"trace[5962685] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"1.635791045s","start":"2026-06-16T01:16:35.18675Z","end":"2026-06-16T01:16:36.822541Z","steps":["trace[5962685] 'read index received' (duration: 1.635546279s)","trace[5962685] 'applied index is now lower than readState.Index' (duration: 243.886µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:16:36.822609Z","caller":"traceutil/trace.go:171","msg":"trace[604166759] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"1.704829679s","start":"2026-06-16T01:16:35.117748Z","end":"2026-06-16T01:16:36.822578Z","steps":["trace[604166759] 'process raft request' (duration: 1.704585623s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:36.822661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.635903217s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T01:16:36.822694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:35.117722Z","time spent":"1.7049159s","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T01:16:36.822712Z","caller":"traceutil/trace.go:171","msg":"trace[466512081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"1.63598999s","start":"2026-06-16T01:16:35.186712Z","end":"2026-06-16T01:16:36.822702Z","steps":["trace[466512081] 'agreement among raft nodes before linearized reading' (duration: 1.635912378s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:36.822747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:35.186694Z","time spent":"1.636046261s","remote":"127.0.0.1:58184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T01:16:36.822977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.303336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-16T01:16:36.823023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.389127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-16T01:16:36.823049Z","caller":"traceutil/trace.go:171","msg":"trace[1709085515] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:650; }","duration":"865.427979ms","start":"2026-06-16T01:16:35.957613Z","end":"2026-06-16T01:16:36.823041Z","steps":["trace[1709085515] 'agreement among raft nodes before linearized reading' (duration: 865.343547ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:16:36.823047Z","caller":"traceutil/trace.go:171","msg":"trace[558656862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:650; }","duration":"865.432309ms","start":"2026-06-16T01:16:35.957598Z","end":"2026-06-16T01:16:36.82303Z","steps":["trace[558656862] 'agreement among raft nodes before linearized reading' (duration: 865.228734ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:36.823069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:35.957607Z","time spent":"865.457719ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-16T01:16:36.8231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:35.957575Z","time spent":"865.514321ms","remote":"127.0.0.1:58508","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-06-16T01:16:36.82334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.736646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-16T01:16:36.823585Z","caller":"traceutil/trace.go:171","msg":"trace[622950586] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:650; }","duration":"866.015293ms","start":"2026-06-16T01:16:35.957558Z","end":"2026-06-16T01:16:36.823573Z","steps":["trace[622950586] 'agreement among raft nodes before linearized reading' (duration: 865.262405ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:36.823688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:35.957539Z","time spent":"866.137426ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-16T01:16:37.63338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.130448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T01:16:37.633508Z","caller":"traceutil/trace.go:171","msg":"trace[1323765161] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"807.18041ms","start":"2026-06-16T01:16:36.826313Z","end":"2026-06-16T01:16:37.633494Z","steps":["trace[1323765161] 'process raft request' (duration: 496.849957ms)","trace[1323765161] 'compare' (duration: 309.914503ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:16:37.633555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:36.826303Z","time spent":"807.232551ms","remote":"127.0.0.1:58508","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-06-16T01:16:37.633691Z","caller":"traceutil/trace.go:171","msg":"trace[38770425] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"807.185709ms","start":"2026-06-16T01:16:36.826498Z","end":"2026-06-16T01:16:37.633684Z","steps":["trace[38770425] 'process raft request' (duration: 806.957334ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:37.633733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:36.826489Z","time spent":"807.223861ms","remote":"127.0.0.1:58508","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-06-16T01:16:37.633981Z","caller":"traceutil/trace.go:171","msg":"trace[1127177083] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:673; }","duration":"803.044558ms","start":"2026-06-16T01:16:36.830922Z","end":"2026-06-16T01:16:37.633967Z","steps":["trace[1127177083] 'read index received' (duration: 492.207332ms)","trace[1127177083] 'applied index is now lower than readState.Index' (duration: 310.836376ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:16:37.634078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.14086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T01:16:37.634099Z","caller":"traceutil/trace.go:171","msg":"trace[1902740941] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"803.172832ms","start":"2026-06-16T01:16:36.830919Z","end":"2026-06-16T01:16:37.634091Z","steps":["trace[1902740941] 'agreement among raft nodes before linearized reading' (duration: 803.10847ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:16:37.634066Z","caller":"traceutil/trace.go:171","msg":"trace[1535177769] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"805.760646ms","start":"2026-06-16T01:16:36.828273Z","end":"2026-06-16T01:16:37.634034Z","steps":["trace[1535177769] 'process raft request' (duration: 805.381745ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:37.634121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:36.830896Z","time spent":"803.219353ms","remote":"127.0.0.1:58184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T01:16:37.634256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:36.828265Z","time spent":"805.873238ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:16:39.49521Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096581078699640,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-16T01:16:39.52484Z","caller":"traceutil/trace.go:171","msg":"trace[744014318] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:688; }","duration":"530.682456ms","start":"2026-06-16T01:16:38.994125Z","end":"2026-06-16T01:16:39.524808Z","steps":["trace[744014318] 'read index received' (duration: 530.407178ms)","trace[744014318] 'applied index is now lower than readState.Index' (duration: 272.318µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:16:39.524923Z","caller":"traceutil/trace.go:171","msg":"trace[498078065] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"546.807221ms","start":"2026-06-16T01:16:38.978094Z","end":"2026-06-16T01:16:39.524901Z","steps":["trace[498078065] 'process raft request' (duration: 546.557534ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:39.52507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:38.978075Z","time spent":"546.898344ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:16:39.525111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.564047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T01:16:39.525206Z","caller":"traceutil/trace.go:171","msg":"trace[863154471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:666; }","duration":"342.019538ms","start":"2026-06-16T01:16:39.183163Z","end":"2026-06-16T01:16:39.525182Z","steps":["trace[863154471] 'agreement among raft nodes before linearized reading' (duration: 341.873974ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:39.525262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:39.183139Z","time spent":"342.11048ms","remote":"127.0.0.1:58184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T01:16:39.525326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.196668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-16T01:16:39.525394Z","caller":"traceutil/trace.go:171","msg":"trace[1115933918] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:666; }","duration":"531.326442ms","start":"2026-06-16T01:16:38.994051Z","end":"2026-06-16T01:16:39.525378Z","steps":["trace[1115933918] 'agreement among raft nodes before linearized reading' (duration: 530.921192ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:39.525442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:38.994026Z","time spent":"531.403523ms","remote":"127.0.0.1:58258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.89\" "} {"level":"info","ts":"2026-06-16T01:16:43.069048Z","caller":"traceutil/trace.go:171","msg":"trace[1730519653] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"167.109879ms","start":"2026-06-16T01:16:42.901916Z","end":"2026-06-16T01:16:43.069026Z","steps":["trace[1730519653] 'process raft request' (duration: 167.037177ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:16:43.06916Z","caller":"traceutil/trace.go:171","msg":"trace[1881765440] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"176.462069ms","start":"2026-06-16T01:16:42.892678Z","end":"2026-06-16T01:16:43.06914Z","steps":["trace[1881765440] 'process raft request' (duration: 176.137171ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:16:43.159353Z","caller":"traceutil/trace.go:171","msg":"trace[222390024] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"171.115237ms","start":"2026-06-16T01:16:42.988216Z","end":"2026-06-16T01:16:43.159331Z","steps":["trace[222390024] 'process raft request' (duration: 170.906912ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:50.22541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.778593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-16T01:16:50.225498Z","caller":"traceutil/trace.go:171","msg":"trace[1971515439] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:788; }","duration":"396.924776ms","start":"2026-06-16T01:16:49.828555Z","end":"2026-06-16T01:16:50.225479Z","steps":["trace[1971515439] 'range keys from in-memory index tree' (duration: 396.540777ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:50.225558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:49.82853Z","time spent":"397.010269ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-16T01:16:50.225635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.314585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-16T01:16:50.225702Z","caller":"traceutil/trace.go:171","msg":"trace[278566787] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"359.369016ms","start":"2026-06-16T01:16:49.866309Z","end":"2026-06-16T01:16:50.225678Z","steps":["trace[278566787] 'process raft request' (duration: 357.319016ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:16:50.225734Z","caller":"traceutil/trace.go:171","msg":"trace[354376956] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:788; }","duration":"421.460549ms","start":"2026-06-16T01:16:49.804252Z","end":"2026-06-16T01:16:50.225713Z","steps":["trace[354376956] 'range keys from in-memory index tree' (duration: 421.09558ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:50.225791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:49.804233Z","time spent":"421.54384ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-16T01:16:50.225841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:49.866284Z","time spent":"359.476868ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:16:50.225635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.1238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-16T01:16:50.225969Z","caller":"traceutil/trace.go:171","msg":"trace[1283684433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:788; }","duration":"377.476439ms","start":"2026-06-16T01:16:49.848455Z","end":"2026-06-16T01:16:50.225931Z","steps":["trace[1283684433] 'range keys from in-memory index tree' (duration: 376.729081ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:50.226043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:49.848434Z","time spent":"377.588561ms","remote":"127.0.0.1:58508","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-06-16T01:16:53.607898Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096581078699933,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-16T01:16:54.108528Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096581078699933,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-16T01:16:54.38293Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.434526599s","expected-duration":"1s"} {"level":"info","ts":"2026-06-16T01:16:54.38337Z","caller":"traceutil/trace.go:171","msg":"trace[662446292] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:835; }","duration":"1.276386641s","start":"2026-06-16T01:16:53.106962Z","end":"2026-06-16T01:16:54.383349Z","steps":["trace[662446292] 'read index received' (duration: 1.276207726s)","trace[662446292] 'applied index is now lower than readState.Index' (duration: 177.965µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:16:54.38352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.19926073s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T01:16:54.383544Z","caller":"traceutil/trace.go:171","msg":"trace[1085659367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:808; }","duration":"1.199422564s","start":"2026-06-16T01:16:53.184113Z","end":"2026-06-16T01:16:54.383536Z","steps":["trace[1085659367] 'agreement among raft nodes before linearized reading' (duration: 1.199356132s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:54.383568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:53.184092Z","time spent":"1.199470354s","remote":"127.0.0.1:58184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-16T01:16:54.383552Z","caller":"traceutil/trace.go:171","msg":"trace[1473610407] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"1.436274712s","start":"2026-06-16T01:16:52.947238Z","end":"2026-06-16T01:16:54.383513Z","steps":["trace[1473610407] 'process raft request' (duration: 1.435868031s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:54.383621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.276637647s","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-06-16T01:16:54.383722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:52.947181Z","time spent":"1.436446117s","remote":"127.0.0.1:58430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T01:16:54.383769Z","caller":"traceutil/trace.go:171","msg":"trace[371840012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:808; }","duration":"1.276831492s","start":"2026-06-16T01:16:53.106912Z","end":"2026-06-16T01:16:54.383744Z","steps":["trace[371840012] 'agreement among raft nodes before linearized reading' (duration: 1.276537915s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:54.383842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:53.106891Z","time spent":"1.276932894s","remote":"127.0.0.1:58508","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-06-16T01:16:54.383866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.320316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-16T01:16:54.383897Z","caller":"traceutil/trace.go:171","msg":"trace[1357875585] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:808; }","duration":"712.387836ms","start":"2026-06-16T01:16:53.671501Z","end":"2026-06-16T01:16:54.383889Z","steps":["trace[1357875585] 'agreement among raft nodes before linearized reading' (duration: 712.285934ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:54.383917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:16:53.671482Z","time spent":"712.431168ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-16T01:16:54.383969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.869453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-16T01:16:54.384027Z","caller":"traceutil/trace.go:171","msg":"trace[2052347598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:808; }","duration":"135.949994ms","start":"2026-06-16T01:16:54.248058Z","end":"2026-06-16T01:16:54.384008Z","steps":["trace[2052347598] 'agreement among raft nodes before linearized reading' (duration: 135.701808ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:54.384135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.994876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-16T01:16:54.384187Z","caller":"traceutil/trace.go:171","msg":"trace[638213599] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:808; }","duration":"136.070527ms","start":"2026-06-16T01:16:54.248109Z","end":"2026-06-16T01:16:54.38418Z","steps":["trace[638213599] 'agreement among raft nodes before linearized reading' (duration: 135.979095ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:54.384236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.124426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-16T01:16:54.384277Z","caller":"traceutil/trace.go:171","msg":"trace[1908716021] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:808; }","duration":"140.207838ms","start":"2026-06-16T01:16:54.244057Z","end":"2026-06-16T01:16:54.384265Z","steps":["trace[1908716021] 'agreement among raft nodes before linearized reading' (duration: 139.709916ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:16:54.656537Z","caller":"traceutil/trace.go:171","msg":"trace[1014426219] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"266.837726ms","start":"2026-06-16T01:16:54.389679Z","end":"2026-06-16T01:16:54.656517Z","steps":["trace[1014426219] 'process raft request' (duration: 212.39277ms)","trace[1014426219] 'compare' (duration: 54.372104ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:16:54.668462Z","caller":"traceutil/trace.go:171","msg":"trace[492659023] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:836; }","duration":"275.522008ms","start":"2026-06-16T01:16:54.392908Z","end":"2026-06-16T01:16:54.66843Z","steps":["trace[492659023] 'read index received' (duration: 209.266963ms)","trace[492659023] 'applied index is now lower than readState.Index' (duration: 66.253655ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:16:54.668791Z","caller":"traceutil/trace.go:171","msg":"trace[256063186] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"278.720737ms","start":"2026-06-16T01:16:54.390004Z","end":"2026-06-16T01:16:54.668725Z","steps":["trace[256063186] 'process raft request' (duration: 278.320837ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:16:54.669296Z","caller":"traceutil/trace.go:171","msg":"trace[1449830828] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"279.341133ms","start":"2026-06-16T01:16:54.389918Z","end":"2026-06-16T01:16:54.669259Z","steps":["trace[1449830828] 'process raft request' (duration: 278.245815ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:16:54.669599Z","caller":"traceutil/trace.go:171","msg":"trace[1232309888] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"279.173819ms","start":"2026-06-16T01:16:54.390417Z","end":"2026-06-16T01:16:54.669591Z","steps":["trace[1232309888] 'process raft request' (duration: 277.947958ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:16:54.669737Z","caller":"traceutil/trace.go:171","msg":"trace[1467133649] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"279.270901ms","start":"2026-06-16T01:16:54.39046Z","end":"2026-06-16T01:16:54.66973Z","steps":["trace[1467133649] 'process raft request' (duration: 277.932328ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:16:54.676298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.358571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T01:16:54.676384Z","caller":"traceutil/trace.go:171","msg":"trace[447199599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:813; }","duration":"283.480113ms","start":"2026-06-16T01:16:54.392883Z","end":"2026-06-16T01:16:54.676363Z","steps":["trace[447199599] 'agreement among raft nodes before linearized reading' (duration: 283.297449ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:17:25.108412Z","caller":"traceutil/trace.go:171","msg":"trace[1535181824] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"266.453584ms","start":"2026-06-16T01:17:24.841939Z","end":"2026-06-16T01:17:25.108393Z","steps":["trace[1535181824] 'read index received' (duration: 266.321611ms)","trace[1535181824] 'applied index is now lower than readState.Index' (duration: 131.173µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:17:25.108527Z","caller":"traceutil/trace.go:171","msg":"trace[876291171] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"268.67313ms","start":"2026-06-16T01:17:24.839826Z","end":"2026-06-16T01:17:25.108499Z","steps":["trace[876291171] 'process raft request' (duration: 268.419044ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:17:25.108677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.70748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-16T01:17:25.108748Z","caller":"traceutil/trace.go:171","msg":"trace[773463301] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1002; }","duration":"266.836353ms","start":"2026-06-16T01:17:24.841892Z","end":"2026-06-16T01:17:25.108728Z","steps":["trace[773463301] 'agreement among raft nodes before linearized reading' (duration: 266.628208ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:17:25.109439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.532166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-16T01:17:25.109493Z","caller":"traceutil/trace.go:171","msg":"trace[1388588863] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1003; }","duration":"233.620189ms","start":"2026-06-16T01:17:24.875861Z","end":"2026-06-16T01:17:25.109481Z","steps":["trace[1388588863] 'agreement among raft nodes before linearized reading' (duration: 233.486105ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:17:25.109506Z","caller":"traceutil/trace.go:171","msg":"trace[1770109826] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"266.459794ms","start":"2026-06-16T01:17:24.843022Z","end":"2026-06-16T01:17:25.109482Z","steps":["trace[1770109826] 'process raft request' (duration: 266.223448ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:17:25.109538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.043732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-16T01:17:25.10979Z","caller":"traceutil/trace.go:171","msg":"trace[1031865747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1003; }","duration":"227.306819ms","start":"2026-06-16T01:17:24.88246Z","end":"2026-06-16T01:17:25.109767Z","steps":["trace[1031865747] 'agreement among raft nodes before linearized reading' (duration: 227.025512ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:17:31.35433Z","caller":"traceutil/trace.go:171","msg":"trace[933433098] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1062; }","duration":"200.111505ms","start":"2026-06-16T01:17:31.154195Z","end":"2026-06-16T01:17:31.354307Z","steps":["trace[933433098] 'read index received' (duration: 200.030503ms)","trace[933433098] 'applied index is now lower than readState.Index' (duration: 80.002µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:17:31.354427Z","caller":"traceutil/trace.go:171","msg":"trace[851723089] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"343.327928ms","start":"2026-06-16T01:17:31.011071Z","end":"2026-06-16T01:17:31.354399Z","steps":["trace[851723089] 'process raft request' (duration: 343.079232ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:17:31.354566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.350521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-16T01:17:31.354633Z","caller":"traceutil/trace.go:171","msg":"trace[1686671202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1025; }","duration":"200.457054ms","start":"2026-06-16T01:17:31.154164Z","end":"2026-06-16T01:17:31.354622Z","steps":["trace[1686671202] 'agreement among raft nodes before linearized reading' (duration: 200.28665ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:17:31.354578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:17:31.011048Z","time spent":"343.450032ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T01:17:31.847505Z","caller":"traceutil/trace.go:171","msg":"trace[716797412] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1063; }","duration":"493.072556ms","start":"2026-06-16T01:17:31.354412Z","end":"2026-06-16T01:17:31.847485Z","steps":["trace[716797412] 'read index received' (duration: 423.212497ms)","trace[716797412] 'applied index is now lower than readState.Index' (duration: 69.858519ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:17:31.847497Z","caller":"traceutil/trace.go:171","msg":"trace[1787997240] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"488.381877ms","start":"2026-06-16T01:17:31.359085Z","end":"2026-06-16T01:17:31.847467Z","steps":["trace[1787997240] 'process raft request' (duration: 488.312395ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:17:31.847494Z","caller":"traceutil/trace.go:171","msg":"trace[1205066546] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"547.615349ms","start":"2026-06-16T01:17:31.299828Z","end":"2026-06-16T01:17:31.847443Z","steps":["trace[1205066546] 'process raft request' (duration: 477.823412ms)","trace[1205066546] 'compare' (duration: 69.350646ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:17:31.847622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:17:31.359063Z","time spent":"488.498961ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:17:31.847725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.825338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T01:17:31.847748Z","caller":"traceutil/trace.go:171","msg":"trace[1387548113] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1027; }","duration":"641.8856ms","start":"2026-06-16T01:17:31.205852Z","end":"2026-06-16T01:17:31.847738Z","steps":["trace[1387548113] 'agreement among raft nodes before linearized reading' (duration: 641.846379ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:17:31.847764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:17:31.205843Z","time spent":"641.91708ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-06-16T01:17:31.847778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.36742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T01:17:31.847835Z","caller":"traceutil/trace.go:171","msg":"trace[1946583478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1027; }","duration":"665.466773ms","start":"2026-06-16T01:17:31.182359Z","end":"2026-06-16T01:17:31.847825Z","steps":["trace[1946583478] 'agreement among raft nodes before linearized reading' (duration: 665.377791ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:17:31.847859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:17:31.182342Z","time spent":"665.511534ms","remote":"127.0.0.1:58184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T01:17:31.847947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.278843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-16T01:17:31.847948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.664663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-16T01:17:31.847991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.166309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-16T01:17:31.848012Z","caller":"traceutil/trace.go:171","msg":"trace[1401450666] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1027; }","duration":"660.200521ms","start":"2026-06-16T01:17:31.187806Z","end":"2026-06-16T01:17:31.848007Z","steps":["trace[1401450666] 'agreement among raft nodes before linearized reading' (duration: 660.099228ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:17:31.84801Z","caller":"traceutil/trace.go:171","msg":"trace[2075360614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1027; }","duration":"660.388856ms","start":"2026-06-16T01:17:31.187603Z","end":"2026-06-16T01:17:31.847992Z","steps":["trace[2075360614] 'agreement among raft nodes before linearized reading' (duration: 660.117729ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:17:31.848026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:17:31.187799Z","time spent":"660.223701ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-16T01:17:31.848018Z","caller":"traceutil/trace.go:171","msg":"trace[1135637830] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1027; }","duration":"660.785335ms","start":"2026-06-16T01:17:31.187214Z","end":"2026-06-16T01:17:31.848Z","steps":["trace[1135637830] 'agreement among raft nodes before linearized reading' (duration: 660.55489ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:17:31.84806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:17:31.187197Z","time spent":"660.854578ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-16T01:17:31.84806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:17:31.187561Z","time spent":"660.487449ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-16T01:17:31.848067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.286023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-16T01:17:31.848136Z","caller":"traceutil/trace.go:171","msg":"trace[766115970] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1027; }","duration":"660.371625ms","start":"2026-06-16T01:17:31.187746Z","end":"2026-06-16T01:17:31.848118Z","steps":["trace[766115970] 'agreement among raft nodes before linearized reading' (duration: 660.181461ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:17:31.848189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:17:31.187742Z","time spent":"660.440057ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-16T01:17:31.848344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:17:31.299803Z","time spent":"547.793673ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T01:18:00.320482Z","caller":"traceutil/trace.go:171","msg":"trace[1703690838] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"133.039968ms","start":"2026-06-16T01:18:00.187413Z","end":"2026-06-16T01:18:00.320453Z","steps":["trace[1703690838] 'process raft request' (duration: 93.954995ms)","trace[1703690838] 'compare' (duration: 38.96215ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:18:02.370028Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096581078700698,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-16T01:18:02.850575Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.184699507s","expected-duration":"1s"} {"level":"info","ts":"2026-06-16T01:18:02.850919Z","caller":"traceutil/trace.go:171","msg":"trace[175636802] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"1.185127658s","start":"2026-06-16T01:18:01.665766Z","end":"2026-06-16T01:18:02.850893Z","steps":["trace[175636802] 'process raft request' (duration: 1.184985575s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:02.851071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:01.665742Z","time spent":"1.185250981s","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:18:02.970932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.080123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T01:18:02.971074Z","caller":"traceutil/trace.go:171","msg":"trace[1725571617] linearizableReadLoop","detail":"{readStateIndex:1169; appliedIndex:1167; }","duration":"1.101848663s","start":"2026-06-16T01:18:01.869204Z","end":"2026-06-16T01:18:02.971053Z","steps":["trace[1725571617] 'read index received' (duration: 981.569677ms)","trace[1725571617] 'applied index is now lower than readState.Index' (duration: 120.277316ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:18:02.971185Z","caller":"traceutil/trace.go:171","msg":"trace[306732818] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"920.700316ms","start":"2026-06-16T01:18:02.050468Z","end":"2026-06-16T01:18:02.971168Z","steps":["trace[306732818] 'process raft request' (duration: 819.265244ms)","trace[306732818] 'compare' (duration: 100.97173ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:18:02.971271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:02.050442Z","time spent":"920.783367ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:18:02.971405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.978401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T01:18:02.971443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.647416ms","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-06-16T01:18:02.971474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.198695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-16T01:18:02.971514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.484296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-16T01:18:02.971458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.789382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-06-16T01:18:02.971533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102318884s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T01:18:02.971549Z","caller":"traceutil/trace.go:171","msg":"trace[16535697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1125; }","duration":"785.290928ms","start":"2026-06-16T01:18:02.186242Z","end":"2026-06-16T01:18:02.971533Z","steps":["trace[16535697] 'agreement among raft nodes before linearized reading' (duration: 785.120823ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:02.971544Z","caller":"traceutil/trace.go:171","msg":"trace[2094200910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1125; }","duration":"905.824451ms","start":"2026-06-16T01:18:02.065701Z","end":"2026-06-16T01:18:02.971526Z","steps":["trace[2094200910] 'agreement among raft nodes before linearized reading' (duration: 905.512853ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:02.971603Z","caller":"traceutil/trace.go:171","msg":"trace[2047561177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1125; }","duration":"1.102423907s","start":"2026-06-16T01:18:01.869165Z","end":"2026-06-16T01:18:02.971589Z","steps":["trace[2047561177] 'agreement among raft nodes before linearized reading' (duration: 1.102323725s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:02.971615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:02.186235Z","time spent":"785.368159ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-16T01:18:02.971645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:01.869146Z","time spent":"1.102490938s","remote":"127.0.0.1:58184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-16T01:18:02.971617Z","caller":"traceutil/trace.go:171","msg":"trace[216468172] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1125; }","duration":"886.933105ms","start":"2026-06-16T01:18:02.084637Z","end":"2026-06-16T01:18:02.97157Z","steps":["trace[216468172] 'agreement among raft nodes before linearized reading' (duration: 886.720239ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:02.971738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:02.084627Z","time spent":"887.101979ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-16T01:18:02.971643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:02.065681Z","time spent":"905.948614ms","remote":"127.0.0.1:58508","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-06-16T01:18:02.971486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.932149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T01:18:02.972269Z","caller":"traceutil/trace.go:171","msg":"trace[758218859] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1125; }","duration":"628.722198ms","start":"2026-06-16T01:18:02.343508Z","end":"2026-06-16T01:18:02.97223Z","steps":["trace[758218859] 'agreement among raft nodes before linearized reading' (duration: 627.928188ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:02.972353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:02.343493Z","time spent":"628.833622ms","remote":"127.0.0.1:45860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-06-16T01:18:02.97156Z","caller":"traceutil/trace.go:171","msg":"trace[1579139253] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1125; }","duration":"646.557348ms","start":"2026-06-16T01:18:02.32499Z","end":"2026-06-16T01:18:02.971547Z","steps":["trace[1579139253] 'agreement among raft nodes before linearized reading' (duration: 646.478676ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:02.972641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:02.324967Z","time spent":"647.642115ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-16T01:18:02.971507Z","caller":"traceutil/trace.go:171","msg":"trace[2025011311] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1125; }","duration":"787.147904ms","start":"2026-06-16T01:18:02.184335Z","end":"2026-06-16T01:18:02.971483Z","steps":["trace[2025011311] 'agreement among raft nodes before linearized reading' (duration: 786.98082ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:02.972713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:02.184287Z","time spent":"788.407897ms","remote":"127.0.0.1:58196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T01:18:02.97143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.327311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-16T01:18:02.972821Z","caller":"traceutil/trace.go:171","msg":"trace[964269652] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1125; }","duration":"740.774448ms","start":"2026-06-16T01:18:02.232032Z","end":"2026-06-16T01:18:02.972807Z","steps":["trace[964269652] 'agreement among raft nodes before linearized reading' (duration: 739.25531ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:02.972856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:02.231992Z","time spent":"740.85419ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-16T01:18:03.142642Z","caller":"traceutil/trace.go:171","msg":"trace[255285085] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"165.893213ms","start":"2026-06-16T01:18:02.976721Z","end":"2026-06-16T01:18:03.142615Z","steps":["trace[255285085] 'process raft request' (duration: 165.648027ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:03.142864Z","caller":"traceutil/trace.go:171","msg":"trace[1290175833] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"166.043837ms","start":"2026-06-16T01:18:02.976804Z","end":"2026-06-16T01:18:03.142848Z","steps":["trace[1290175833] 'process raft request' (duration: 165.75874ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:03.14266Z","caller":"traceutil/trace.go:171","msg":"trace[2788768] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"165.893613ms","start":"2026-06-16T01:18:02.976724Z","end":"2026-06-16T01:18:03.142617Z","steps":["trace[2788768] 'process raft request' (duration: 165.801991ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:03.14285Z","caller":"traceutil/trace.go:171","msg":"trace[1330152441] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"165.664608ms","start":"2026-06-16T01:18:02.977173Z","end":"2026-06-16T01:18:03.142838Z","steps":["trace[1330152441] 'process raft request' (duration: 165.564635ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:03.168376Z","caller":"traceutil/trace.go:171","msg":"trace[543049285] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"125.109838ms","start":"2026-06-16T01:18:03.043242Z","end":"2026-06-16T01:18:03.168352Z","steps":["trace[543049285] 'process raft request' (duration: 124.954453ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:04.624225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.679628ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-06-16T01:18:05.528499Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096581078700712,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-16T01:18:05.70286Z","caller":"traceutil/trace.go:171","msg":"trace[368049310] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"726.127009ms","start":"2026-06-16T01:18:04.976704Z","end":"2026-06-16T01:18:05.702831Z","steps":["trace[368049310] 'process raft request' (duration: 725.529915ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:05.703109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.145436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-16T01:18:05.703187Z","caller":"traceutil/trace.go:171","msg":"trace[593568006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1132; }","duration":"675.28495ms","start":"2026-06-16T01:18:05.027884Z","end":"2026-06-16T01:18:05.703169Z","steps":["trace[593568006] 'agreement among raft nodes before linearized reading' (duration: 675.050064ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:05.703263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:05.027864Z","time spent":"675.382563ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-16T01:18:05.702847Z","caller":"traceutil/trace.go:171","msg":"trace[2126469437] linearizableReadLoop","detail":"{readStateIndex:1177; appliedIndex:1176; }","duration":"674.87855ms","start":"2026-06-16T01:18:05.027942Z","end":"2026-06-16T01:18:05.70282Z","steps":["trace[2126469437] 'read index received' (duration: 674.176753ms)","trace[2126469437] 'applied index is now lower than readState.Index' (duration: 700.267µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:18:05.703689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.120377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-16T01:18:05.703744Z","caller":"traceutil/trace.go:171","msg":"trace[1885662669] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1132; }","duration":"557.2224ms","start":"2026-06-16T01:18:05.146507Z","end":"2026-06-16T01:18:05.703729Z","steps":["trace[1885662669] 'agreement among raft nodes before linearized reading' (duration: 557.078705ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:05.703756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.369469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-06-16T01:18:05.703783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:05.146483Z","time spent":"557.289312ms","remote":"127.0.0.1:58508","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-06-16T01:18:05.703812Z","caller":"traceutil/trace.go:171","msg":"trace[1285045910] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1132; }","duration":"556.42684ms","start":"2026-06-16T01:18:05.147365Z","end":"2026-06-16T01:18:05.703792Z","steps":["trace[1285045910] 'agreement among raft nodes before linearized reading' (duration: 556.243086ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:05.703839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.390339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-06-16T01:18:05.703852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:05.147326Z","time spent":"556.514143ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-16T01:18:05.703895Z","caller":"traceutil/trace.go:171","msg":"trace[268185203] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1132; }","duration":"556.473671ms","start":"2026-06-16T01:18:05.147406Z","end":"2026-06-16T01:18:05.70388Z","steps":["trace[268185203] 'agreement among raft nodes before linearized reading' (duration: 556.316548ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:05.703938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:05.14739Z","time spent":"556.536153ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-16T01:18:05.704284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:04.97668Z","time spent":"726.319164ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:18:05.70496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.61979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-16T01:18:05.705044Z","caller":"traceutil/trace.go:171","msg":"trace[1412589380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1132; }","duration":"557.815835ms","start":"2026-06-16T01:18:05.147215Z","end":"2026-06-16T01:18:05.705031Z","steps":["trace[1412589380] 'agreement among raft nodes before linearized reading' (duration: 556.462521ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:05.705086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:05.147194Z","time spent":"557.880516ms","remote":"127.0.0.1:58508","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-06-16T01:18:05.813893Z","caller":"traceutil/trace.go:171","msg":"trace[1323872807] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"102.865058ms","start":"2026-06-16T01:18:05.710987Z","end":"2026-06-16T01:18:05.813852Z","steps":["trace[1323872807] 'process raft request' (duration: 81.649634ms)","trace[1323872807] 'compare' (duration: 20.988709ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:18:05.813974Z","caller":"traceutil/trace.go:171","msg":"trace[2011060147] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"102.874718ms","start":"2026-06-16T01:18:05.711071Z","end":"2026-06-16T01:18:05.813945Z","steps":["trace[2011060147] 'process raft request' (duration: 102.695094ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:05.814575Z","caller":"traceutil/trace.go:171","msg":"trace[1100394446] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"102.812797ms","start":"2026-06-16T01:18:05.711733Z","end":"2026-06-16T01:18:05.814546Z","steps":["trace[1100394446] 'process raft request' (duration: 102.09891ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:05.81462Z","caller":"traceutil/trace.go:171","msg":"trace[1752731044] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"102.54577ms","start":"2026-06-16T01:18:05.712061Z","end":"2026-06-16T01:18:05.814606Z","steps":["trace[1752731044] 'process raft request' (duration: 102.368835ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:07.8665Z","caller":"traceutil/trace.go:171","msg":"trace[501753440] linearizableReadLoop","detail":"{readStateIndex:1183; appliedIndex:1182; }","duration":"144.731286ms","start":"2026-06-16T01:18:07.721726Z","end":"2026-06-16T01:18:07.866457Z","steps":["trace[501753440] 'read index received' (duration: 144.549142ms)","trace[501753440] 'applied index is now lower than readState.Index' (duration: 181.294µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:18:07.866531Z","caller":"traceutil/trace.go:171","msg":"trace[82743575] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"152.382961ms","start":"2026-06-16T01:18:07.714127Z","end":"2026-06-16T01:18:07.86651Z","steps":["trace[82743575] 'process raft request' (duration: 152.192446ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:07.867067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.309221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-16T01:18:07.867176Z","caller":"traceutil/trace.go:171","msg":"trace[14084567] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1138; }","duration":"145.487456ms","start":"2026-06-16T01:18:07.721664Z","end":"2026-06-16T01:18:07.867151Z","steps":["trace[14084567] 'agreement among raft nodes before linearized reading' (duration: 144.936412ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:07.9908Z","caller":"traceutil/trace.go:171","msg":"trace[91729816] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"119.164039ms","start":"2026-06-16T01:18:07.871615Z","end":"2026-06-16T01:18:07.990779Z","steps":["trace[91729816] 'process raft request' (duration: 119.110038ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:07.990837Z","caller":"traceutil/trace.go:171","msg":"trace[554697081] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"119.174929ms","start":"2026-06-16T01:18:07.871621Z","end":"2026-06-16T01:18:07.990796Z","steps":["trace[554697081] 'process raft request' (duration: 77.37603ms)","trace[554697081] 'compare' (duration: 41.581833ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:18:07.99103Z","caller":"traceutil/trace.go:171","msg":"trace[1215641267] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"119.2222ms","start":"2026-06-16T01:18:07.871793Z","end":"2026-06-16T01:18:07.991015Z","steps":["trace[1215641267] 'process raft request' (duration: 118.960394ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:07.99115Z","caller":"traceutil/trace.go:171","msg":"trace[1598676151] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"119.083796ms","start":"2026-06-16T01:18:07.872056Z","end":"2026-06-16T01:18:07.99114Z","steps":["trace[1598676151] 'process raft request' (duration: 118.893712ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:24.310387Z","caller":"traceutil/trace.go:171","msg":"trace[1132575820] linearizableReadLoop","detail":"{readStateIndex:1296; appliedIndex:1294; }","duration":"110.159491ms","start":"2026-06-16T01:18:24.200204Z","end":"2026-06-16T01:18:24.310364Z","steps":["trace[1132575820] 'read index received' (duration: 62.965465ms)","trace[1132575820] 'applied index is now lower than readState.Index' (duration: 47.193156ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:18:24.310702Z","caller":"traceutil/trace.go:171","msg":"trace[1133581712] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"110.857178ms","start":"2026-06-16T01:18:24.199822Z","end":"2026-06-16T01:18:24.31068Z","steps":["trace[1133581712] 'process raft request' (duration: 110.311144ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:24.310799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.570821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-16T01:18:24.31085Z","caller":"traceutil/trace.go:171","msg":"trace[267080457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1246; }","duration":"110.642343ms","start":"2026-06-16T01:18:24.200194Z","end":"2026-06-16T01:18:24.310837Z","steps":["trace[267080457] 'agreement among raft nodes before linearized reading' (duration: 110.472999ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:24.893246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.574263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-16T01:18:24.893394Z","caller":"traceutil/trace.go:171","msg":"trace[535652331] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1246; }","duration":"690.754147ms","start":"2026-06-16T01:18:24.202618Z","end":"2026-06-16T01:18:24.893372Z","steps":["trace[535652331] 'agreement among raft nodes before linearized reading' (duration: 108.14959ms)","trace[535652331] 'range keys from in-memory index tree' (duration: 582.326931ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:18:24.893474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:24.202609Z","time spent":"690.852029ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-16T01:18:24.893564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.94728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T01:18:24.893651Z","caller":"traceutil/trace.go:171","msg":"trace[362036545] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"578.811732ms","start":"2026-06-16T01:18:24.314826Z","end":"2026-06-16T01:18:24.893638Z","steps":["trace[362036545] 'process raft request' (duration: 197.704108ms)","trace[362036545] 'compare' (duration: 380.747165ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:18:24.893699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:24.314806Z","time spent":"578.873443ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:18:25.045319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.767072ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-16T01:18:25.045942Z","caller":"traceutil/trace.go:171","msg":"trace[2022144442] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"148.999954ms","start":"2026-06-16T01:18:24.89692Z","end":"2026-06-16T01:18:25.04592Z","steps":["trace[2022144442] 'process raft request' (duration: 148.496471ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:26.693817Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096581078700970,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-16T01:18:27.194495Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096581078700970,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-16T01:18:27.695343Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096581078700970,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-16T01:18:28.194614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99994871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-16T01:18:28.194682Z","caller":"traceutil/trace.go:171","msg":"trace[1053887450] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000035402s","start":"2026-06-16T01:18:26.194631Z","end":"2026-06-16T01:18:28.194666Z","steps":["trace[1053887450] 'agreement among raft nodes before linearized reading' (duration: 1.99995577s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:28.194725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:26.194626Z","time spent":"2.000092354s","remote":"127.0.0.1:58184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T01:18:28.196051Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096581078700970,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-16T01:18:28.696841Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096581078700970,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-16T01:18:28.715475Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.770687862s","expected-duration":"1s"} {"level":"info","ts":"2026-06-16T01:18:28.715783Z","caller":"traceutil/trace.go:171","msg":"trace[1069446246] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1299; }","duration":"2.522858865s","start":"2026-06-16T01:18:26.192907Z","end":"2026-06-16T01:18:28.715766Z","steps":["trace[1069446246] 'read index received' (duration: 2.52267846s)","trace[1069446246] 'applied index is now lower than readState.Index' (duration: 179.735µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:18:28.716097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.547609901s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T01:18:28.716139Z","caller":"traceutil/trace.go:171","msg":"trace[1956818445] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1249; }","duration":"1.547694813s","start":"2026-06-16T01:18:27.168431Z","end":"2026-06-16T01:18:28.716126Z","steps":["trace[1956818445] 'agreement among raft nodes before linearized reading' (duration: 1.547618511s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:28.716145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.448912672s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-06-16T01:18:28.716199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:27.168394Z","time spent":"1.547794505s","remote":"127.0.0.1:45882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-06-16T01:18:28.716224Z","caller":"traceutil/trace.go:171","msg":"trace[316447504] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1249; }","duration":"2.449025925s","start":"2026-06-16T01:18:26.267186Z","end":"2026-06-16T01:18:28.716212Z","steps":["trace[316447504] 'agreement among raft nodes before linearized reading' (duration: 2.448810039s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:28.716252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:26.267165Z","time spent":"2.449078547s","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-16T01:18:28.716352Z","caller":"traceutil/trace.go:171","msg":"trace[549531213] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"2.771635026s","start":"2026-06-16T01:18:25.944708Z","end":"2026-06-16T01:18:28.716343Z","steps":["trace[549531213] 'process raft request' (duration: 2.770931589s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:28.716438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.666711408s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-16T01:18:28.71647Z","caller":"traceutil/trace.go:171","msg":"trace[127767114] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1249; }","duration":"1.666743059s","start":"2026-06-16T01:18:27.049716Z","end":"2026-06-16T01:18:28.716459Z","steps":["trace[127767114] 'agreement among raft nodes before linearized reading' (duration: 1.666656667s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:28.716494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:27.049696Z","time spent":"1.666791311s","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-16T01:18:28.716512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.523603043s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-16T01:18:28.716535Z","caller":"traceutil/trace.go:171","msg":"trace[1678741655] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1249; }","duration":"2.523650776s","start":"2026-06-16T01:18:26.192878Z","end":"2026-06-16T01:18:28.716529Z","steps":["trace[1678741655] 'agreement among raft nodes before linearized reading' (duration: 2.523576784s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:28.716552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:26.192864Z","time spent":"2.523683716s","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-16T01:18:28.716619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.52023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T01:18:28.716635Z","caller":"traceutil/trace.go:171","msg":"trace[9606146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1249; }","duration":"521.555821ms","start":"2026-06-16T01:18:28.195074Z","end":"2026-06-16T01:18:28.71663Z","steps":["trace[9606146] 'agreement among raft nodes before linearized reading' (duration: 521.530931ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:28.71665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:28.195062Z","time spent":"521.584612ms","remote":"127.0.0.1:58196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T01:18:28.716691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.819098299s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-16T01:18:28.716714Z","caller":"traceutil/trace.go:171","msg":"trace[806485012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1249; }","duration":"1.819140739s","start":"2026-06-16T01:18:26.897567Z","end":"2026-06-16T01:18:28.716708Z","steps":["trace[806485012] 'agreement among raft nodes before linearized reading' (duration: 1.819071418s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:28.716732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:26.897554Z","time spent":"1.8191742s","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-16T01:18:28.716776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.156779571s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T01:18:28.716794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.122424611s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T01:18:28.716798Z","caller":"traceutil/trace.go:171","msg":"trace[964877243] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1249; }","duration":"1.156802561s","start":"2026-06-16T01:18:27.559989Z","end":"2026-06-16T01:18:28.716792Z","steps":["trace[964877243] 'agreement among raft nodes before linearized reading' (duration: 1.156767711s)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:28.716812Z","caller":"traceutil/trace.go:171","msg":"trace[1097761625] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1249; }","duration":"2.122487413s","start":"2026-06-16T01:18:26.594318Z","end":"2026-06-16T01:18:28.716805Z","steps":["trace[1097761625] 'agreement among raft nodes before linearized reading' (duration: 2.122456732s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:28.716817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:27.559937Z","time spent":"1.156875694s","remote":"127.0.0.1:58630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-06-16T01:18:28.716828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:26.594298Z","time spent":"2.122525234s","remote":"127.0.0.1:58646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-06-16T01:18:28.716436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:25.94469Z","time spent":"2.771683078s","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:18:28.716953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.300467273s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T01:18:28.716975Z","caller":"traceutil/trace.go:171","msg":"trace[1941457553] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1249; }","duration":"2.300532695s","start":"2026-06-16T01:18:26.416435Z","end":"2026-06-16T01:18:28.716968Z","steps":["trace[1941457553] 'agreement among raft nodes before linearized reading' (duration: 2.300496754s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:28.716994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:26.416411Z","time spent":"2.300577595s","remote":"127.0.0.1:59230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-06-16T01:18:28.717179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.403496582s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-16T01:18:28.717238Z","caller":"traceutil/trace.go:171","msg":"trace[1279609366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1249; }","duration":"2.403553173s","start":"2026-06-16T01:18:26.31366Z","end":"2026-06-16T01:18:28.717213Z","steps":["trace[1279609366] 'agreement among raft nodes before linearized reading' (duration: 2.403398909s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:28.71727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:26.313621Z","time spent":"2.403642915s","remote":"127.0.0.1:58508","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-06-16T01:18:28.831285Z","caller":"traceutil/trace.go:171","msg":"trace[552431768] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"109.602516ms","start":"2026-06-16T01:18:28.721659Z","end":"2026-06-16T01:18:28.831262Z","steps":["trace[552431768] 'process raft request' (duration: 109.537825ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:28.831349Z","caller":"traceutil/trace.go:171","msg":"trace[911647918] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"109.684748ms","start":"2026-06-16T01:18:28.721618Z","end":"2026-06-16T01:18:28.831303Z","steps":["trace[911647918] 'process raft request' (duration: 109.525854ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:28.831528Z","caller":"traceutil/trace.go:171","msg":"trace[1580940680] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"109.689299ms","start":"2026-06-16T01:18:28.72183Z","end":"2026-06-16T01:18:28.831519Z","steps":["trace[1580940680] 'process raft request' (duration: 109.398211ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:28.831569Z","caller":"traceutil/trace.go:171","msg":"trace[1066326931] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"110.032237ms","start":"2026-06-16T01:18:28.721508Z","end":"2026-06-16T01:18:28.831541Z","steps":["trace[1066326931] 'process raft request' (duration: 109.494384ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:28.831707Z","caller":"traceutil/trace.go:171","msg":"trace[1069467005] linearizableReadLoop","detail":"{readStateIndex:1306; appliedIndex:1300; }","duration":"106.60039ms","start":"2026-06-16T01:18:28.725093Z","end":"2026-06-16T01:18:28.831693Z","steps":["trace[1069467005] 'read index received' (duration: 57.464726ms)","trace[1069467005] 'applied index is now lower than readState.Index' (duration: 49.134924ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:18:28.831804Z","caller":"traceutil/trace.go:171","msg":"trace[707051181] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"109.239088ms","start":"2026-06-16T01:18:28.722557Z","end":"2026-06-16T01:18:28.831796Z","steps":["trace[707051181] 'process raft request' (duration: 108.927619ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:28.831904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.792346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T01:18:28.831934Z","caller":"traceutil/trace.go:171","msg":"trace[479727009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1255; }","duration":"106.855328ms","start":"2026-06-16T01:18:28.725067Z","end":"2026-06-16T01:18:28.831923Z","steps":["trace[479727009] 'agreement among raft nodes before linearized reading' (duration: 106.798016ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:45.020093Z","caller":"traceutil/trace.go:171","msg":"trace[1237310493] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"107.191735ms","start":"2026-06-16T01:18:44.912606Z","end":"2026-06-16T01:18:45.019798Z","steps":["trace[1237310493] 'process raft request' (duration: 104.978518ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:45.029173Z","caller":"traceutil/trace.go:171","msg":"trace[1752266696] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"115.914656ms","start":"2026-06-16T01:18:44.913242Z","end":"2026-06-16T01:18:45.029157Z","steps":["trace[1752266696] 'process raft request' (duration: 115.683891ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:45.029174Z","caller":"traceutil/trace.go:171","msg":"trace[675378826] linearizableReadLoop","detail":"{readStateIndex:1376; appliedIndex:1374; }","duration":"115.814134ms","start":"2026-06-16T01:18:44.913321Z","end":"2026-06-16T01:18:45.029135Z","steps":["trace[675378826] 'read index received' (duration: 104.250181ms)","trace[675378826] 'applied index is now lower than readState.Index' (duration: 11.562593ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:18:45.029435Z","caller":"traceutil/trace.go:171","msg":"trace[851117916] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"115.390784ms","start":"2026-06-16T01:18:44.914022Z","end":"2026-06-16T01:18:45.029413Z","steps":["trace[851117916] 'process raft request' (duration: 115.066825ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:45.029493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.151123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-16T01:18:45.02956Z","caller":"traceutil/trace.go:171","msg":"trace[232071298] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1322; }","duration":"116.257185ms","start":"2026-06-16T01:18:44.913288Z","end":"2026-06-16T01:18:45.029545Z","steps":["trace[232071298] 'agreement among raft nodes before linearized reading' (duration: 116.067579ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:45.029544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.100335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-16T01:18:45.029611Z","caller":"traceutil/trace.go:171","msg":"trace[1873630388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1322; }","duration":"113.198718ms","start":"2026-06-16T01:18:44.916396Z","end":"2026-06-16T01:18:45.029595Z","steps":["trace[1873630388] 'agreement among raft nodes before linearized reading' (duration: 112.996253ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:45.029644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.968936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-16T01:18:45.029722Z","caller":"traceutil/trace.go:171","msg":"trace[1245629053] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1322; }","duration":"112.084409ms","start":"2026-06-16T01:18:44.917621Z","end":"2026-06-16T01:18:45.029705Z","steps":["trace[1245629053] 'agreement among raft nodes before linearized reading' (duration: 111.882594ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:49.116225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.333235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-16T01:18:49.11631Z","caller":"traceutil/trace.go:171","msg":"trace[1362968985] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:1343; }","duration":"114.458179ms","start":"2026-06-16T01:18:49.00183Z","end":"2026-06-16T01:18:49.116288Z","steps":["trace[1362968985] 'range keys from in-memory index tree' (duration: 114.12663ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:50.886313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.030967ms","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-06-16T01:18:50.886463Z","caller":"traceutil/trace.go:171","msg":"trace[1937905608] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1351; }","duration":"549.195231ms","start":"2026-06-16T01:18:50.337245Z","end":"2026-06-16T01:18:50.88644Z","steps":["trace[1937905608] 'range keys from in-memory index tree' (duration: 549.013517ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:50.887121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.995759ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-16T01:18:50.887224Z","caller":"traceutil/trace.go:171","msg":"trace[223363602] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"1.237031416s","start":"2026-06-16T01:18:49.650174Z","end":"2026-06-16T01:18:50.887205Z","steps":["trace[223363602] 'process raft request' (duration: 544.885673ms)","trace[223363602] 'compare' (duration: 691.65857ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:18:50.887283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:49.650162Z","time spent":"1.237098378s","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-16T01:18:51.73224Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.537087265s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-16T01:18:52.122131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.440295ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-16T01:18:52.122981Z","caller":"traceutil/trace.go:171","msg":"trace[433896639] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"2.041234317s","start":"2026-06-16T01:18:50.081678Z","end":"2026-06-16T01:18:52.122913Z","steps":["trace[433896639] 'process raft request' (duration: 1.65094768s)","trace[433896639] 'compare' (duration: 389.220379ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:18:52.123056Z","caller":"traceutil/trace.go:171","msg":"trace[2102086871] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"1.229534646s","start":"2026-06-16T01:18:50.893494Z","end":"2026-06-16T01:18:52.123028Z","steps":["trace[2102086871] 'process raft request' (duration: 1.229419453s)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:52.123106Z","caller":"traceutil/trace.go:171","msg":"trace[386090000] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1407; }","duration":"2.008677062s","start":"2026-06-16T01:18:50.114412Z","end":"2026-06-16T01:18:52.123089Z","steps":["trace[386090000] 'read index received' (duration: 80.767086ms)","trace[386090000] 'applied index is now lower than readState.Index' (duration: 1.927908706s)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:18:52.123153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:50.081656Z","time spent":"2.041415902s","remote":"127.0.0.1:58314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-16T01:18:52.123159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:50.89347Z","time spent":"1.229644858s","remote":"127.0.0.1:58314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":869,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T01:18:52.123051Z","caller":"traceutil/trace.go:171","msg":"trace[113298123] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"1.217241114s","start":"2026-06-16T01:18:50.905792Z","end":"2026-06-16T01:18:52.123033Z","steps":["trace[113298123] 'process raft request' (duration: 1.217183892s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:52.123329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.939688855s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T01:18:52.123396Z","caller":"traceutil/trace.go:171","msg":"trace[1913429392] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1355; }","duration":"1.939790587s","start":"2026-06-16T01:18:50.183588Z","end":"2026-06-16T01:18:52.123379Z","steps":["trace[1913429392] 'agreement among raft nodes before linearized reading' (duration: 1.939706165s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:52.123438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:50.183565Z","time spent":"1.939864979s","remote":"127.0.0.1:58184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T01:18:52.123403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.670992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T01:18:52.123446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.467627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-16T01:18:52.123488Z","caller":"traceutil/trace.go:171","msg":"trace[948852192] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1355; }","duration":"254.803515ms","start":"2026-06-16T01:18:51.868673Z","end":"2026-06-16T01:18:52.123476Z","steps":["trace[948852192] 'agreement among raft nodes before linearized reading' (duration: 254.694782ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:52.123506Z","caller":"traceutil/trace.go:171","msg":"trace[1473675435] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1355; }","duration":"952.59004ms","start":"2026-06-16T01:18:51.170898Z","end":"2026-06-16T01:18:52.123488Z","steps":["trace[1473675435] 'agreement among raft nodes before linearized reading' (duration: 952.411146ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:52.123358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:50.905776Z","time spent":"1.217502901s","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:18:52.123566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:51.170856Z","time spent":"952.696943ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-16T01:18:52.1237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.874827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-16T01:18:52.123742Z","caller":"traceutil/trace.go:171","msg":"trace[2125252439] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1355; }","duration":"952.955579ms","start":"2026-06-16T01:18:51.170776Z","end":"2026-06-16T01:18:52.123732Z","steps":["trace[2125252439] 'agreement among raft nodes before linearized reading' (duration: 952.822996ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:52.123509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.992895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-16T01:18:52.123828Z","caller":"traceutil/trace.go:171","msg":"trace[1748767761] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1355; }","duration":"952.345614ms","start":"2026-06-16T01:18:51.171469Z","end":"2026-06-16T01:18:52.123814Z","steps":["trace[1748767761] 'agreement among raft nodes before linearized reading' (duration: 951.901013ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:52.123873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.783254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-06-16T01:18:52.123739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.489419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T01:18:52.123947Z","caller":"traceutil/trace.go:171","msg":"trace[1209675453] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1355; }","duration":"556.893477ms","start":"2026-06-16T01:18:51.567036Z","end":"2026-06-16T01:18:52.12393Z","steps":["trace[1209675453] 'agreement among raft nodes before linearized reading' (duration: 556.701022ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:52.123998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:51.567017Z","time spent":"556.965488ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-06-16T01:18:52.123989Z","caller":"traceutil/trace.go:171","msg":"trace[95864555] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1355; }","duration":"247.825727ms","start":"2026-06-16T01:18:51.876141Z","end":"2026-06-16T01:18:52.123967Z","steps":["trace[95864555] 'agreement among raft nodes before linearized reading' (duration: 247.514539ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:52.124239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.160579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-16T01:18:52.124273Z","caller":"traceutil/trace.go:171","msg":"trace[998318374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1355; }","duration":"952.20979ms","start":"2026-06-16T01:18:51.172055Z","end":"2026-06-16T01:18:52.124265Z","steps":["trace[998318374] 'agreement among raft nodes before linearized reading' (duration: 952.031996ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:52.124295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:51.17205Z","time spent":"952.239661ms","remote":"127.0.0.1:58508","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-06-16T01:18:52.124301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.008869466s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T01:18:52.124442Z","caller":"traceutil/trace.go:171","msg":"trace[1423055019] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1355; }","duration":"2.010050736s","start":"2026-06-16T01:18:50.114348Z","end":"2026-06-16T01:18:52.124399Z","steps":["trace[1423055019] 'agreement among raft nodes before linearized reading' (duration: 2.008856306s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:52.123873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.233441153s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-06-16T01:18:52.124505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:50.114322Z","time spent":"2.010167119s","remote":"127.0.0.1:58696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-06-16T01:18:52.124512Z","caller":"traceutil/trace.go:171","msg":"trace[1289186071] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1355; }","duration":"1.23410611s","start":"2026-06-16T01:18:50.890387Z","end":"2026-06-16T01:18:52.124493Z","steps":["trace[1289186071] 'agreement among raft nodes before linearized reading' (duration: 1.233404183s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:52.124552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:50.890371Z","time spent":"1.234171302s","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-16T01:18:52.124771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.728734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-16T01:18:52.124805Z","caller":"traceutil/trace.go:171","msg":"trace[1090494190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1355; }","duration":"952.784265ms","start":"2026-06-16T01:18:51.172012Z","end":"2026-06-16T01:18:52.124796Z","steps":["trace[1090494190] 'agreement among raft nodes before linearized reading' (duration: 951.293147ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:52.124828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:51.171998Z","time spent":"952.823066ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-16T01:18:52.123957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:51.17146Z","time spent":"952.456717ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-16T01:18:52.123773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:51.170753Z","time spent":"953.013541ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-16T01:18:52.342996Z","caller":"traceutil/trace.go:171","msg":"trace[1238279872] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"211.420824ms","start":"2026-06-16T01:18:52.131559Z","end":"2026-06-16T01:18:52.342979Z","steps":["trace[1238279872] 'process raft request' (duration: 211.400344ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:52.343016Z","caller":"traceutil/trace.go:171","msg":"trace[1609387377] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"212.963124ms","start":"2026-06-16T01:18:52.130017Z","end":"2026-06-16T01:18:52.34298Z","steps":["trace[1609387377] 'process raft request' (duration: 212.836941ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:52.343023Z","caller":"traceutil/trace.go:171","msg":"trace[543036252] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"214.205845ms","start":"2026-06-16T01:18:52.128769Z","end":"2026-06-16T01:18:52.342975Z","steps":["trace[543036252] 'process raft request' (duration: 214.00309ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:52.34317Z","caller":"traceutil/trace.go:171","msg":"trace[1796569230] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"212.728268ms","start":"2026-06-16T01:18:52.130435Z","end":"2026-06-16T01:18:52.343163Z","steps":["trace[1796569230] 'process raft request' (duration: 212.451661ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:52.343213Z","caller":"traceutil/trace.go:171","msg":"trace[51618141] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"212.588575ms","start":"2026-06-16T01:18:52.130621Z","end":"2026-06-16T01:18:52.343209Z","steps":["trace[51618141] 'process raft request' (duration: 212.284417ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:52.343252Z","caller":"traceutil/trace.go:171","msg":"trace[1154591036] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"212.304137ms","start":"2026-06-16T01:18:52.130945Z","end":"2026-06-16T01:18:52.343249Z","steps":["trace[1154591036] 'process raft request' (duration: 211.978429ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:52.343284Z","caller":"traceutil/trace.go:171","msg":"trace[2000072973] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"212.304017ms","start":"2026-06-16T01:18:52.130977Z","end":"2026-06-16T01:18:52.343281Z","steps":["trace[2000072973] 'process raft request' (duration: 211.960068ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:54.285119Z","caller":"traceutil/trace.go:171","msg":"trace[1638371286] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"154.511024ms","start":"2026-06-16T01:18:54.130577Z","end":"2026-06-16T01:18:54.285088Z","steps":["trace[1638371286] 'process raft request' (duration: 154.316319ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:54.842292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.72399ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T01:18:54.842658Z","caller":"traceutil/trace.go:171","msg":"trace[278181381] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"552.942246ms","start":"2026-06-16T01:18:54.289689Z","end":"2026-06-16T01:18:54.842632Z","steps":["trace[278181381] 'process raft request' (duration: 552.693949ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:54.842702Z","caller":"traceutil/trace.go:171","msg":"trace[1117962349] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"575.866107ms","start":"2026-06-16T01:18:54.266801Z","end":"2026-06-16T01:18:54.842667Z","steps":["trace[1117962349] 'process raft request' (duration: 191.692695ms)","trace[1117962349] 'compare' (duration: 383.431763ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:18:54.842802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:54.266772Z","time spent":"575.95698ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:18:54.8428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:54.289665Z","time spent":"553.06052ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:18:55.100594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.14655ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-16T01:18:55.100867Z","caller":"traceutil/trace.go:171","msg":"trace[278842600] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"619.581984ms","start":"2026-06-16T01:18:54.481267Z","end":"2026-06-16T01:18:55.100849Z","steps":["trace[278842600] 'process raft request' (duration: 619.529953ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:55.10087Z","caller":"traceutil/trace.go:171","msg":"trace[318564250] linearizableReadLoop","detail":"{readStateIndex:1422; appliedIndex:1420; }","duration":"753.579179ms","start":"2026-06-16T01:18:54.34726Z","end":"2026-06-16T01:18:55.100839Z","steps":["trace[318564250] 'read index received' (duration: 111.257978ms)","trace[318564250] 'applied index is now lower than readState.Index' (duration: 642.318351ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:18:55.100918Z","caller":"traceutil/trace.go:171","msg":"trace[724426487] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"672.397292ms","start":"2026-06-16T01:18:54.428502Z","end":"2026-06-16T01:18:55.100899Z","steps":["trace[724426487] 'process raft request' (duration: 672.186917ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:55.100969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:54.481245Z","time spent":"619.662186ms","remote":"127.0.0.1:58418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6319,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:18:55.101046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:54.428474Z","time spent":"672.486525ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:18:55.101179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.215219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-16T01:18:55.101214Z","caller":"traceutil/trace.go:171","msg":"trace[1984773269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1368; }","duration":"753.283271ms","start":"2026-06-16T01:18:54.347918Z","end":"2026-06-16T01:18:55.101201Z","steps":["trace[1984773269] 'agreement among raft nodes before linearized reading' (duration: 753.162538ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:55.101225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.941837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-06-16T01:18:55.101253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:54.3479Z","time spent":"753.342073ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-16T01:18:55.101284Z","caller":"traceutil/trace.go:171","msg":"trace[1864542005] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1368; }","duration":"754.06297ms","start":"2026-06-16T01:18:54.347204Z","end":"2026-06-16T01:18:55.101267Z","steps":["trace[1864542005] 'agreement among raft nodes before linearized reading' (duration: 753.805094ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:55.101339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:54.347182Z","time spent":"754.142413ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-16T01:18:55.101384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.679774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-16T01:18:55.101423Z","caller":"traceutil/trace.go:171","msg":"trace[1516812061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1368; }","duration":"746.729425ms","start":"2026-06-16T01:18:54.354683Z","end":"2026-06-16T01:18:55.101413Z","steps":["trace[1516812061] 'agreement among raft nodes before linearized reading' (duration: 746.609122ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:55.101454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:54.354675Z","time spent":"746.769776ms","remote":"127.0.0.1:58508","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-06-16T01:18:55.101474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.411654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-16T01:18:55.10153Z","caller":"traceutil/trace.go:171","msg":"trace[1710269400] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1368; }","duration":"753.599039ms","start":"2026-06-16T01:18:54.347918Z","end":"2026-06-16T01:18:55.101517Z","steps":["trace[1710269400] 'agreement among raft nodes before linearized reading' (duration: 753.397564ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:55.101579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:54.347905Z","time spent":"753.667871ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-16T01:18:57.34714Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313096581078701250,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-16T01:18:57.355975Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.065292145s","expected-duration":"1s"} {"level":"info","ts":"2026-06-16T01:18:57.356354Z","caller":"traceutil/trace.go:171","msg":"trace[1205632752] linearizableReadLoop","detail":"{readStateIndex:1430; appliedIndex:1429; }","duration":"509.595418ms","start":"2026-06-16T01:18:56.846738Z","end":"2026-06-16T01:18:57.356333Z","steps":["trace[1205632752] 'read index received' (duration: 509.351962ms)","trace[1205632752] 'applied index is now lower than readState.Index' (duration: 242.466µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T01:18:57.356455Z","caller":"traceutil/trace.go:171","msg":"trace[1289434307] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"1.065831779s","start":"2026-06-16T01:18:56.290598Z","end":"2026-06-16T01:18:57.35643Z","steps":["trace[1289434307] 'process raft request' (duration: 1.065555522s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:57.356566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.825974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-06-16T01:18:57.356593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.793224ms","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-06-16T01:18:57.356592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:56.290576Z","time spent":"1.06592186s","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T01:18:57.356622Z","caller":"traceutil/trace.go:171","msg":"trace[2109700965] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1373; }","duration":"220.862445ms","start":"2026-06-16T01:18:57.135751Z","end":"2026-06-16T01:18:57.356614Z","steps":["trace[2109700965] 'agreement among raft nodes before linearized reading' (duration: 220.790254ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:57.356667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.665834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-16T01:18:57.35669Z","caller":"traceutil/trace.go:171","msg":"trace[224772364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1373; }","duration":"170.711784ms","start":"2026-06-16T01:18:57.185972Z","end":"2026-06-16T01:18:57.356683Z","steps":["trace[224772364] 'agreement among raft nodes before linearized reading' (duration: 170.646812ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:57.356759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.614977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T01:18:57.356778Z","caller":"traceutil/trace.go:171","msg":"trace[372774361] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1373; }","duration":"171.635087ms","start":"2026-06-16T01:18:57.185137Z","end":"2026-06-16T01:18:57.356772Z","steps":["trace[372774361] 'agreement among raft nodes before linearized reading' (duration: 171.604747ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:57.356819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.968321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-16T01:18:57.356857Z","caller":"traceutil/trace.go:171","msg":"trace[368399870] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1373; }","duration":"171.040472ms","start":"2026-06-16T01:18:57.185804Z","end":"2026-06-16T01:18:57.356844Z","steps":["trace[368399870] 'agreement among raft nodes before linearized reading' (duration: 170.902539ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:57.356997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.454767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T01:18:57.357008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.086004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-16T01:18:57.357029Z","caller":"traceutil/trace.go:171","msg":"trace[1081487357] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1373; }","duration":"164.499617ms","start":"2026-06-16T01:18:57.192518Z","end":"2026-06-16T01:18:57.357018Z","steps":["trace[1081487357] 'agreement among raft nodes before linearized reading' (duration: 164.452996ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:57.357036Z","caller":"traceutil/trace.go:171","msg":"trace[566033351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1373; }","duration":"171.167236ms","start":"2026-06-16T01:18:57.185861Z","end":"2026-06-16T01:18:57.357028Z","steps":["trace[566033351] 'agreement among raft nodes before linearized reading' (duration: 171.082214ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:57.356598Z","caller":"traceutil/trace.go:171","msg":"trace[1579424219] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1373; }","duration":"509.927907ms","start":"2026-06-16T01:18:56.84666Z","end":"2026-06-16T01:18:57.356588Z","steps":["trace[1579424219] 'agreement among raft nodes before linearized reading' (duration: 509.788153ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:57.357114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:56.846624Z","time spent":"510.482121ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-16T01:18:58.088587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.860157ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T01:18:58.088686Z","caller":"traceutil/trace.go:171","msg":"trace[501904754] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"727.648872ms","start":"2026-06-16T01:18:57.361019Z","end":"2026-06-16T01:18:58.088667Z","steps":["trace[501904754] 'process raft request' (duration: 286.632071ms)","trace[501904754] 'compare' (duration: 440.642522ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:18:58.08874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:57.360998Z","time spent":"727.716274ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T01:18:58.186429Z","caller":"traceutil/trace.go:171","msg":"trace[1538373759] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"824.066794ms","start":"2026-06-16T01:18:57.362335Z","end":"2026-06-16T01:18:58.186402Z","steps":["trace[1538373759] 'process raft request' (duration: 823.999533ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:58.186457Z","caller":"traceutil/trace.go:171","msg":"trace[381310297] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"825.247464ms","start":"2026-06-16T01:18:57.361172Z","end":"2026-06-16T01:18:58.18642Z","steps":["trace[381310297] 'process raft request' (duration: 825.009958ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:58.186511Z","caller":"traceutil/trace.go:171","msg":"trace[1561463749] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"825.190962ms","start":"2026-06-16T01:18:57.36128Z","end":"2026-06-16T01:18:58.186471Z","steps":["trace[1561463749] 'process raft request' (duration: 825.026428ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:58.186661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:57.362326Z","time spent":"824.192137ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:18:58.186669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:57.361165Z","time spent":"825.404178ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T01:18:58.186764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:57.361255Z","time spent":"825.416008ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T01:18:58.186893Z","caller":"traceutil/trace.go:171","msg":"trace[134288272] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"824.497815ms","start":"2026-06-16T01:18:57.362367Z","end":"2026-06-16T01:18:58.186865Z","steps":["trace[134288272] 'process raft request' (duration: 823.992512ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:58.186993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:57.362354Z","time spent":"824.583877ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T01:18:58.187135Z","caller":"traceutil/trace.go:171","msg":"trace[4724978] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"823.551181ms","start":"2026-06-16T01:18:57.363568Z","end":"2026-06-16T01:18:58.187119Z","steps":["trace[4724978] 'process raft request' (duration: 823.381587ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:18:58.187168Z","caller":"traceutil/trace.go:171","msg":"trace[2076656873] linearizableReadLoop","detail":"{readStateIndex:1437; appliedIndex:1430; }","duration":"672.730791ms","start":"2026-06-16T01:18:57.514415Z","end":"2026-06-16T01:18:58.187146Z","steps":["trace[2076656873] 'read index received' (duration: 133.246716ms)","trace[2076656873] 'applied index is now lower than readState.Index' (duration: 539.482655ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:18:58.18722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:57.363546Z","time spent":"823.628874ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T01:18:58.187278Z","caller":"traceutil/trace.go:171","msg":"trace[618990883] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"823.656744ms","start":"2026-06-16T01:18:57.363611Z","end":"2026-06-16T01:18:58.187267Z","steps":["trace[618990883] 'process raft request' (duration: 823.433658ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:58.187326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.925014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-16T01:18:58.187332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.916335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T01:18:58.187363Z","caller":"traceutil/trace.go:171","msg":"trace[682860436] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1380; }","duration":"533.996736ms","start":"2026-06-16T01:18:57.653357Z","end":"2026-06-16T01:18:58.187354Z","steps":["trace[682860436] 'agreement among raft nodes before linearized reading' (duration: 533.917694ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:58.187358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:57.363599Z","time spent":"823.717515ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T01:18:58.187374Z","caller":"traceutil/trace.go:171","msg":"trace[419240195] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1380; }","duration":"672.958326ms","start":"2026-06-16T01:18:57.514402Z","end":"2026-06-16T01:18:58.18736Z","steps":["trace[419240195] 'agreement among raft nodes before linearized reading' (duration: 672.891675ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:18:58.187389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:57.65334Z","time spent":"534.041177ms","remote":"127.0.0.1:58348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":17,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-06-16T01:18:58.187413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:18:57.514348Z","time spent":"673.053489ms","remote":"127.0.0.1:59214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-06-16T01:19:05.494988Z","caller":"traceutil/trace.go:171","msg":"trace[1232852501] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"119.62099ms","start":"2026-06-16T01:19:05.375344Z","end":"2026-06-16T01:19:05.494965Z","steps":["trace[1232852501] 'process raft request' (duration: 119.484897ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:19:05.511645Z","caller":"traceutil/trace.go:171","msg":"trace[461497407] transaction","detail":"{read_only:false; response_revision:1433; number_of_response:1; }","duration":"128.190737ms","start":"2026-06-16T01:19:05.383424Z","end":"2026-06-16T01:19:05.511615Z","steps":["trace[461497407] 'process raft request' (duration: 127.984132ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:19:18.515247Z","caller":"traceutil/trace.go:171","msg":"trace[554361912] transaction","detail":"{read_only:false; response_revision:1492; number_of_response:1; }","duration":"125.464572ms","start":"2026-06-16T01:19:18.389756Z","end":"2026-06-16T01:19:18.51522Z","steps":["trace[554361912] 'process raft request' (duration: 124.957959ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:19:18.520137Z","caller":"traceutil/trace.go:171","msg":"trace[2119904648] transaction","detail":"{read_only:false; response_revision:1493; number_of_response:1; }","duration":"129.591488ms","start":"2026-06-16T01:19:18.390509Z","end":"2026-06-16T01:19:18.5201Z","steps":["trace[2119904648] 'process raft request' (duration: 129.218837ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:19:26.844259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.365099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-06-16T01:19:26.844353Z","caller":"traceutil/trace.go:171","msg":"trace[1020770413] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1532; }","duration":"101.599055ms","start":"2026-06-16T01:19:26.74273Z","end":"2026-06-16T01:19:26.844329Z","steps":["trace[1020770413] 'range keys from in-memory index tree' (duration: 101.172424ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:42.229994Z","caller":"traceutil/trace.go:171","msg":"trace[61298549] transaction","detail":"{read_only:false; response_revision:2448; number_of_response:1; }","duration":"268.931861ms","start":"2026-06-16T01:22:41.961039Z","end":"2026-06-16T01:22:42.229971Z","steps":["trace[61298549] 'process raft request' (duration: 268.798088ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T01:22:47.721603Z","caller":"traceutil/trace.go:171","msg":"trace[388649019] transaction","detail":"{read_only:false; response_revision:2477; number_of_response:1; }","duration":"133.943437ms","start":"2026-06-16T01:22:47.587638Z","end":"2026-06-16T01:22:47.721581Z","steps":["trace[388649019] 'process raft request' (duration: 133.147286ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:48.627333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.347504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T01:22:48.627437Z","caller":"traceutil/trace.go:171","msg":"trace[1403576785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2478; }","duration":"443.501908ms","start":"2026-06-16T01:22:48.183912Z","end":"2026-06-16T01:22:48.627414Z","steps":["trace[1403576785] 'range keys from in-memory index tree' (duration: 443.20199ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:48.627423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.93661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-06-16T01:22:48.627806Z","caller":"traceutil/trace.go:171","msg":"trace[1936219802] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2478; }","duration":"369.28435ms","start":"2026-06-16T01:22:48.258433Z","end":"2026-06-16T01:22:48.627718Z","steps":["trace[1936219802] 'range keys from in-memory index tree' (duration: 368.791167ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T01:22:48.627948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:22:48.25842Z","time spent":"369.447123ms","remote":"127.0.0.1:58508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-06-16T01:22:48.627428Z","caller":"traceutil/trace.go:171","msg":"trace[793391086] transaction","detail":"{read_only:false; response_revision:2479; number_of_response:1; }","duration":"276.767562ms","start":"2026-06-16T01:22:48.35063Z","end":"2026-06-16T01:22:48.627398Z","steps":["trace[793391086] 'process raft request' (duration: 257.762096ms)","trace[793391086] 'compare' (duration: 18.34148ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:22:48.627489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T01:22:48.183897Z","time spent":"443.581919ms","remote":"127.0.0.1:58184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-16T01:22:48.627421Z","caller":"traceutil/trace.go:171","msg":"trace[1027330852] linearizableReadLoop","detail":"{readStateIndex:2583; appliedIndex:2582; }","duration":"230.61461ms","start":"2026-06-16T01:22:48.396779Z","end":"2026-06-16T01:22:48.627394Z","steps":["trace[1027330852] 'read index received' (duration: 211.522321ms)","trace[1027330852] 'applied index is now lower than readState.Index' (duration: 19.091259ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T01:22:48.627535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.745114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T01:22:48.628405Z","caller":"traceutil/trace.go:171","msg":"trace[1225979141] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2479; }","duration":"231.651257ms","start":"2026-06-16T01:22:48.396739Z","end":"2026-06-16T01:22:48.628391Z","steps":["trace[1225979141] 'agreement among raft nodes before linearized reading' (duration: 230.737683ms)"],"step_count":1}