{"level":"warn","ts":"2026-06-15T20:01:39.489113Z","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-15T20:01:39.48926Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.193:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.193:2380","--initial-cluster=instance=https://199.19.213.193:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.193:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.193: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-15T20:01:39.489358Z","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-15T20:01:39.489377Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.193:2380"]} {"level":"info","ts":"2026-06-15T20:01:39.489408Z","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-15T20:01:39.490451Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.193:2379"]} {"level":"info","ts":"2026-06-15T20:01:39.490566Z","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.19.213.193:2380"],"listen-peer-urls":["https://199.19.213.193:2380"],"advertise-client-urls":["https://199.19.213.193:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.193:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.193: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-15T20:01:39.500388Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.534106ms"} {"level":"info","ts":"2026-06-15T20:01:39.506867Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fc4b8086375a405d","cluster-id":"7155ef20751e16de"} {"level":"info","ts":"2026-06-15T20:01:39.509977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d switched to configuration voters=()"} {"level":"info","ts":"2026-06-15T20:01:39.510008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became follower at term 0"} {"level":"info","ts":"2026-06-15T20:01:39.510024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fc4b8086375a405d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-15T20:01:39.510038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became follower at term 1"} {"level":"info","ts":"2026-06-15T20:01:39.510095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d switched to configuration voters=(18179765634753773661)"} {"level":"warn","ts":"2026-06-15T20:01:39.519549Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-15T20:01:39.520872Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-15T20:01:39.522077Z","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-15T20:01:39.524397Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fc4b8086375a405d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-15T20:01:39.524981Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fc4b8086375a405d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-15T20:01:39.525022Z","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-15T20:01:39.52535Z","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-15T20:01:39.525428Z","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-15T20:01:39.527247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d switched to configuration voters=(18179765634753773661)"} {"level":"info","ts":"2026-06-15T20:01:39.527866Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7155ef20751e16de","local-member-id":"fc4b8086375a405d","added-peer-id":"fc4b8086375a405d","added-peer-peer-urls":["https://199.19.213.193:2380"]} {"level":"info","ts":"2026-06-15T20:01:39.529916Z","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-15T20:01:39.530053Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.193:2380"} {"level":"info","ts":"2026-06-15T20:01:39.530158Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.193:2380"} {"level":"info","ts":"2026-06-15T20:01:39.530408Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fc4b8086375a405d","initial-advertise-peer-urls":["https://199.19.213.193:2380"],"listen-peer-urls":["https://199.19.213.193:2380"],"advertise-client-urls":["https://199.19.213.193:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.193:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-15T20:01:39.530516Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-15T20:01:39.810702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d is starting a new election at term 1"} {"level":"info","ts":"2026-06-15T20:01:39.810785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became pre-candidate at term 1"} {"level":"info","ts":"2026-06-15T20:01:39.810844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d received MsgPreVoteResp from fc4b8086375a405d at term 1"} {"level":"info","ts":"2026-06-15T20:01:39.810875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became candidate at term 2"} {"level":"info","ts":"2026-06-15T20:01:39.810892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d received MsgVoteResp from fc4b8086375a405d at term 2"} {"level":"info","ts":"2026-06-15T20:01:39.810914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became leader at term 2"} {"level":"info","ts":"2026-06-15T20:01:39.810939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fc4b8086375a405d elected leader fc4b8086375a405d at term 2"} {"level":"info","ts":"2026-06-15T20:01:39.812123Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fc4b8086375a405d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.193:2379]}","request-path":"/0/members/fc4b8086375a405d/attributes","cluster-id":"7155ef20751e16de","publish-timeout":"7s"} {"level":"info","ts":"2026-06-15T20:01:39.812187Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-15T20:01:39.812256Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-15T20:01:39.812657Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T20:01:39.813111Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-15T20:01:39.813183Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-15T20:01:39.813911Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7155ef20751e16de","local-member-id":"fc4b8086375a405d","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T20:01:39.81428Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T20:01:39.814466Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T20:01:39.815862Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-15T20:01:39.81596Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.193:2379"} {"level":"info","ts":"2026-06-15T20:01:45.666405Z","caller":"traceutil/trace.go:171","msg":"trace[1521907740] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"153.683707ms","start":"2026-06-15T20:01:45.512687Z","end":"2026-06-15T20:01:45.666371Z","steps":["trace[1521907740] 'process raft request' (duration: 153.633516ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:01:45.666473Z","caller":"traceutil/trace.go:171","msg":"trace[238403046] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:290; }","duration":"171.98514ms","start":"2026-06-15T20:01:45.494455Z","end":"2026-06-15T20:01:45.66644Z","steps":["trace[238403046] 'read index received' (duration: 74.107244ms)","trace[238403046] 'applied index is now lower than readState.Index' (duration: 97.874036ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:01:45.666685Z","caller":"traceutil/trace.go:171","msg":"trace[688169716] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"172.126584ms","start":"2026-06-15T20:01:45.494259Z","end":"2026-06-15T20:01:45.666385Z","steps":["trace[688169716] 'process raft request' (duration: 74.165345ms)","trace[688169716] 'compare' (duration: 97.693072ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:01:45.666757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.232926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:01:45.666886Z","caller":"traceutil/trace.go:171","msg":"trace[1817907541] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:0; response_revision:286; }","duration":"172.470402ms","start":"2026-06-15T20:01:45.494387Z","end":"2026-06-15T20:01:45.666857Z","steps":["trace[1817907541] 'agreement among raft nodes before linearized reading' (duration: 172.194375ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:01:45.765268Z","caller":"traceutil/trace.go:171","msg":"trace[494119002] transaction","detail":"{read_only:false; number_of_response:0; response_revision:286; }","duration":"152.62533ms","start":"2026-06-15T20:01:45.612547Z","end":"2026-06-15T20:01:45.765173Z","steps":["trace[494119002] 'process raft request' (duration: 152.505506ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:01:45.772647Z","caller":"traceutil/trace.go:171","msg":"trace[1054970775] transaction","detail":"{read_only:false; number_of_response:0; response_revision:286; }","duration":"146.840261ms","start":"2026-06-15T20:01:45.625772Z","end":"2026-06-15T20:01:45.772612Z","steps":["trace[1054970775] 'process raft request' (duration: 146.689567ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:01:45.772716Z","caller":"traceutil/trace.go:171","msg":"trace[387059804] transaction","detail":"{read_only:false; number_of_response:0; response_revision:286; }","duration":"146.695337ms","start":"2026-06-15T20:01:45.625973Z","end":"2026-06-15T20:01:45.772668Z","steps":["trace[387059804] 'process raft request' (duration: 146.584554ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:01:45.773016Z","caller":"traceutil/trace.go:171","msg":"trace[2093917807] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"100.121455ms","start":"2026-06-15T20:01:45.672836Z","end":"2026-06-15T20:01:45.772958Z","steps":["trace[2093917807] 'process raft request' (duration: 99.94298ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:01:45.773019Z","caller":"traceutil/trace.go:171","msg":"trace[313616827] linearizableReadLoop","detail":"{readStateIndex:298; appliedIndex:293; }","duration":"106.315595ms","start":"2026-06-15T20:01:45.666608Z","end":"2026-06-15T20:01:45.772924Z","steps":["trace[313616827] 'read index received' (duration: 98.561234ms)","trace[313616827] 'applied index is now lower than readState.Index' (duration: 7.752981ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:01:45.773239Z","caller":"traceutil/trace.go:171","msg":"trace[29098343] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"145.069225ms","start":"2026-06-15T20:01:45.628158Z","end":"2026-06-15T20:01:45.773227Z","steps":["trace[29098343] 'process raft request' (duration: 144.48316ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:01:45.773342Z","caller":"traceutil/trace.go:171","msg":"trace[1535179693] transaction","detail":"{read_only:false; number_of_response:0; response_revision:287; }","duration":"144.290935ms","start":"2026-06-15T20:01:45.62904Z","end":"2026-06-15T20:01:45.773331Z","steps":["trace[1535179693] 'process raft request' (duration: 143.70976ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:45.773314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.238383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:01:45.773469Z","caller":"traceutil/trace.go:171","msg":"trace[1711183596] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:289; }","duration":"261.432288ms","start":"2026-06-15T20:01:45.512019Z","end":"2026-06-15T20:01:45.773452Z","steps":["trace[1711183596] 'agreement among raft nodes before linearized reading' (duration: 261.183652ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:45.773583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.243715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-06-15T20:01:45.77363Z","caller":"traceutil/trace.go:171","msg":"trace[892540168] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:289; }","duration":"103.354668ms","start":"2026-06-15T20:01:45.670264Z","end":"2026-06-15T20:01:45.773619Z","steps":["trace[892540168] 'agreement among raft nodes before linearized reading' (duration: 103.225494ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:01:45.773758Z","caller":"traceutil/trace.go:171","msg":"trace[1196422900] transaction","detail":"{read_only:false; number_of_response:0; response_revision:286; }","duration":"145.919707ms","start":"2026-06-15T20:01:45.627791Z","end":"2026-06-15T20:01:45.773711Z","steps":["trace[1196422900] 'process raft request' (duration: 144.791038ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:45.774016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.119966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-06-15T20:01:45.774093Z","caller":"traceutil/trace.go:171","msg":"trace[1762262172] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:289; }","duration":"262.264919ms","start":"2026-06-15T20:01:45.511813Z","end":"2026-06-15T20:01:45.774078Z","steps":["trace[1762262172] 'agreement among raft nodes before linearized reading' (duration: 261.729356ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:01:59.44316Z","caller":"traceutil/trace.go:171","msg":"trace[986531088] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"107.788023ms","start":"2026-06-15T20:01:59.335338Z","end":"2026-06-15T20:01:59.443126Z","steps":["trace[986531088] 'process raft request' (duration: 107.218558ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:05.486395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.965637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:02:05.48646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.203417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"warn","ts":"2026-06-15T20:02:05.48648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.816971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-15T20:02:05.486586Z","caller":"traceutil/trace.go:171","msg":"trace[2083987245] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:435; }","duration":"731.007707ms","start":"2026-06-15T20:02:04.755557Z","end":"2026-06-15T20:02:05.486565Z","steps":["trace[2083987245] 'range keys from in-memory index tree' (duration: 730.658538ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:05.486516Z","caller":"traceutil/trace.go:171","msg":"trace[275556121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:435; }","duration":"672.197173ms","start":"2026-06-15T20:02:04.814294Z","end":"2026-06-15T20:02:05.486491Z","steps":["trace[275556121] 'range keys from in-memory index tree' (duration: 671.839174ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:05.486632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:04.755521Z","time spent":"731.101669ms","remote":"127.0.0.1:54600","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-15T20:02:05.486704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:04.814261Z","time spent":"672.428649ms","remote":"127.0.0.1:54374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-15T20:02:05.486516Z","caller":"traceutil/trace.go:171","msg":"trace[1409678761] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:435; }","duration":"212.30873ms","start":"2026-06-15T20:02:05.274188Z","end":"2026-06-15T20:02:05.486496Z","steps":["trace[1409678761] 'range keys from in-memory index tree' (duration: 212.061234ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:05.971498Z","caller":"traceutil/trace.go:171","msg":"trace[1422573410] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"479.975224ms","start":"2026-06-15T20:02:05.491489Z","end":"2026-06-15T20:02:05.971464Z","steps":["trace[1422573410] 'process raft request' (duration: 479.716387ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:05.972637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:05.491462Z","time spent":"480.184969ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:02:06.043926Z","caller":"traceutil/trace.go:171","msg":"trace[1089110197] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"552.182344ms","start":"2026-06-15T20:02:05.49168Z","end":"2026-06-15T20:02:06.043862Z","steps":["trace[1089110197] 'process raft request' (duration: 540.022649ms)","trace[1089110197] 'compare' (duration: 11.871308ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:02:06.044171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:05.491636Z","time spent":"552.410391ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:02:06.044065Z","caller":"traceutil/trace.go:171","msg":"trace[451135437] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"545.552544ms","start":"2026-06-15T20:02:05.498417Z","end":"2026-06-15T20:02:06.04397Z","steps":["trace[451135437] 'process raft request' (duration: 545.331208ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:06.044369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:05.498392Z","time spent":"545.857861ms","remote":"127.0.0.1:54516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4361,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:02:07.894363Z","caller":"traceutil/trace.go:171","msg":"trace[1695567956] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"110.559423ms","start":"2026-06-15T20:02:07.783771Z","end":"2026-06-15T20:02:07.894331Z","steps":["trace[1695567956] 'process raft request' (duration: 101.572121ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:23.222523Z","caller":"traceutil/trace.go:171","msg":"trace[964817758] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"100.466643ms","start":"2026-06-15T20:02:23.122024Z","end":"2026-06-15T20:02:23.222491Z","steps":["trace[964817758] 'process raft request' (duration: 100.262338ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:24.654087Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638037794031844036,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-15T20:02:25.324117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.217603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:02:25.324279Z","caller":"traceutil/trace.go:171","msg":"trace[205963374] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:566; }","duration":"1.170721167s","start":"2026-06-15T20:02:24.153533Z","end":"2026-06-15T20:02:25.324255Z","steps":["trace[205963374] 'read index received' (duration: 769.131115ms)","trace[205963374] 'applied index is now lower than readState.Index' (duration: 401.588312ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:02:25.324418Z","caller":"traceutil/trace.go:171","msg":"trace[1672137912] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"1.174818083s","start":"2026-06-15T20:02:24.149583Z","end":"2026-06-15T20:02:25.324401Z","steps":["trace[1672137912] 'process raft request' (duration: 772.943013ms)","trace[1672137912] 'compare' (duration: 400.673169ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:02:25.324495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:24.149566Z","time spent":"1.174887014s","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:02:25.324767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171233551s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:25.324802Z","caller":"traceutil/trace.go:171","msg":"trace[1772580850] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:544; }","duration":"1.171306172s","start":"2026-06-15T20:02:24.153485Z","end":"2026-06-15T20:02:25.324791Z","steps":["trace[1772580850] 'agreement among raft nodes before linearized reading' (duration: 1.17123713s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:25.324835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:24.153459Z","time spent":"1.171367164s","remote":"127.0.0.1:54468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-06-15T20:02:25.324968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.062059683s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-06-15T20:02:25.325067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.450217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:25.325099Z","caller":"traceutil/trace.go:171","msg":"trace[2000637382] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:544; }","duration":"1.062260649s","start":"2026-06-15T20:02:24.262813Z","end":"2026-06-15T20:02:25.325074Z","steps":["trace[2000637382] 'agreement among raft nodes before linearized reading' (duration: 1.062041183s)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:25.32512Z","caller":"traceutil/trace.go:171","msg":"trace[419422524] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:544; }","duration":"509.503848ms","start":"2026-06-15T20:02:24.815601Z","end":"2026-06-15T20:02:25.325104Z","steps":["trace[419422524] 'agreement among raft nodes before linearized reading' (duration: 509.426106ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:25.325157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:24.815533Z","time spent":"509.617851ms","remote":"127.0.0.1:54374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-15T20:02:25.325154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:24.262784Z","time spent":"1.062359821s","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-15T20:02:25.325168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.079542ms","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-15T20:02:25.325207Z","caller":"traceutil/trace.go:171","msg":"trace[309683430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:544; }","duration":"918.160844ms","start":"2026-06-15T20:02:24.407034Z","end":"2026-06-15T20:02:25.325195Z","steps":["trace[309683430] 'agreement among raft nodes before linearized reading' (duration: 918.083042ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:25.325235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:24.407009Z","time spent":"918.220296ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-15T20:02:25.828396Z","caller":"traceutil/trace.go:171","msg":"trace[2138157581] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"132.02423ms","start":"2026-06-15T20:02:25.696326Z","end":"2026-06-15T20:02:25.82835Z","steps":["trace[2138157581] 'process raft request' (duration: 131.803295ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:25.990201Z","caller":"traceutil/trace.go:171","msg":"trace[998070873] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"245.400077ms","start":"2026-06-15T20:02:25.744765Z","end":"2026-06-15T20:02:25.990165Z","steps":["trace[998070873] 'process raft request' (duration: 240.312875ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:25.99164Z","caller":"traceutil/trace.go:171","msg":"trace[992662509] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"232.003761ms","start":"2026-06-15T20:02:25.759622Z","end":"2026-06-15T20:02:25.991626Z","steps":["trace[992662509] 'process raft request' (duration: 231.959968ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:25.991736Z","caller":"traceutil/trace.go:171","msg":"trace[241230275] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"246.840415ms","start":"2026-06-15T20:02:25.744876Z","end":"2026-06-15T20:02:25.991717Z","steps":["trace[241230275] 'process raft request' (duration: 246.005043ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:25.992362Z","caller":"traceutil/trace.go:171","msg":"trace[308148116] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"127.552554ms","start":"2026-06-15T20:02:25.86477Z","end":"2026-06-15T20:02:25.992322Z","steps":["trace[308148116] 'process raft request' (duration: 126.883127ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:25.992892Z","caller":"traceutil/trace.go:171","msg":"trace[719470913] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"110.943424ms","start":"2026-06-15T20:02:25.881925Z","end":"2026-06-15T20:02:25.992868Z","steps":["trace[719470913] 'process raft request' (duration: 110.312768ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:27.751847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.637706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:27.751944Z","caller":"traceutil/trace.go:171","msg":"trace[1085136424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"928.78935ms","start":"2026-06-15T20:02:26.823136Z","end":"2026-06-15T20:02:27.751925Z","steps":["trace[1085136424] 'range keys from in-memory index tree' (duration: 928.527044ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:27.751975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:26.82311Z","time spent":"928.858372ms","remote":"127.0.0.1:54374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-15T20:02:27.753457Z","caller":"traceutil/trace.go:171","msg":"trace[1389773474] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"885.538629ms","start":"2026-06-15T20:02:26.867895Z","end":"2026-06-15T20:02:27.753433Z","steps":["trace[1389773474] 'process raft request' (duration: 885.330823ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:27.753612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:26.867863Z","time spent":"885.681293ms","remote":"127.0.0.1:54422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-15T20:02:27.762602Z","caller":"traceutil/trace.go:171","msg":"trace[1332605242] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"432.280969ms","start":"2026-06-15T20:02:27.330288Z","end":"2026-06-15T20:02:27.762569Z","steps":["trace[1332605242] 'read index received' (duration: 423.249754ms)","trace[1332605242] 'applied index is now lower than readState.Index' (duration: 9.029975ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:02:27.763242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.930395ms","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-15T20:02:27.763284Z","caller":"traceutil/trace.go:171","msg":"trace[395221033] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"820.285999ms","start":"2026-06-15T20:02:26.942956Z","end":"2026-06-15T20:02:27.763242Z","steps":["trace[395221033] 'process raft request' (duration: 819.177351ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:27.76336Z","caller":"traceutil/trace.go:171","msg":"trace[995919023] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:617; }","duration":"433.085349ms","start":"2026-06-15T20:02:27.330242Z","end":"2026-06-15T20:02:27.763327Z","steps":["trace[995919023] 'agreement among raft nodes before linearized reading' (duration: 432.864944ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:27.763419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:26.94291Z","time spent":"820.428483ms","remote":"127.0.0.1:54422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:02:27.763426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:27.330223Z","time spent":"433.189042ms","remote":"127.0.0.1:54600","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-15T20:02:27.76341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.190339ms","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-15T20:02:27.76354Z","caller":"traceutil/trace.go:171","msg":"trace[1911204698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:617; }","duration":"419.396545ms","start":"2026-06-15T20:02:27.344104Z","end":"2026-06-15T20:02:27.763501Z","steps":["trace[1911204698] 'agreement among raft nodes before linearized reading' (duration: 419.136648ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:27.763586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:27.344068Z","time spent":"419.508768ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T20:02:27.763794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.347731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-15T20:02:27.763831Z","caller":"traceutil/trace.go:171","msg":"trace[52865928] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:617; }","duration":"417.436954ms","start":"2026-06-15T20:02:27.346383Z","end":"2026-06-15T20:02:27.76382Z","steps":["trace[52865928] 'agreement among raft nodes before linearized reading' (duration: 417.27701ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:27.763859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:27.346356Z","time spent":"417.497555ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-15T20:02:33.093074Z","caller":"traceutil/trace.go:171","msg":"trace[1321460032] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"105.996465ms","start":"2026-06-15T20:02:32.987033Z","end":"2026-06-15T20:02:33.093029Z","steps":["trace[1321460032] 'process raft request' (duration: 100.866743ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:33.769587Z","caller":"traceutil/trace.go:171","msg":"trace[1255713951] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"172.510729ms","start":"2026-06-15T20:02:33.597023Z","end":"2026-06-15T20:02:33.769534Z","steps":["trace[1255713951] 'process raft request' (duration: 171.714839ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:34.295555Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638037794031844219,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-15T20:02:34.694243Z","caller":"traceutil/trace.go:171","msg":"trace[1762104821] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"908.431163ms","start":"2026-06-15T20:02:33.785747Z","end":"2026-06-15T20:02:34.694178Z","steps":["trace[1762104821] 'process raft request' (duration: 908.047273ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:34.694548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:33.785696Z","time spent":"908.699069ms","remote":"127.0.0.1:54532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:02:34.80745Z","caller":"traceutil/trace.go:171","msg":"trace[1980460094] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"1.012254882s","start":"2026-06-15T20:02:33.795147Z","end":"2026-06-15T20:02:34.807402Z","steps":["trace[1980460094] 'read index received' (duration: 899.090221ms)","trace[1980460094] 'applied index is now lower than readState.Index' (duration: 113.163111ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:02:34.807636Z","caller":"traceutil/trace.go:171","msg":"trace[22788710] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"975.703377ms","start":"2026-06-15T20:02:33.831868Z","end":"2026-06-15T20:02:34.807572Z","steps":["trace[22788710] 'process raft request' (duration: 961.635021ms)","trace[22788710] 'compare' (duration: 13.715886ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:02:34.807819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:33.831835Z","time spent":"975.898471ms","remote":"127.0.0.1:54794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-15T20:02:34.808072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012879259s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-15T20:02:34.808135Z","caller":"traceutil/trace.go:171","msg":"trace[1867438697] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:656; }","duration":"1.013028562s","start":"2026-06-15T20:02:33.79509Z","end":"2026-06-15T20:02:34.808118Z","steps":["trace[1867438697] 'agreement among raft nodes before linearized reading' (duration: 1.01252467s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:34.808103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.007558961s","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-15T20:02:34.80814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002967422s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.193\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-06-15T20:02:34.808186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:33.795076Z","time spent":"1.013100075s","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-15T20:02:34.808203Z","caller":"traceutil/trace.go:171","msg":"trace[1758227981] range","detail":"{range_begin:/registry/masterleases/199.19.213.193; range_end:; response_count:1; response_revision:656; }","duration":"1.003057954s","start":"2026-06-15T20:02:33.805128Z","end":"2026-06-15T20:02:34.808186Z","steps":["trace[1758227981] 'agreement among raft nodes before linearized reading' (duration: 1.002853479s)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:34.808207Z","caller":"traceutil/trace.go:171","msg":"trace[1939697816] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:656; }","duration":"1.007719945s","start":"2026-06-15T20:02:33.800458Z","end":"2026-06-15T20:02:34.808178Z","steps":["trace[1939697816] 'agreement among raft nodes before linearized reading' (duration: 1.007275464s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:34.808242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:33.805113Z","time spent":"1.003118735s","remote":"127.0.0.1:54406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.193\" "} {"level":"warn","ts":"2026-06-15T20:02:34.808233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.332343ms","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-15T20:02:34.808262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:33.800438Z","time spent":"1.007814557s","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-15T20:02:34.808299Z","caller":"traceutil/trace.go:171","msg":"trace[1594539074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:656; }","duration":"624.461787ms","start":"2026-06-15T20:02:34.18382Z","end":"2026-06-15T20:02:34.808282Z","steps":["trace[1594539074] 'agreement among raft nodes before linearized reading' (duration: 624.280732ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:34.808349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006893994s","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-15T20:02:34.808352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:34.183793Z","time spent":"624.548029ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-15T20:02:34.808387Z","caller":"traceutil/trace.go:171","msg":"trace[910740998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:656; }","duration":"1.006981346s","start":"2026-06-15T20:02:33.801393Z","end":"2026-06-15T20:02:34.808375Z","steps":["trace[910740998] 'agreement among raft nodes before linearized reading' (duration: 1.006806972s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:34.808412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:33.801367Z","time spent":"1.007039828s","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T20:02:36.188016Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.360136423s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-15T20:02:36.816663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999404133s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-15T20:02:36.816859Z","caller":"traceutil/trace.go:171","msg":"trace[2040903694] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.99964039s","start":"2026-06-15T20:02:34.817179Z","end":"2026-06-15T20:02:36.816819Z","steps":["trace[2040903694] 'agreement among raft nodes before linearized reading' (duration: 1.999422614s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:36.816947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:34.817167Z","time spent":"1.999769463s","remote":"127.0.0.1:54374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/15 20:02:36 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-15T20:02:37.455221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.266693654s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:02:37.455791Z","caller":"traceutil/trace.go:171","msg":"trace[49900705] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"649.500716ms","start":"2026-06-15T20:02:36.806263Z","end":"2026-06-15T20:02:37.455764Z","steps":["trace[49900705] 'process raft request' (duration: 649.383673ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:37.45589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:36.806232Z","time spent":"649.613159ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:02:37.456068Z","caller":"traceutil/trace.go:171","msg":"trace[17467431] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"2.642374249s","start":"2026-06-15T20:02:34.81368Z","end":"2026-06-15T20:02:37.456055Z","steps":["trace[17467431] 'process raft request' (duration: 1.37470374s)","trace[17467431] 'compare' (duration: 1.266264232s)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:02:37.45613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:34.813644Z","time spent":"2.642454991s","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:02:37.456219Z","caller":"traceutil/trace.go:171","msg":"trace[574279527] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"2.639058464s","start":"2026-06-15T20:02:34.81715Z","end":"2026-06-15T20:02:37.456208Z","steps":["trace[574279527] 'process raft request' (duration: 2.638391427s)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:37.456236Z","caller":"traceutil/trace.go:171","msg":"trace[635389351] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"2.64121227s","start":"2026-06-15T20:02:34.814994Z","end":"2026-06-15T20:02:37.456206Z","steps":["trace[635389351] 'process raft request' (duration: 2.640503392s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:37.456274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:34.817138Z","time spent":"2.639108495s","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:02:37.456362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:34.814972Z","time spent":"2.641326702s","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:02:37.45641Z","caller":"traceutil/trace.go:171","msg":"trace[677591279] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"2.627460863s","start":"2026-06-15T20:02:34.828937Z","end":"2026-06-15T20:02:37.456398Z","steps":["trace[677591279] 'process raft request' (duration: 2.626654542s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:37.456462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:34.828912Z","time spent":"2.627518565s","remote":"127.0.0.1:54406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:02:37.456498Z","caller":"traceutil/trace.go:171","msg":"trace[1655819217] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:684; }","duration":"2.639080594s","start":"2026-06-15T20:02:34.817207Z","end":"2026-06-15T20:02:37.456288Z","steps":["trace[1655819217] 'read index received' (duration: 10.721838ms)","trace[1655819217] 'applied index is now lower than readState.Index' (duration: 2.628349406s)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:02:37.456584Z","caller":"traceutil/trace.go:171","msg":"trace[217127680] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"2.641973679s","start":"2026-06-15T20:02:34.814581Z","end":"2026-06-15T20:02:37.456554Z","steps":["trace[217127680] 'process raft request' (duration: 2.640786638s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:37.456655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:34.814553Z","time spent":"2.642069372s","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:02:37.456772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.637825511s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:02:37.456794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.528086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:37.456828Z","caller":"traceutil/trace.go:171","msg":"trace[1978227689] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:662; }","duration":"2.637907103s","start":"2026-06-15T20:02:34.818898Z","end":"2026-06-15T20:02:37.456805Z","steps":["trace[1978227689] 'agreement among raft nodes before linearized reading' (duration: 2.63778777s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:37.456899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:34.81889Z","time spent":"2.637998605s","remote":"127.0.0.1:54578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-06-15T20:02:37.456902Z","caller":"traceutil/trace.go:171","msg":"trace[1807540081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:662; }","duration":"639.690021ms","start":"2026-06-15T20:02:36.817182Z","end":"2026-06-15T20:02:37.456872Z","steps":["trace[1807540081] 'agreement among raft nodes before linearized reading' (duration: 639.538807ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:37.456954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:36.817152Z","time spent":"639.793634ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-15T20:02:39.60748Z","caller":"traceutil/trace.go:171","msg":"trace[915062955] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"638.927841ms","start":"2026-06-15T20:02:38.968524Z","end":"2026-06-15T20:02:39.607451Z","steps":["trace[915062955] 'process raft request' (duration: 638.769177ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:39.607728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:38.968508Z","time spent":"639.121986ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-15T20:02:39.610252Z","caller":"traceutil/trace.go:171","msg":"trace[1640887483] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:710; }","duration":"149.476282ms","start":"2026-06-15T20:02:39.460756Z","end":"2026-06-15T20:02:39.610233Z","steps":["trace[1640887483] 'read index received' (duration: 146.533476ms)","trace[1640887483] 'applied index is now lower than readState.Index' (duration: 2.940846ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:02:39.610595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.861816ms","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-15T20:02:39.610641Z","caller":"traceutil/trace.go:171","msg":"trace[1541328127] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:682; }","duration":"148.927588ms","start":"2026-06-15T20:02:39.461702Z","end":"2026-06-15T20:02:39.610629Z","steps":["trace[1541328127] 'agreement among raft nodes before linearized reading' (duration: 148.767874ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:39.610731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.361469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-15T20:02:39.610595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.823431ms","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-15T20:02:39.610779Z","caller":"traceutil/trace.go:171","msg":"trace[804067053] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:682; }","duration":"149.426571ms","start":"2026-06-15T20:02:39.461341Z","end":"2026-06-15T20:02:39.610768Z","steps":["trace[804067053] 'agreement among raft nodes before linearized reading' (duration: 149.142984ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:39.610855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.884332ms","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-15T20:02:39.610936Z","caller":"traceutil/trace.go:171","msg":"trace[1447144253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:682; }","duration":"150.103828ms","start":"2026-06-15T20:02:39.460801Z","end":"2026-06-15T20:02:39.610905Z","steps":["trace[1447144253] 'agreement among raft nodes before linearized reading' (duration: 149.640437ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:39.611526Z","caller":"traceutil/trace.go:171","msg":"trace[1682715838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:682; }","duration":"150.082688ms","start":"2026-06-15T20:02:39.460719Z","end":"2026-06-15T20:02:39.610801Z","steps":["trace[1682715838] 'agreement among raft nodes before linearized reading' (duration: 149.720618ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:40.922587Z","caller":"traceutil/trace.go:171","msg":"trace[1361474017] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:743; }","duration":"107.885004ms","start":"2026-06-15T20:02:40.814655Z","end":"2026-06-15T20:02:40.92254Z","steps":["trace[1361474017] 'read index received' (duration: 81.83493ms)","trace[1361474017] 'applied index is now lower than readState.Index' (duration: 26.047964ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:02:40.922665Z","caller":"traceutil/trace.go:171","msg":"trace[1271111752] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"167.895569ms","start":"2026-06-15T20:02:40.75474Z","end":"2026-06-15T20:02:40.922636Z","steps":["trace[1271111752] 'process raft request' (duration: 141.737032ms)","trace[1271111752] 'compare' (duration: 25.755047ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:02:40.922904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.212322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:40.923018Z","caller":"traceutil/trace.go:171","msg":"trace[804325721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:714; }","duration":"108.369337ms","start":"2026-06-15T20:02:40.814596Z","end":"2026-06-15T20:02:40.922966Z","steps":["trace[804325721] 'agreement among raft nodes before linearized reading' (duration: 108.181582ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:40.92311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.020818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:40.923246Z","caller":"traceutil/trace.go:171","msg":"trace[1109363680] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:714; }","duration":"101.252394ms","start":"2026-06-15T20:02:40.821961Z","end":"2026-06-15T20:02:40.923213Z","steps":["trace[1109363680] 'agreement among raft nodes before linearized reading' (duration: 100.820182ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:55.73784Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638037794031844654,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-15T20:02:56.238899Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638037794031844654,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-15T20:02:56.308699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.493779857s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:56.308786Z","caller":"traceutil/trace.go:171","msg":"trace[1275881940] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:830; }","duration":"1.49393434s","start":"2026-06-15T20:02:54.814832Z","end":"2026-06-15T20:02:56.308766Z","steps":["trace[1275881940] 'range keys from in-memory index tree' (duration: 1.493692824s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:56.308821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:54.814798Z","time spent":"1.494015823s","remote":"127.0.0.1:54374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-15T20:02:56.313173Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.468999783s","expected-duration":"1s"} {"level":"info","ts":"2026-06-15T20:02:56.313495Z","caller":"traceutil/trace.go:171","msg":"trace[1596799601] linearizableReadLoop","detail":"{readStateIndex:866; appliedIndex:865; }","duration":"1.076737862s","start":"2026-06-15T20:02:55.236742Z","end":"2026-06-15T20:02:56.31348Z","steps":["trace[1596799601] 'read index received' (duration: 1.076524027s)","trace[1596799601] 'applied index is now lower than readState.Index' (duration: 213.175µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:02:56.313612Z","caller":"traceutil/trace.go:171","msg":"trace[374174619] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"1.46962325s","start":"2026-06-15T20:02:54.84397Z","end":"2026-06-15T20:02:56.313593Z","steps":["trace[374174619] 'process raft request' (duration: 1.469367583s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:56.313697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.076900947s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:02:56.313738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:54.84393Z","time spent":"1.469727073s","remote":"127.0.0.1:54578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:02:56.313757Z","caller":"traceutil/trace.go:171","msg":"trace[699970409] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:831; }","duration":"1.077045851s","start":"2026-06-15T20:02:55.236686Z","end":"2026-06-15T20:02:56.313732Z","steps":["trace[699970409] 'agreement among raft nodes before linearized reading' (duration: 1.076919257s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:56.314743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:55.236656Z","time spent":"1.078064787s","remote":"127.0.0.1:54498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:02:56.313865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.674673ms","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-15T20:02:56.314948Z","caller":"traceutil/trace.go:171","msg":"trace[1826717211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:831; }","duration":"547.839162ms","start":"2026-06-15T20:02:55.76709Z","end":"2026-06-15T20:02:56.314929Z","steps":["trace[1826717211] 'agreement among raft nodes before linearized reading' (duration: 546.563129ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:56.314988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:55.766975Z","time spent":"548.003746ms","remote":"127.0.0.1:54600","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-15T20:02:56.313914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.176649ms","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-15T20:02:56.315177Z","caller":"traceutil/trace.go:171","msg":"trace[763651993] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:831; }","duration":"547.483102ms","start":"2026-06-15T20:02:55.767684Z","end":"2026-06-15T20:02:56.315167Z","steps":["trace[763651993] 'agreement among raft nodes before linearized reading' (duration: 546.149518ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:56.315206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:55.767649Z","time spent":"547.548114ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-15T20:02:56.313985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.495338ms","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-15T20:02:56.315515Z","caller":"traceutil/trace.go:171","msg":"trace[985693520] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:831; }","duration":"548.023447ms","start":"2026-06-15T20:02:55.767451Z","end":"2026-06-15T20:02:56.315474Z","steps":["trace[985693520] 'agreement among raft nodes before linearized reading' (duration: 546.478268ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:56.315558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:55.767412Z","time spent":"548.13169ms","remote":"127.0.0.1:54600","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-15T20:02:56.31395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.939941ms","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-15T20:02:56.315794Z","caller":"traceutil/trace.go:171","msg":"trace[945609640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:831; }","duration":"545.82413ms","start":"2026-06-15T20:02:55.769961Z","end":"2026-06-15T20:02:56.315785Z","steps":["trace[945609640] 'agreement among raft nodes before linearized reading' (duration: 543.726595ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:56.31583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:55.769952Z","time spent":"545.867951ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T20:02:56.313993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.433112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-15T20:02:56.316012Z","caller":"traceutil/trace.go:171","msg":"trace[480926025] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:831; }","duration":"544.507466ms","start":"2026-06-15T20:02:55.771498Z","end":"2026-06-15T20:02:56.316006Z","steps":["trace[480926025] 'agreement among raft nodes before linearized reading' (duration: 542.31575ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:56.316036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:55.771447Z","time spent":"544.581747ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-15T20:03:01.346624Z","caller":"traceutil/trace.go:171","msg":"trace[1888265553] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:921; }","duration":"160.009255ms","start":"2026-06-15T20:03:01.186581Z","end":"2026-06-15T20:03:01.34659Z","steps":["trace[1888265553] 'read index received' (duration: 159.79951ms)","trace[1888265553] 'applied index is now lower than readState.Index' (duration: 208.685µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:03:01.346691Z","caller":"traceutil/trace.go:171","msg":"trace[1520095481] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"238.324154ms","start":"2026-06-15T20:03:01.1083Z","end":"2026-06-15T20:03:01.346624Z","steps":["trace[1520095481] 'process raft request' (duration: 238.067457ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:01.346952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.333743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-oidc-hmac\" ","response":"range_response_count:1 size:452"} {"level":"info","ts":"2026-06-15T20:03:01.346988Z","caller":"traceutil/trace.go:171","msg":"trace[694900791] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-oidc-hmac; range_end:; response_count:1; response_revision:886; }","duration":"160.444786ms","start":"2026-06-15T20:03:01.186531Z","end":"2026-06-15T20:03:01.346976Z","steps":["trace[694900791] 'agreement among raft nodes before linearized reading' (duration: 160.22828ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:01.563806Z","caller":"traceutil/trace.go:171","msg":"trace[836922083] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"200.242527ms","start":"2026-06-15T20:03:01.363526Z","end":"2026-06-15T20:03:01.563769Z","steps":["trace[836922083] 'process raft request' (duration: 198.784929ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:01.564602Z","caller":"traceutil/trace.go:171","msg":"trace[13524803] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:923; }","duration":"196.752966ms","start":"2026-06-15T20:03:01.367836Z","end":"2026-06-15T20:03:01.564589Z","steps":["trace[13524803] 'read index received' (duration: 194.458687ms)","trace[13524803] 'applied index is now lower than readState.Index' (duration: 2.293059ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:03:01.564661Z","caller":"traceutil/trace.go:171","msg":"trace[453473265] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"199.961941ms","start":"2026-06-15T20:03:01.364646Z","end":"2026-06-15T20:03:01.564608Z","steps":["trace[453473265] 'process raft request' (duration: 199.875118ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:01.564825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.962431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/\" range_end:\"/registry/serviceaccounts/envoy-gateway-system0\" limit:10000 ","response":"range_response_count:3 size:1862"} {"level":"info","ts":"2026-06-15T20:03:01.564863Z","caller":"traceutil/trace.go:171","msg":"trace[224100231] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/; range_end:/registry/serviceaccounts/envoy-gateway-system0; response_count:3; response_revision:889; }","duration":"197.057484ms","start":"2026-06-15T20:03:01.367792Z","end":"2026-06-15T20:03:01.56485Z","steps":["trace[224100231] 'agreement among raft nodes before linearized reading' (duration: 196.848189ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:21.791214Z","caller":"traceutil/trace.go:171","msg":"trace[781679858] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"101.404196ms","start":"2026-06-15T20:03:21.689784Z","end":"2026-06-15T20:03:21.791188Z","steps":["trace[781679858] 'process raft request' (duration: 101.243132ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:33.316346Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638037794031845112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-15T20:03:33.817447Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638037794031845112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-15T20:03:34.318164Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638037794031845112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-15T20:03:34.610478Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.923513343s","expected-duration":"1s"} {"level":"info","ts":"2026-06-15T20:03:34.611088Z","caller":"traceutil/trace.go:171","msg":"trace[1310367116] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"1.924190072s","start":"2026-06-15T20:03:32.686854Z","end":"2026-06-15T20:03:34.611044Z","steps":["trace[1310367116] 'process raft request' (duration: 1.923899494s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:34.611809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:32.68684Z","time spent":"1.924598862s","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:03:34.815694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000374377s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-15T20:03:34.815835Z","caller":"traceutil/trace.go:171","msg":"trace[599973091] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000586963s","start":"2026-06-15T20:03:32.815214Z","end":"2026-06-15T20:03:34.815801Z","steps":["trace[599973091] 'agreement among raft nodes before linearized reading' (duration: 2.000415059s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:34.816091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:32.815183Z","time spent":"2.000704456s","remote":"127.0.0.1:54374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/15 20:03:34 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-15T20:03:35.432747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.863625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:03:35.433048Z","caller":"traceutil/trace.go:171","msg":"trace[827145492] linearizableReadLoop","detail":"{readStateIndex:1101; appliedIndex:1095; }","duration":"2.617750722s","start":"2026-06-15T20:03:32.815277Z","end":"2026-06-15T20:03:35.433027Z","steps":["trace[827145492] 'read index received' (duration: 1.795730666s)","trace[827145492] 'applied index is now lower than readState.Index' (duration: 822.018886ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:03:35.433131Z","caller":"traceutil/trace.go:171","msg":"trace[1437434206] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"2.74591621s","start":"2026-06-15T20:03:32.687202Z","end":"2026-06-15T20:03:35.433118Z","steps":["trace[1437434206] 'process raft request' (duration: 1.987574892s)","trace[1437434206] 'compare' (duration: 757.682091ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:03:35.433231Z","caller":"traceutil/trace.go:171","msg":"trace[274683421] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"2.743540548s","start":"2026-06-15T20:03:32.689668Z","end":"2026-06-15T20:03:35.433208Z","steps":["trace[274683421] 'process raft request' (duration: 2.743281321s)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:35.433216Z","caller":"traceutil/trace.go:171","msg":"trace[1320850249] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"2.743705452s","start":"2026-06-15T20:03:32.689468Z","end":"2026-06-15T20:03:35.433174Z","steps":["trace[1320850249] 'process raft request' (duration: 2.743447396s)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:35.433276Z","caller":"traceutil/trace.go:171","msg":"trace[1071253210] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"1.527227671s","start":"2026-06-15T20:03:33.906031Z","end":"2026-06-15T20:03:35.433259Z","steps":["trace[1071253210] 'process raft request' (duration: 1.526955764s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:35.433312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:32.689662Z","time spent":"2.7436053s","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:03:35.43335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:32.687195Z","time spent":"2.745962341s","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:03:35.433352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:33.906006Z","time spent":"1.527307234s","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:03:35.433412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:32.689458Z","time spent":"2.743842735s","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:03:35.433471Z","caller":"traceutil/trace.go:171","msg":"trace[1754035610] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"2.74554082s","start":"2026-06-15T20:03:32.687919Z","end":"2026-06-15T20:03:35.433459Z","steps":["trace[1754035610] 'process raft request' (duration: 2.744948304s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:35.433533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:32.687911Z","time spent":"2.745587311s","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:03:35.767169Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.089280588s","expected-duration":"1s"} {"level":"info","ts":"2026-06-15T20:03:35.768329Z","caller":"traceutil/trace.go:171","msg":"trace[1483205466] linearizableReadLoop","detail":"{readStateIndex:1102; appliedIndex:1101; }","duration":"335.221122ms","start":"2026-06-15T20:03:35.433078Z","end":"2026-06-15T20:03:35.768299Z","steps":["trace[1483205466] 'read index received' (duration: 334.259647ms)","trace[1483205466] 'applied index is now lower than readState.Index' (duration: 960.215µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:03:35.768451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.51807837s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:03:35.768482Z","caller":"traceutil/trace.go:171","msg":"trace[985134015] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1058; }","duration":"2.518175823s","start":"2026-06-15T20:03:33.250297Z","end":"2026-06-15T20:03:35.768473Z","steps":["trace[985134015] 'agreement among raft nodes before linearized reading' (duration: 2.51807883s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:35.768514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:33.250266Z","time spent":"2.518238925s","remote":"127.0.0.1:54752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:03:35.768726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.270639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:03:35.768854Z","caller":"traceutil/trace.go:171","msg":"trace[1284031768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1058; }","duration":"952.436463ms","start":"2026-06-15T20:03:34.816371Z","end":"2026-06-15T20:03:35.768808Z","steps":["trace[1284031768] 'agreement among raft nodes before linearized reading' (duration: 952.272659ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:35.768905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:34.816345Z","time spent":"952.551646ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-15T20:03:35.768772Z","caller":"traceutil/trace.go:171","msg":"trace[565009603] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"1.090995644s","start":"2026-06-15T20:03:34.677766Z","end":"2026-06-15T20:03:35.768761Z","steps":["trace[565009603] 'process raft request' (duration: 1.089639328s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:35.768968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.960423641s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.193\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-06-15T20:03:35.769063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:34.677735Z","time spent":"1.09124902s","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:03:35.769072Z","caller":"traceutil/trace.go:171","msg":"trace[92573367] range","detail":"{range_begin:/registry/masterleases/199.19.213.193; range_end:; response_count:1; response_revision:1058; }","duration":"1.960596816s","start":"2026-06-15T20:03:33.808452Z","end":"2026-06-15T20:03:35.769049Z","steps":["trace[92573367] 'agreement among raft nodes before linearized reading' (duration: 1.960266978s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:35.769139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:33.808423Z","time spent":"1.960704018s","remote":"127.0.0.1:54406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.193\" "} {"level":"info","ts":"2026-06-15T20:04:02.937584Z","caller":"traceutil/trace.go:171","msg":"trace[1948910582] linearizableReadLoop","detail":"{readStateIndex:1196; appliedIndex:1195; }","duration":"115.349359ms","start":"2026-06-15T20:04:02.822199Z","end":"2026-06-15T20:04:02.937548Z","steps":["trace[1948910582] 'read index received' (duration: 115.089523ms)","trace[1948910582] 'applied index is now lower than readState.Index' (duration: 258.426µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:04:02.937786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.534064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:04:02.937834Z","caller":"traceutil/trace.go:171","msg":"trace[2140457974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1146; }","duration":"115.641426ms","start":"2026-06-15T20:04:02.822178Z","end":"2026-06-15T20:04:02.937819Z","steps":["trace[2140457974] 'agreement among raft nodes before linearized reading' (duration: 115.517523ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:02.937892Z","caller":"traceutil/trace.go:171","msg":"trace[1784448502] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"116.52889ms","start":"2026-06-15T20:04:02.821325Z","end":"2026-06-15T20:04:02.937854Z","steps":["trace[1784448502] 'process raft request' (duration: 116.005737ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:04.169663Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638037794031845352,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-15T20:04:04.207995Z","caller":"traceutil/trace.go:171","msg":"trace[1000463429] linearizableReadLoop","detail":"{readStateIndex:1197; appliedIndex:1196; }","duration":"539.057612ms","start":"2026-06-15T20:04:03.668912Z","end":"2026-06-15T20:04:04.20797Z","steps":["trace[1000463429] 'read index received' (duration: 513.177524ms)","trace[1000463429] 'applied index is now lower than readState.Index' (duration: 25.879158ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:04:04.208034Z","caller":"traceutil/trace.go:171","msg":"trace[1295991206] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"584.162383ms","start":"2026-06-15T20:04:03.623827Z","end":"2026-06-15T20:04:04.207989Z","steps":["trace[1295991206] 'process raft request' (duration: 558.397699ms)","trace[1295991206] 'compare' (duration: 25.252561ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:04:04.208287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.342161ms","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-15T20:04:04.208401Z","caller":"traceutil/trace.go:171","msg":"trace[325081241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1147; }","duration":"539.526845ms","start":"2026-06-15T20:04:03.668847Z","end":"2026-06-15T20:04:04.208374Z","steps":["trace[325081241] 'agreement among raft nodes before linearized reading' (duration: 539.222388ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:04.208451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:03.668811Z","time spent":"539.630178ms","remote":"127.0.0.1:54600","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-15T20:04:04.208424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.226375ms","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-15T20:04:04.208504Z","caller":"traceutil/trace.go:171","msg":"trace[62186660] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1147; }","duration":"529.341758ms","start":"2026-06-15T20:04:03.67915Z","end":"2026-06-15T20:04:04.208492Z","steps":["trace[62186660] 'agreement among raft nodes before linearized reading' (duration: 528.975889ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:04.208419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:03.623792Z","time spent":"584.439722ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:04:04.208587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:03.679136Z","time spent":"529.438711ms","remote":"127.0.0.1:54600","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-15T20:04:04.208806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.718598ms","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-15T20:04:04.208836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.853941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-15T20:04:04.208887Z","caller":"traceutil/trace.go:171","msg":"trace[1865591487] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1147; }","duration":"524.933143ms","start":"2026-06-15T20:04:03.683943Z","end":"2026-06-15T20:04:04.208876Z","steps":["trace[1865591487] 'agreement among raft nodes before linearized reading' (duration: 524.81364ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:04.208922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:03.683922Z","time spent":"524.992425ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-15T20:04:04.208867Z","caller":"traceutil/trace.go:171","msg":"trace[392694891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1147; }","duration":"524.838091ms","start":"2026-06-15T20:04:03.684017Z","end":"2026-06-15T20:04:04.208855Z","steps":["trace[392694891] 'agreement among raft nodes before linearized reading' (duration: 524.716798ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:04.209018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:03.683987Z","time spent":"525.017976ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T20:04:04.208787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.617954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.193\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-15T20:04:04.209159Z","caller":"traceutil/trace.go:171","msg":"trace[511529902] range","detail":"{range_begin:/registry/masterleases/199.19.213.193; range_end:; response_count:1; response_revision:1147; }","duration":"396.062546ms","start":"2026-06-15T20:04:03.813084Z","end":"2026-06-15T20:04:04.209147Z","steps":["trace[511529902] 'agreement among raft nodes before linearized reading' (duration: 395.491442ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:04.2092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:03.813049Z","time spent":"396.14074ms","remote":"127.0.0.1:54406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.193\" "} {"level":"warn","ts":"2026-06-15T20:04:05.039442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.930751ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-06-15T20:04:05.03953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:04.210345Z","time spent":"829.181354ms","remote":"127.0.0.1:54406","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-15T20:04:05.140831Z","caller":"traceutil/trace.go:171","msg":"trace[1630879852] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"927.806867ms","start":"2026-06-15T20:04:04.212975Z","end":"2026-06-15T20:04:05.140782Z","steps":["trace[1630879852] 'process raft request' (duration: 927.236463ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:05.141219Z","caller":"traceutil/trace.go:171","msg":"trace[581308258] linearizableReadLoop","detail":"{readStateIndex:1202; appliedIndex:1198; }","duration":"327.299235ms","start":"2026-06-15T20:04:04.813904Z","end":"2026-06-15T20:04:05.141204Z","steps":["trace[581308258] 'read index received' (duration: 326.328689ms)","trace[581308258] 'applied index is now lower than readState.Index' (duration: 969.656µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:04:05.141229Z","caller":"traceutil/trace.go:171","msg":"trace[713403807] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"927.873809ms","start":"2026-06-15T20:04:04.213325Z","end":"2026-06-15T20:04:05.141199Z","steps":["trace[713403807] 'process raft request' (duration: 927.399916ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:05.141215Z","caller":"traceutil/trace.go:171","msg":"trace[16380993] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"926.855853ms","start":"2026-06-15T20:04:04.214317Z","end":"2026-06-15T20:04:05.141173Z","steps":["trace[16380993] 'process raft request' (duration: 926.648017ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:05.141385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:04.21331Z","time spent":"927.992443ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:04:05.141454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:04.214301Z","time spent":"927.026746ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:04:05.141578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.658514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:04:05.141631Z","caller":"traceutil/trace.go:171","msg":"trace[683772315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1151; }","duration":"327.763957ms","start":"2026-06-15T20:04:04.813856Z","end":"2026-06-15T20:04:05.14162Z","steps":["trace[683772315] 'agreement among raft nodes before linearized reading' (duration: 327.636684ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:05.141662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:04.813838Z","time spent":"327.814298ms","remote":"127.0.0.1:54374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-15T20:04:05.141473Z","caller":"traceutil/trace.go:171","msg":"trace[1145547675] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"927.116239ms","start":"2026-06-15T20:04:04.214345Z","end":"2026-06-15T20:04:05.141461Z","steps":["trace[1145547675] 'process raft request' (duration: 926.77217ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:05.141788Z","caller":"traceutil/trace.go:171","msg":"trace[1085283184] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"100.672517ms","start":"2026-06-15T20:04:05.041106Z","end":"2026-06-15T20:04:05.141779Z","steps":["trace[1085283184] 'process raft request' (duration: 100.576725ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:05.141806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:04.214314Z","time spent":"927.453008ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:04:05.142199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:04.212938Z","time spent":"928.015273ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:04:05.143585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.274671ms","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-15T20:04:05.143709Z","caller":"traceutil/trace.go:171","msg":"trace[233923415] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1152; }","duration":"200.03899ms","start":"2026-06-15T20:04:04.943646Z","end":"2026-06-15T20:04:05.143685Z","steps":["trace[233923415] 'agreement among raft nodes before linearized reading' (duration: 198.645544ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:06.52334Z","caller":"traceutil/trace.go:171","msg":"trace[1734690523] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"602.879195ms","start":"2026-06-15T20:04:05.920429Z","end":"2026-06-15T20:04:06.523308Z","steps":["trace[1734690523] 'process raft request' (duration: 602.69351ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:06.52353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:05.920412Z","time spent":"603.016718ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:04:07.163528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.275583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:04:07.163672Z","caller":"traceutil/trace.go:171","msg":"trace[1200763573] linearizableReadLoop","detail":"{readStateIndex:1206; appliedIndex:1205; }","duration":"349.673161ms","start":"2026-06-15T20:04:06.813974Z","end":"2026-06-15T20:04:07.163647Z","steps":["trace[1200763573] 'read index received' (duration: 243.089788ms)","trace[1200763573] 'applied index is now lower than readState.Index' (duration: 106.581393ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:04:07.164074Z","caller":"traceutil/trace.go:171","msg":"trace[151722304] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"946.918778ms","start":"2026-06-15T20:04:06.217139Z","end":"2026-06-15T20:04:07.164058Z","steps":["trace[151722304] 'process raft request' (duration: 839.982717ms)","trace[151722304] 'compare' (duration: 106.084698ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:04:07.164166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:06.217103Z","time spent":"947.020081ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:04:07.164254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.297277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:04:07.164388Z","caller":"traceutil/trace.go:171","msg":"trace[655727801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1155; }","duration":"350.498033ms","start":"2026-06-15T20:04:06.813858Z","end":"2026-06-15T20:04:07.164356Z","steps":["trace[655727801] 'agreement among raft nodes before linearized reading' (duration: 350.270217ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:07.164456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:06.813824Z","time spent":"350.609606ms","remote":"127.0.0.1:54374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-15T20:04:07.495566Z","caller":"traceutil/trace.go:171","msg":"trace[747483864] linearizableReadLoop","detail":"{readStateIndex:1210; appliedIndex:1206; }","duration":"282.669226ms","start":"2026-06-15T20:04:07.212867Z","end":"2026-06-15T20:04:07.495536Z","steps":["trace[747483864] 'read index received' (duration: 4.518979ms)","trace[747483864] 'applied index is now lower than readState.Index' (duration: 278.149217ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:04:07.49569Z","caller":"traceutil/trace.go:171","msg":"trace[33564576] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"325.483217ms","start":"2026-06-15T20:04:07.170173Z","end":"2026-06-15T20:04:07.495657Z","steps":["trace[33564576] 'process raft request' (duration: 325.053946ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:07.495799Z","caller":"traceutil/trace.go:171","msg":"trace[21379974] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"324.0595ms","start":"2026-06-15T20:04:07.171687Z","end":"2026-06-15T20:04:07.495746Z","steps":["trace[21379974] 'process raft request' (duration: 323.814644ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:07.49586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:07.170158Z","time spent":"325.61877ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:04:07.496052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:07.171672Z","time spent":"324.252925ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:04:07.496137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.20802ms","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-15T20:04:07.496195Z","caller":"traceutil/trace.go:171","msg":"trace[1314814915] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1159; }","duration":"283.374154ms","start":"2026-06-15T20:04:07.21281Z","end":"2026-06-15T20:04:07.496184Z","steps":["trace[1314814915] 'agreement among raft nodes before linearized reading' (duration: 282.910442ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:07.495672Z","caller":"traceutil/trace.go:171","msg":"trace[1116152113] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"324.618205ms","start":"2026-06-15T20:04:07.171013Z","end":"2026-06-15T20:04:07.495631Z","steps":["trace[1116152113] 'process raft request' (duration: 324.44687ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:07.496517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:07.171004Z","time spent":"325.455336ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:04:09.50694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.613947ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-15T20:04:09.507253Z","caller":"traceutil/trace.go:171","msg":"trace[1207357203] linearizableReadLoop","detail":"{readStateIndex:1213; appliedIndex:1211; }","duration":"280.639673ms","start":"2026-06-15T20:04:09.226595Z","end":"2026-06-15T20:04:09.507235Z","steps":["trace[1207357203] 'read index received' (duration: 69.752758ms)","trace[1207357203] 'applied index is now lower than readState.Index' (duration: 210.886075ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:04:09.507544Z","caller":"traceutil/trace.go:171","msg":"trace[1983095082] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"325.106287ms","start":"2026-06-15T20:04:09.182424Z","end":"2026-06-15T20:04:09.50753Z","steps":["trace[1983095082] 'process raft request' (duration: 324.619355ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:09.507645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:09.182196Z","time spent":"325.377984ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:04:09.507888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.284639ms","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-15T20:04:09.507934Z","caller":"traceutil/trace.go:171","msg":"trace[510341889] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1161; }","duration":"281.381131ms","start":"2026-06-15T20:04:09.226542Z","end":"2026-06-15T20:04:09.507923Z","steps":["trace[510341889] 'agreement among raft nodes before linearized reading' (duration: 281.224057ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:09.960428Z","caller":"traceutil/trace.go:171","msg":"trace[1227632281] linearizableReadLoop","detail":"{readStateIndex:1214; appliedIndex:1213; }","duration":"453.103711ms","start":"2026-06-15T20:04:09.507292Z","end":"2026-06-15T20:04:09.960395Z","steps":["trace[1227632281] 'read index received' (duration: 452.908256ms)","trace[1227632281] 'applied index is now lower than readState.Index' (duration: 193.775µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:04:09.960583Z","caller":"traceutil/trace.go:171","msg":"trace[1186703072] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"487.292697ms","start":"2026-06-15T20:04:09.473228Z","end":"2026-06-15T20:04:09.960521Z","steps":["trace[1186703072] 'process raft request' (duration: 486.907307ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:09.960782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.922119ms","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-15T20:04:09.960831Z","caller":"traceutil/trace.go:171","msg":"trace[373338804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1162; }","duration":"460.016211ms","start":"2026-06-15T20:04:09.500801Z","end":"2026-06-15T20:04:09.960817Z","steps":["trace[373338804] 'agreement among raft nodes before linearized reading' (duration: 459.750654ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:09.960856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:09.473196Z","time spent":"487.516803ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:04:09.960883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:09.500791Z","time spent":"460.080794ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-15T20:04:09.960878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.924739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-15T20:04:09.961061Z","caller":"traceutil/trace.go:171","msg":"trace[681217340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1162; }","duration":"460.121964ms","start":"2026-06-15T20:04:09.500887Z","end":"2026-06-15T20:04:09.961009Z","steps":["trace[681217340] 'agreement among raft nodes before linearized reading' (duration: 459.645362ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:09.961176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:09.500883Z","time spent":"460.276278ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T20:04:09.961273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.500784ms","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-15T20:04:09.96133Z","caller":"traceutil/trace.go:171","msg":"trace[1608308522] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1162; }","duration":"460.602877ms","start":"2026-06-15T20:04:09.500712Z","end":"2026-06-15T20:04:09.961315Z","steps":["trace[1608308522] 'agreement among raft nodes before linearized reading' (duration: 459.910079ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:09.961375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:09.500686Z","time spent":"460.681249ms","remote":"127.0.0.1:54600","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-15T20:04:09.970229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.478803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-15T20:04:09.970411Z","caller":"traceutil/trace.go:171","msg":"trace[1376640034] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1163; }","duration":"447.724229ms","start":"2026-06-15T20:04:09.522663Z","end":"2026-06-15T20:04:09.970388Z","steps":["trace[1376640034] 'agreement among raft nodes before linearized reading' (duration: 447.34354ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:09.970482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:09.52264Z","time spent":"447.831782ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-15T20:04:09.970727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.635617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T20:04:09.970699Z","caller":"traceutil/trace.go:171","msg":"trace[1621229584] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"457.777153ms","start":"2026-06-15T20:04:09.512885Z","end":"2026-06-15T20:04:09.970662Z","steps":["trace[1621229584] 'process raft request' (duration: 456.929191ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:09.970771Z","caller":"traceutil/trace.go:171","msg":"trace[20393307] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1163; }","duration":"413.73738ms","start":"2026-06-15T20:04:09.557024Z","end":"2026-06-15T20:04:09.970762Z","steps":["trace[20393307] 'agreement among raft nodes before linearized reading' (duration: 413.607437ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:09.970795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:09.557Z","time spent":"413.788261ms","remote":"127.0.0.1:54668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:04:09.97084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:09.512846Z","time spent":"457.928657ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:04:10.087631Z","caller":"traceutil/trace.go:171","msg":"trace[1931007686] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"112.564325ms","start":"2026-06-15T20:04:09.975028Z","end":"2026-06-15T20:04:10.087592Z","steps":["trace[1931007686] 'process raft request' (duration: 55.628585ms)","trace[1931007686] 'compare' (duration: 56.780226ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:04:28.314764Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638037794031845630,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-15T20:04:28.815268Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638037794031845630,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-15T20:04:28.941219Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.864907473s","expected-duration":"1s"} {"level":"info","ts":"2026-06-15T20:04:28.942062Z","caller":"traceutil/trace.go:171","msg":"trace[419443486] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"1.865832687s","start":"2026-06-15T20:04:27.076191Z","end":"2026-06-15T20:04:28.942024Z","steps":["trace[419443486] 'process raft request' (duration: 1.86553215s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:28.942353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:27.076169Z","time spent":"1.866046622s","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:04:28.943221Z","caller":"traceutil/trace.go:171","msg":"trace[1837239408] linearizableReadLoop","detail":"{readStateIndex:1330; appliedIndex:1328; }","duration":"1.128717359s","start":"2026-06-15T20:04:27.814476Z","end":"2026-06-15T20:04:28.943193Z","steps":["trace[1837239408] 'read index received' (duration: 1.127460547s)","trace[1837239408] 'applied index is now lower than readState.Index' (duration: 1.255462ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:04:28.943554Z","caller":"traceutil/trace.go:171","msg":"trace[1881579996] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"1.348203036s","start":"2026-06-15T20:04:27.595308Z","end":"2026-06-15T20:04:28.943511Z","steps":["trace[1881579996] 'process raft request' (duration: 1.347758945s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:28.943701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:27.59529Z","time spent":"1.348316068s","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:04:28.943931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129463027s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:04:28.943976Z","caller":"traceutil/trace.go:171","msg":"trace[637622246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1273; }","duration":"1.12955888s","start":"2026-06-15T20:04:27.814407Z","end":"2026-06-15T20:04:28.943966Z","steps":["trace[637622246] 'agreement among raft nodes before linearized reading' (duration: 1.129489208s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:28.944015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:27.814382Z","time spent":"1.129626202s","remote":"127.0.0.1:54374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-15T20:04:28.944282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.851974ms","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-15T20:04:28.944322Z","caller":"traceutil/trace.go:171","msg":"trace[1872388241] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1273; }","duration":"797.919246ms","start":"2026-06-15T20:04:28.146393Z","end":"2026-06-15T20:04:28.944312Z","steps":["trace[1872388241] 'agreement among raft nodes before linearized reading' (duration: 797.774142ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:28.944351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:28.146378Z","time spent":"797.966637ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-15T20:04:28.944579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.21572ms","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-15T20:04:28.944622Z","caller":"traceutil/trace.go:171","msg":"trace[465325126] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1273; }","duration":"327.292462ms","start":"2026-06-15T20:04:28.617317Z","end":"2026-06-15T20:04:28.944609Z","steps":["trace[465325126] 'agreement among raft nodes before linearized reading' (duration: 327.23407ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:28.944655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:28.6173Z","time spent":"327.343783ms","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:04:28.944866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.243614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-15T20:04:28.944907Z","caller":"traceutil/trace.go:171","msg":"trace[1112124199] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1273; }","duration":"654.317486ms","start":"2026-06-15T20:04:28.290578Z","end":"2026-06-15T20:04:28.944896Z","steps":["trace[1112124199] 'agreement among raft nodes before linearized reading' (duration: 654.221684ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:28.944934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:28.290558Z","time spent":"654.370007ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-15T20:04:28.945125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.878136ms","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-15T20:04:28.945154Z","caller":"traceutil/trace.go:171","msg":"trace[1781442690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1273; }","duration":"767.923987ms","start":"2026-06-15T20:04:28.177221Z","end":"2026-06-15T20:04:28.945145Z","steps":["trace[1781442690] 'agreement among raft nodes before linearized reading' (duration: 767.824085ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:28.945181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:28.177212Z","time spent":"767.962258ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-15T20:04:28.945335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.77898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-15T20:04:28.945397Z","caller":"traceutil/trace.go:171","msg":"trace[412392403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1273; }","duration":"770.875012ms","start":"2026-06-15T20:04:28.174505Z","end":"2026-06-15T20:04:28.94538Z","steps":["trace[412392403] 'agreement among raft nodes before linearized reading' (duration: 770.658707ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:28.945439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:28.174491Z","time spent":"770.940515ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T20:04:28.945706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.884388ms","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-15T20:04:28.945751Z","caller":"traceutil/trace.go:171","msg":"trace[1803692750] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1273; }","duration":"794.946089ms","start":"2026-06-15T20:04:28.150794Z","end":"2026-06-15T20:04:28.94574Z","steps":["trace[1803692750] 'agreement among raft nodes before linearized reading' (duration: 794.828136ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:28.945792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:28.150787Z","time spent":"794.998302ms","remote":"127.0.0.1:54600","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-15T20:04:43.38009Z","caller":"traceutil/trace.go:171","msg":"trace[1148169002] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1378; }","duration":"328.38901ms","start":"2026-06-15T20:04:43.051658Z","end":"2026-06-15T20:04:43.380047Z","steps":["trace[1148169002] 'read index received' (duration: 328.115582ms)","trace[1148169002] 'applied index is now lower than readState.Index' (duration: 272.308µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:04:43.380593Z","caller":"traceutil/trace.go:171","msg":"trace[463738815] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"344.221166ms","start":"2026-06-15T20:04:43.036355Z","end":"2026-06-15T20:04:43.380577Z","steps":["trace[463738815] 'process raft request' (duration: 343.515818ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:43.38082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:43.03632Z","time spent":"344.377999ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:04:43.381207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.557209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-15T20:04:43.381253Z","caller":"traceutil/trace.go:171","msg":"trace[1802546227] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1319; }","duration":"329.60224ms","start":"2026-06-15T20:04:43.051636Z","end":"2026-06-15T20:04:43.381238Z","steps":["trace[1802546227] 'agreement among raft nodes before linearized reading' (duration: 329.431566ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:43.381296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:43.051557Z","time spent":"329.726314ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-15T20:04:43.381545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.487562ms","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-15T20:04:43.381582Z","caller":"traceutil/trace.go:171","msg":"trace[320836960] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1319; }","duration":"296.552083ms","start":"2026-06-15T20:04:43.085018Z","end":"2026-06-15T20:04:43.38157Z","steps":["trace[320836960] 'agreement among raft nodes before linearized reading' (duration: 296.43751ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:43.381898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.078695ms","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-15T20:04:43.381949Z","caller":"traceutil/trace.go:171","msg":"trace[1290726709] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1319; }","duration":"295.177577ms","start":"2026-06-15T20:04:43.08676Z","end":"2026-06-15T20:04:43.381938Z","steps":["trace[1290726709] 'agreement among raft nodes before linearized reading' (duration: 295.051474ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:43.446669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.130098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-15T20:04:43.446869Z","caller":"traceutil/trace.go:171","msg":"trace[1447386787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1319; }","duration":"361.354025ms","start":"2026-06-15T20:04:43.085475Z","end":"2026-06-15T20:04:43.446829Z","steps":["trace[1447386787] 'agreement among raft nodes before linearized reading' (duration: 296.726478ms)","trace[1447386787] 'range keys from in-memory index tree' (duration: 64.256417ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:04:43.446726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.009855ms","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-15T20:04:43.447911Z","caller":"traceutil/trace.go:171","msg":"trace[554785717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1319; }","duration":"362.260178ms","start":"2026-06-15T20:04:43.085625Z","end":"2026-06-15T20:04:43.447885Z","steps":["trace[554785717] 'agreement among raft nodes before linearized reading' (duration: 296.595524ms)","trace[554785717] 'range keys from in-memory index tree' (duration: 64.378511ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:04:43.447986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:43.085582Z","time spent":"362.382571ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-15T20:04:43.447805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:43.085469Z","time spent":"361.519429ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-15T20:04:43.495575Z","caller":"traceutil/trace.go:171","msg":"trace[363938111] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"106.176412ms","start":"2026-06-15T20:04:43.389375Z","end":"2026-06-15T20:04:43.495552Z","steps":["trace[363938111] 'process raft request' (duration: 106.135521ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:43.495741Z","caller":"traceutil/trace.go:171","msg":"trace[1416908412] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"106.45727ms","start":"2026-06-15T20:04:43.389231Z","end":"2026-06-15T20:04:43.495688Z","steps":["trace[1416908412] 'process raft request' (duration: 106.159932ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:47.642455Z","caller":"traceutil/trace.go:171","msg":"trace[422716501] linearizableReadLoop","detail":"{readStateIndex:1401; appliedIndex:1400; }","duration":"180.756194ms","start":"2026-06-15T20:04:47.461673Z","end":"2026-06-15T20:04:47.642429Z","steps":["trace[422716501] 'read index received' (duration: 180.645801ms)","trace[422716501] 'applied index is now lower than readState.Index' (duration: 109.083µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:04:47.642515Z","caller":"traceutil/trace.go:171","msg":"trace[1358886790] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"246.593242ms","start":"2026-06-15T20:04:47.395883Z","end":"2026-06-15T20:04:47.642476Z","steps":["trace[1358886790] 'process raft request' (duration: 246.374226ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:47.642883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.017728ms","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-15T20:04:47.642931Z","caller":"traceutil/trace.go:171","msg":"trace[1149990766] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1340; }","duration":"122.11214ms","start":"2026-06-15T20:04:47.520808Z","end":"2026-06-15T20:04:47.64292Z","steps":["trace[1149990766] 'agreement among raft nodes before linearized reading' (duration: 121.859114ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:47.643183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.958236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-15T20:04:47.643191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.501973ms","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-15T20:04:47.643179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.946627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-15T20:04:47.643352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.096226ms","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-15T20:04:47.643409Z","caller":"traceutil/trace.go:171","msg":"trace[453908493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1340; }","duration":"123.194519ms","start":"2026-06-15T20:04:47.520199Z","end":"2026-06-15T20:04:47.643393Z","steps":["trace[453908493] 'agreement among raft nodes before linearized reading' (duration: 123.063686ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:47.643211Z","caller":"traceutil/trace.go:171","msg":"trace[206174343] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1340; }","duration":"122.081269ms","start":"2026-06-15T20:04:47.521123Z","end":"2026-06-15T20:04:47.643204Z","steps":["trace[206174343] 'agreement among raft nodes before linearized reading' (duration: 121.968846ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:47.643464Z","caller":"traceutil/trace.go:171","msg":"trace[164496931] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1340; }","duration":"129.334096ms","start":"2026-06-15T20:04:47.514098Z","end":"2026-06-15T20:04:47.643432Z","steps":["trace[164496931] 'agreement among raft nodes before linearized reading' (duration: 128.69195ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:47.643254Z","caller":"traceutil/trace.go:171","msg":"trace[673207768] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1340; }","duration":"181.635606ms","start":"2026-06-15T20:04:47.461602Z","end":"2026-06-15T20:04:47.643238Z","steps":["trace[673207768] 'agreement among raft nodes before linearized reading' (duration: 180.99152ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:47.644247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.035311ms","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-15T20:04:47.644323Z","caller":"traceutil/trace.go:171","msg":"trace[1898844645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1340; }","duration":"124.114523ms","start":"2026-06-15T20:04:47.520191Z","end":"2026-06-15T20:04:47.644306Z","steps":["trace[1898844645] 'agreement among raft nodes before linearized reading' (duration: 123.895858ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:48.264276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.88114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:04:48.26436Z","caller":"traceutil/trace.go:171","msg":"trace[1400914890] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"614.989467ms","start":"2026-06-15T20:04:47.649355Z","end":"2026-06-15T20:04:48.264345Z","steps":["trace[1400914890] 'process raft request' (duration: 312.974963ms)","trace[1400914890] 'compare' (duration: 301.445179ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:04:48.264413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:47.649331Z","time spent":"615.051308ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:04:48.310583Z","caller":"traceutil/trace.go:171","msg":"trace[2089631883] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"660.755031ms","start":"2026-06-15T20:04:47.6498Z","end":"2026-06-15T20:04:48.310555Z","steps":["trace[2089631883] 'process raft request' (duration: 660.675589ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:48.310645Z","caller":"traceutil/trace.go:171","msg":"trace[872667038] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"661.083379ms","start":"2026-06-15T20:04:47.649525Z","end":"2026-06-15T20:04:48.310608Z","steps":["trace[872667038] 'process raft request' (duration: 660.842453ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:48.31077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:47.649793Z","time spent":"660.891894ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:04:48.310797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:47.649489Z","time spent":"661.225983ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:04:48.310948Z","caller":"traceutil/trace.go:171","msg":"trace[1459783554] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"659.702814ms","start":"2026-06-15T20:04:47.65123Z","end":"2026-06-15T20:04:48.310933Z","steps":["trace[1459783554] 'process raft request' (duration: 659.288744ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:48.311035Z","caller":"traceutil/trace.go:171","msg":"trace[705624735] linearizableReadLoop","detail":"{readStateIndex:1406; appliedIndex:1401; }","duration":"498.557143ms","start":"2026-06-15T20:04:47.812468Z","end":"2026-06-15T20:04:48.311025Z","steps":["trace[705624735] 'read index received' (duration: 149.986195ms)","trace[705624735] 'applied index is now lower than readState.Index' (duration: 348.570208ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:04:48.311038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:47.651217Z","time spent":"659.781196ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:04:48.311156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.690146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:04:48.311174Z","caller":"traceutil/trace.go:171","msg":"trace[1653757343] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"659.682364ms","start":"2026-06-15T20:04:47.65148Z","end":"2026-06-15T20:04:48.311162Z","steps":["trace[1653757343] 'process raft request' (duration: 659.358816ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:48.311172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.998612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T20:04:48.311209Z","caller":"traceutil/trace.go:171","msg":"trace[718059307] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1345; }","duration":"498.778628ms","start":"2026-06-15T20:04:47.812417Z","end":"2026-06-15T20:04:48.311195Z","steps":["trace[718059307] 'agreement among raft nodes before linearized reading' (duration: 498.702966ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:48.311216Z","caller":"traceutil/trace.go:171","msg":"trace[1046928245] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1345; }","duration":"254.082985ms","start":"2026-06-15T20:04:48.057122Z","end":"2026-06-15T20:04:48.311205Z","steps":["trace[1046928245] 'agreement among raft nodes before linearized reading' (duration: 254.017373ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:48.311234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:47.651468Z","time spent":"659.736755ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:04:48.311252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:47.812393Z","time spent":"498.84546ms","remote":"127.0.0.1:54374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-15T20:04:50.750333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.186313866s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-15T20:04:50.750599Z","caller":"traceutil/trace.go:171","msg":"trace[1227021889] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1406; }","duration":"1.150511398s","start":"2026-06-15T20:04:49.600066Z","end":"2026-06-15T20:04:50.750578Z","steps":["trace[1227021889] 'read index received' (duration: 57.942µs)","trace[1227021889] 'applied index is now lower than readState.Index' (duration: 1.150452266s)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:04:50.750834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150756774s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T20:04:50.750843Z","caller":"traceutil/trace.go:171","msg":"trace[1756282152] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"1.111663981s","start":"2026-06-15T20:04:49.639163Z","end":"2026-06-15T20:04:50.750827Z","steps":["trace[1756282152] 'process raft request' (duration: 1.111301662s)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:50.750869Z","caller":"traceutil/trace.go:171","msg":"trace[2040884580] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1345; }","duration":"1.150848876s","start":"2026-06-15T20:04:49.600011Z","end":"2026-06-15T20:04:50.750859Z","steps":["trace[2040884580] 'agreement among raft nodes before linearized reading' (duration: 1.150748563s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:50.750917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:49.599986Z","time spent":"1.150905328s","remote":"127.0.0.1:54742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:04:50.750932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:49.639144Z","time spent":"1.111727863s","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:04:50.859612Z","caller":"traceutil/trace.go:171","msg":"trace[1826082184] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"1.208951045s","start":"2026-06-15T20:04:49.650628Z","end":"2026-06-15T20:04:50.859579Z","steps":["trace[1826082184] 'process raft request' (duration: 1.208717248s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:50.859745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05151083s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T20:04:50.859776Z","caller":"traceutil/trace.go:171","msg":"trace[2145172291] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1349; }","duration":"1.051617132s","start":"2026-06-15T20:04:49.808151Z","end":"2026-06-15T20:04:50.859768Z","steps":["trace[2145172291] 'agreement among raft nodes before linearized reading' (duration: 1.051513279s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:50.859798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:49.808121Z","time spent":"1.051671964s","remote":"127.0.0.1:51784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:04:50.859787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:49.650591Z","time spent":"1.20912507s","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:04:50.859623Z","caller":"traceutil/trace.go:171","msg":"trace[975441840] linearizableReadLoop","detail":"{readStateIndex:1411; appliedIndex:1409; }","duration":"108.980454ms","start":"2026-06-15T20:04:50.75063Z","end":"2026-06-15T20:04:50.85961Z","steps":["trace[975441840] 'read index received' (duration: 108.924473ms)","trace[975441840] 'applied index is now lower than readState.Index' (duration: 55.551µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:04:50.860089Z","caller":"traceutil/trace.go:171","msg":"trace[845879104] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"1.206588365s","start":"2026-06-15T20:04:49.653491Z","end":"2026-06-15T20:04:50.860079Z","steps":["trace[845879104] 'process raft request' (duration: 1.206038671s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:50.860128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:49.653464Z","time spent":"1.206641017s","remote":"127.0.0.1:54516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5500,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:04:50.860224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.897604ms","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-15T20:04:50.860251Z","caller":"traceutil/trace.go:171","msg":"trace[1486836008] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1349; }","duration":"543.986607ms","start":"2026-06-15T20:04:50.316257Z","end":"2026-06-15T20:04:50.860243Z","steps":["trace[1486836008] 'agreement among raft nodes before linearized reading' (duration: 543.882834ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:50.860269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:50.316224Z","time spent":"544.040928ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-15T20:04:50.86027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.329656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-15T20:04:50.860289Z","caller":"traceutil/trace.go:171","msg":"trace[1900975914] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1349; }","duration":"590.400677ms","start":"2026-06-15T20:04:50.269882Z","end":"2026-06-15T20:04:50.860283Z","steps":["trace[1900975914] 'agreement among raft nodes before linearized reading' (duration: 590.326295ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:50.860305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:50.269851Z","time spent":"590.450039ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-15T20:04:50.860352Z","caller":"traceutil/trace.go:171","msg":"trace[650405629] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"702.533542ms","start":"2026-06-15T20:04:50.157811Z","end":"2026-06-15T20:04:50.860345Z","steps":["trace[650405629] 'process raft request' (duration: 701.761072ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:50.860385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:50.157784Z","time spent":"702.583243ms","remote":"127.0.0.1:54452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-15T20:04:50.86054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.270773ms","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-15T20:04:50.860562Z","caller":"traceutil/trace.go:171","msg":"trace[1986024919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1349; }","duration":"542.472438ms","start":"2026-06-15T20:04:50.318084Z","end":"2026-06-15T20:04:50.860556Z","steps":["trace[1986024919] 'agreement among raft nodes before linearized reading' (duration: 542.393596ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:50.860583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:50.318039Z","time spent":"542.539491ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T20:04:50.860625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.926666ms","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-15T20:04:50.860644Z","caller":"traceutil/trace.go:171","msg":"trace[1351358733] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1349; }","duration":"543.972406ms","start":"2026-06-15T20:04:50.316666Z","end":"2026-06-15T20:04:50.860638Z","steps":["trace[1351358733] 'agreement among raft nodes before linearized reading' (duration: 543.913805ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:50.860614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046137551s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:04:50.860707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.51208ms","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-15T20:04:50.860724Z","caller":"traceutil/trace.go:171","msg":"trace[1421237053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1349; }","duration":"542.640163ms","start":"2026-06-15T20:04:50.318079Z","end":"2026-06-15T20:04:50.860719Z","steps":["trace[1421237053] 'agreement among raft nodes before linearized reading' (duration: 542.593312ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:50.860738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:50.318054Z","time spent":"542.681014ms","remote":"127.0.0.1:54600","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-15T20:04:50.860718Z","caller":"traceutil/trace.go:171","msg":"trace[1331602131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1349; }","duration":"1.046301965s","start":"2026-06-15T20:04:49.814388Z","end":"2026-06-15T20:04:50.86069Z","steps":["trace[1331602131] 'agreement among raft nodes before linearized reading' (duration: 1.04610669s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:50.860787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:49.814364Z","time spent":"1.046410209s","remote":"127.0.0.1:54374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-15T20:04:50.860661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:50.316655Z","time spent":"544.002138ms","remote":"127.0.0.1:54600","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-15T20:04:50.97081Z","caller":"traceutil/trace.go:171","msg":"trace[81968265] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"102.702103ms","start":"2026-06-15T20:04:50.868071Z","end":"2026-06-15T20:04:50.970773Z","steps":["trace[81968265] 'process raft request' (duration: 41.626396ms)","trace[81968265] 'compare' (duration: 60.796ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:04:50.970814Z","caller":"traceutil/trace.go:171","msg":"trace[763147436] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"102.550609ms","start":"2026-06-15T20:04:50.868228Z","end":"2026-06-15T20:04:50.970779Z","steps":["trace[763147436] 'process raft request' (duration: 102.424466ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:50.971077Z","caller":"traceutil/trace.go:171","msg":"trace[633654246] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"102.441696ms","start":"2026-06-15T20:04:50.868626Z","end":"2026-06-15T20:04:50.971068Z","steps":["trace[633654246] 'process raft request' (duration: 102.076817ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:50.971255Z","caller":"traceutil/trace.go:171","msg":"trace[2024115775] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"102.488737ms","start":"2026-06-15T20:04:50.868729Z","end":"2026-06-15T20:04:50.971218Z","steps":["trace[2024115775] 'process raft request' (duration: 102.235831ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:52.101229Z","caller":"traceutil/trace.go:171","msg":"trace[292395962] linearizableReadLoop","detail":"{readStateIndex:1421; appliedIndex:1420; }","duration":"184.971342ms","start":"2026-06-15T20:04:51.916206Z","end":"2026-06-15T20:04:52.101177Z","steps":["trace[292395962] 'read index received' (duration: 184.634194ms)","trace[292395962] 'applied index is now lower than readState.Index' (duration: 335.778µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:04:52.101451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.201898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T20:04:52.101405Z","caller":"traceutil/trace.go:171","msg":"trace[704881304] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"236.911475ms","start":"2026-06-15T20:04:51.864439Z","end":"2026-06-15T20:04:52.101351Z","steps":["trace[704881304] 'process raft request' (duration: 236.504474ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:52.101497Z","caller":"traceutil/trace.go:171","msg":"trace[1981422992] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1359; }","duration":"185.343042ms","start":"2026-06-15T20:04:51.916143Z","end":"2026-06-15T20:04:52.101486Z","steps":["trace[1981422992] 'agreement among raft nodes before linearized reading' (duration: 185.178638ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:53.31309Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638037794031845835,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-15T20:04:53.542205Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.43263737s","expected-duration":"1s"} {"level":"info","ts":"2026-06-15T20:04:53.542639Z","caller":"traceutil/trace.go:171","msg":"trace[1456512006] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"1.433146763s","start":"2026-06-15T20:04:52.109461Z","end":"2026-06-15T20:04:53.542608Z","steps":["trace[1456512006] 'process raft request' (duration: 1.432975758s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:53.542817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:52.109442Z","time spent":"1.433275526s","remote":"127.0.0.1:54714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:04:53.573948Z","caller":"traceutil/trace.go:171","msg":"trace[1655083730] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"702.766798ms","start":"2026-06-15T20:04:52.871162Z","end":"2026-06-15T20:04:53.573929Z","steps":["trace[1655083730] 'process raft request' (duration: 702.601663ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:53.574087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:52.871112Z","time spent":"702.897102ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:04:53.574258Z","caller":"traceutil/trace.go:171","msg":"trace[355827290] linearizableReadLoop","detail":"{readStateIndex:1423; appliedIndex:1422; }","duration":"761.397821ms","start":"2026-06-15T20:04:52.812491Z","end":"2026-06-15T20:04:53.573889Z","steps":["trace[355827290] 'read index received' (duration: 730.767276ms)","trace[355827290] 'applied index is now lower than readState.Index' (duration: 30.629295ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:04:53.574344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.857673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:04:53.574465Z","caller":"traceutil/trace.go:171","msg":"trace[1687861610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1361; }","duration":"762.018507ms","start":"2026-06-15T20:04:52.812436Z","end":"2026-06-15T20:04:53.574455Z","steps":["trace[1687861610] 'agreement among raft nodes before linearized reading' (duration: 761.869773ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:53.574498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:52.812411Z","time spent":"762.079679ms","remote":"127.0.0.1:54374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-15T20:04:53.574973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.641307ms","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-15T20:04:53.575013Z","caller":"traceutil/trace.go:171","msg":"trace[1680131686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1361; }","duration":"596.700479ms","start":"2026-06-15T20:04:52.978303Z","end":"2026-06-15T20:04:53.575004Z","steps":["trace[1680131686] 'agreement among raft nodes before linearized reading' (duration: 596.545075ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:53.574993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.713795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:04:53.575092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.665299ms","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":"info","ts":"2026-06-15T20:04:53.575102Z","caller":"traceutil/trace.go:171","msg":"trace[100822892] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1361; }","duration":"347.898479ms","start":"2026-06-15T20:04:53.227176Z","end":"2026-06-15T20:04:53.575074Z","steps":["trace[100822892] 'agreement among raft nodes before linearized reading' (duration: 347.713625ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:53.575129Z","caller":"traceutil/trace.go:171","msg":"trace[933662120] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1361; }","duration":"543.734011ms","start":"2026-06-15T20:04:53.031386Z","end":"2026-06-15T20:04:53.57512Z","steps":["trace[933662120] 'agreement among raft nodes before linearized reading' (duration: 543.675809ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:53.575164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:53.031369Z","time spent":"543.787292ms","remote":"127.0.0.1:42362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:04:53.575157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:53.227151Z","time spent":"347.991571ms","remote":"127.0.0.1:42286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:04:53.575043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:52.978296Z","time spent":"596.73995ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T20:04:53.575512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.249152ms","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-15T20:04:53.575544Z","caller":"traceutil/trace.go:171","msg":"trace[561754248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1361; }","duration":"597.304514ms","start":"2026-06-15T20:04:52.978229Z","end":"2026-06-15T20:04:53.575534Z","steps":["trace[561754248] 'agreement among raft nodes before linearized reading' (duration: 597.213712ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:53.57557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:52.978222Z","time spent":"597.342015ms","remote":"127.0.0.1:54600","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-15T20:04:53.575849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.54563ms","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-15T20:04:53.575897Z","caller":"traceutil/trace.go:171","msg":"trace[1303259322] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1361; }","duration":"597.617052ms","start":"2026-06-15T20:04:52.978268Z","end":"2026-06-15T20:04:53.575885Z","steps":["trace[1303259322] 'agreement among raft nodes before linearized reading' (duration: 597.449318ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:53.575913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.694753ms","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-15T20:04:53.575928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:52.978261Z","time spent":"597.659163ms","remote":"127.0.0.1:54600","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-15T20:04:53.575944Z","caller":"traceutil/trace.go:171","msg":"trace[163640391] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1361; }","duration":"597.746955ms","start":"2026-06-15T20:04:52.978188Z","end":"2026-06-15T20:04:53.575935Z","steps":["trace[163640391] 'agreement among raft nodes before linearized reading' (duration: 597.656903ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:53.575971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:52.978177Z","time spent":"597.788996ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-15T20:04:53.580571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.497057ms","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-15T20:04:53.580609Z","caller":"traceutil/trace.go:171","msg":"trace[1742966969] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1361; }","duration":"602.591529ms","start":"2026-06-15T20:04:52.978007Z","end":"2026-06-15T20:04:53.580599Z","steps":["trace[1742966969] 'agreement among raft nodes before linearized reading' (duration: 596.798251ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:53.580632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:52.97798Z","time spent":"602.644921ms","remote":"127.0.0.1:54600","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-15T20:04:53.70038Z","caller":"traceutil/trace.go:171","msg":"trace[1158559445] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"114.854164ms","start":"2026-06-15T20:04:53.585488Z","end":"2026-06-15T20:04:53.700342Z","steps":["trace[1158559445] 'process raft request' (duration: 114.803163ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:53.700737Z","caller":"traceutil/trace.go:171","msg":"trace[4670983] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"115.970654ms","start":"2026-06-15T20:04:53.58472Z","end":"2026-06-15T20:04:53.70069Z","steps":["trace[4670983] 'process raft request' (duration: 115.528733ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:53.701052Z","caller":"traceutil/trace.go:171","msg":"trace[354096709] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"116.404315ms","start":"2026-06-15T20:04:53.584633Z","end":"2026-06-15T20:04:53.701037Z","steps":["trace[354096709] 'process raft request' (duration: 115.567594ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:53.701489Z","caller":"traceutil/trace.go:171","msg":"trace[2124401098] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"119.563456ms","start":"2026-06-15T20:04:53.581908Z","end":"2026-06-15T20:04:53.701472Z","steps":["trace[2124401098] 'process raft request' (duration: 118.055397ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:54.029457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.085294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:04:54.029785Z","caller":"traceutil/trace.go:171","msg":"trace[1841044821] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"130.828895ms","start":"2026-06-15T20:04:53.898931Z","end":"2026-06-15T20:04:54.02976Z","steps":["trace[1841044821] 'process raft request' (duration: 130.714592ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:54.029935Z","caller":"traceutil/trace.go:171","msg":"trace[455445468] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"152.738385ms","start":"2026-06-15T20:04:53.877154Z","end":"2026-06-15T20:04:54.029892Z","steps":["trace[455445468] 'process raft request' (duration: 32.929303ms)","trace[455445468] 'compare' (duration: 118.864288ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:04:54.661941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.115637ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:04:54.662036Z","caller":"traceutil/trace.go:171","msg":"trace[1363499094] linearizableReadLoop","detail":"{readStateIndex:1432; appliedIndex:1431; }","duration":"365.559263ms","start":"2026-06-15T20:04:54.296459Z","end":"2026-06-15T20:04:54.662018Z","steps":["trace[1363499094] 'read index received' (duration: 37.131µs)","trace[1363499094] 'applied index is now lower than readState.Index' (duration: 365.520962ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:04:54.662112Z","caller":"traceutil/trace.go:171","msg":"trace[1511221926] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"532.859631ms","start":"2026-06-15T20:04:54.129242Z","end":"2026-06-15T20:04:54.662102Z","steps":["trace[1511221926] 'process raft request' (duration: 134.510289ms)","trace[1511221926] 'compare' (duration: 397.987313ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:04:54.662166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:54.129208Z","time spent":"532.942194ms","remote":"127.0.0.1:54452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-15T20:04:54.662537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.025885ms","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-15T20:04:54.662701Z","caller":"traceutil/trace.go:171","msg":"trace[1867179522] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1369; }","duration":"366.269621ms","start":"2026-06-15T20:04:54.296402Z","end":"2026-06-15T20:04:54.662671Z","steps":["trace[1867179522] 'agreement among raft nodes before linearized reading' (duration: 365.948313ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:54.662768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:54.296385Z","time spent":"366.363953ms","remote":"127.0.0.1:54740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:04:54.662808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.886257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-qmbbf\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-06-15T20:04:54.662865Z","caller":"traceutil/trace.go:171","msg":"trace[1365785298] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-qmbbf; range_end:; response_count:1; response_revision:1369; }","duration":"313.982299ms","start":"2026-06-15T20:04:54.348871Z","end":"2026-06-15T20:04:54.662853Z","steps":["trace[1365785298] 'agreement among raft nodes before linearized reading' (duration: 313.431545ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:54.662889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:54.348848Z","time spent":"314.034021ms","remote":"127.0.0.1:54532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":11084,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-qmbbf\" "} {"level":"warn","ts":"2026-06-15T20:04:55.227559Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4638037794031845858,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-15T20:04:55.471381Z","caller":"traceutil/trace.go:171","msg":"trace[1764519894] linearizableReadLoop","detail":"{readStateIndex:1433; appliedIndex:1432; }","duration":"744.125068ms","start":"2026-06-15T20:04:54.727224Z","end":"2026-06-15T20:04:55.471349Z","steps":["trace[1764519894] 'read index received' (duration: 743.828851ms)","trace[1764519894] 'applied index is now lower than readState.Index' (duration: 295.097µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:04:55.471848Z","caller":"traceutil/trace.go:171","msg":"trace[1018694914] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"797.206439ms","start":"2026-06-15T20:04:54.674611Z","end":"2026-06-15T20:04:55.471817Z","steps":["trace[1018694914] 'process raft request' (duration: 796.513642ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:55.471892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.625272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-15T20:04:55.471969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.385854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:04:55.471989Z","caller":"traceutil/trace.go:171","msg":"trace[468197919] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1370; }","duration":"744.785525ms","start":"2026-06-15T20:04:54.727182Z","end":"2026-06-15T20:04:55.471967Z","steps":["trace[468197919] 'agreement among raft nodes before linearized reading' (duration: 744.60268ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:55.472006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:54.674581Z","time spent":"797.330623ms","remote":"127.0.0.1:54532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:04:55.472025Z","caller":"traceutil/trace.go:171","msg":"trace[105951734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1370; }","duration":"657.491807ms","start":"2026-06-15T20:04:54.814518Z","end":"2026-06-15T20:04:55.472009Z","steps":["trace[105951734] 'agreement among raft nodes before linearized reading' (duration: 657.401945ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:55.472031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:54.727159Z","time spent":"744.864417ms","remote":"127.0.0.1:54516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:04:55.472063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:54.81449Z","time spent":"657.566759ms","remote":"127.0.0.1:54374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-15T20:04:55.887882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.426638ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:04:55.887992Z","caller":"traceutil/trace.go:171","msg":"trace[399212645] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"408.528984ms","start":"2026-06-15T20:04:55.479444Z","end":"2026-06-15T20:04:55.887973Z","steps":["trace[399212645] 'process raft request' (duration: 138.916071ms)","trace[399212645] 'compare' (duration: 269.189021ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:04:55.888034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:55.479414Z","time spent":"408.601866ms","remote":"127.0.0.1:54616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:04:55.933127Z","caller":"traceutil/trace.go:171","msg":"trace[716210518] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"453.268681ms","start":"2026-06-15T20:04:55.479832Z","end":"2026-06-15T20:04:55.933101Z","steps":["trace[716210518] 'process raft request' (duration: 452.350507ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:55.933339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:55.479825Z","time spent":"453.403184ms","remote":"127.0.0.1:54514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:04:55.933882Z","caller":"traceutil/trace.go:171","msg":"trace[1398203597] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"447.924684ms","start":"2026-06-15T20:04:55.485915Z","end":"2026-06-15T20:04:55.93384Z","steps":["trace[1398203597] 'process raft request' (duration: 446.451646ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:55.934091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:55.485891Z","time spent":"448.098489ms","remote":"127.0.0.1:54760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:04:55.934224Z","caller":"traceutil/trace.go:171","msg":"trace[1794721819] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"353.607066ms","start":"2026-06-15T20:04:55.580598Z","end":"2026-06-15T20:04:55.934205Z","steps":["trace[1794721819] 'process raft request' (duration: 351.83321ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:55.934345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:55.580564Z","time spent":"353.724188ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:04:55.936144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.021135ms","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-15T20:04:55.936211Z","caller":"traceutil/trace.go:171","msg":"trace[1975789246] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1374; }","duration":"272.138437ms","start":"2026-06-15T20:04:55.664056Z","end":"2026-06-15T20:04:55.936195Z","steps":["trace[1975789246] 'agreement among raft nodes before linearized reading' (duration: 271.924852ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:55.936553Z","caller":"traceutil/trace.go:171","msg":"trace[929239749] linearizableReadLoop","detail":"{readStateIndex:1437; appliedIndex:1434; }","duration":"268.37921ms","start":"2026-06-15T20:04:55.664119Z","end":"2026-06-15T20:04:55.932498Z","steps":["trace[929239749] 'read index received' (duration: 267.97416ms)","trace[929239749] 'applied index is now lower than readState.Index' (duration: 403.66µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:04:55.936791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.794257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-15T20:04:55.936832Z","caller":"traceutil/trace.go:171","msg":"trace[46387572] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1374; }","duration":"230.894109ms","start":"2026-06-15T20:04:55.705927Z","end":"2026-06-15T20:04:55.936821Z","steps":["trace[46387572] 'agreement among raft nodes before linearized reading' (duration: 230.751396ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:55.936872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.54929ms","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-15T20:04:55.936927Z","caller":"traceutil/trace.go:171","msg":"trace[15609402] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1374; }","duration":"228.617681ms","start":"2026-06-15T20:04:55.708296Z","end":"2026-06-15T20:04:55.936913Z","steps":["trace[15609402] 'agreement among raft nodes before linearized reading' (duration: 228.418296ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:55.937036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.765955ms","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-15T20:04:55.937446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.615643ms","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-15T20:04:55.937529Z","caller":"traceutil/trace.go:171","msg":"trace[1375366544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1374; }","duration":"230.713275ms","start":"2026-06-15T20:04:55.706768Z","end":"2026-06-15T20:04:55.937481Z","steps":["trace[1375366544] 'agreement among raft nodes before linearized reading' (duration: 230.583942ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:55.937654Z","caller":"traceutil/trace.go:171","msg":"trace[1814453425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1374; }","duration":"228.819377ms","start":"2026-06-15T20:04:55.708244Z","end":"2026-06-15T20:04:55.937063Z","steps":["trace[1814453425] 'agreement among raft nodes before linearized reading' (duration: 228.722135ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:56.163822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.788448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:04:56.163991Z","caller":"traceutil/trace.go:171","msg":"trace[1057453141] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"220.517874ms","start":"2026-06-15T20:04:55.943443Z","end":"2026-06-15T20:04:56.163961Z","steps":["trace[1057453141] 'process raft request' (duration: 113.427398ms)","trace[1057453141] 'compare' (duration: 106.189903ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:04:56.165249Z","caller":"traceutil/trace.go:171","msg":"trace[859354684] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"219.960719ms","start":"2026-06-15T20:04:55.945271Z","end":"2026-06-15T20:04:56.165231Z","steps":["trace[859354684] 'process raft request' (duration: 219.904137ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:56.165515Z","caller":"traceutil/trace.go:171","msg":"trace[910642106] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"221.857239ms","start":"2026-06-15T20:04:55.943642Z","end":"2026-06-15T20:04:56.165499Z","steps":["trace[910642106] 'process raft request' (duration: 221.425517ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:56.165659Z","caller":"traceutil/trace.go:171","msg":"trace[1027593338] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"221.951731ms","start":"2026-06-15T20:04:55.943697Z","end":"2026-06-15T20:04:56.165649Z","steps":["trace[1027593338] 'process raft request' (duration: 221.421017ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:56.165661Z","caller":"traceutil/trace.go:171","msg":"trace[1559894363] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"221.757286ms","start":"2026-06-15T20:04:55.943569Z","end":"2026-06-15T20:04:56.165327Z","steps":["trace[1559894363] 'process raft request' (duration: 221.410617ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:58.298262Z","caller":"traceutil/trace.go:171","msg":"trace[1893490450] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"107.212079ms","start":"2026-06-15T20:04:58.191014Z","end":"2026-06-15T20:04:58.298226Z","steps":["trace[1893490450] 'process raft request' (duration: 106.963623ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:58.493293Z","caller":"traceutil/trace.go:171","msg":"trace[1121617341] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"301.87357ms","start":"2026-06-15T20:04:58.191361Z","end":"2026-06-15T20:04:58.493235Z","steps":["trace[1121617341] 'process raft request' (duration: 301.394807ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:58.493407Z","caller":"traceutil/trace.go:171","msg":"trace[2093135868] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"301.806418ms","start":"2026-06-15T20:04:58.191514Z","end":"2026-06-15T20:04:58.493321Z","steps":["trace[2093135868] 'process raft request' (duration: 301.479379ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:58.49355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:58.191344Z","time spent":"302.082165ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:04:58.493749Z","caller":"traceutil/trace.go:171","msg":"trace[909330788] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"301.767638ms","start":"2026-06-15T20:04:58.191966Z","end":"2026-06-15T20:04:58.493734Z","steps":["trace[909330788] 'process raft request' (duration: 301.391658ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:58.493837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:58.191961Z","time spent":"301.835979ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:04:58.493859Z","caller":"traceutil/trace.go:171","msg":"trace[141014053] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"301.904351ms","start":"2026-06-15T20:04:58.191888Z","end":"2026-06-15T20:04:58.493792Z","steps":["trace[141014053] 'process raft request' (duration: 301.238813ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:04:58.494172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:58.191879Z","time spent":"302.116306ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:04:58.495064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:04:58.191482Z","time spent":"302.631519ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:05:01.043579Z","caller":"traceutil/trace.go:171","msg":"trace[1742169461] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"173.308404ms","start":"2026-06-15T20:05:00.870243Z","end":"2026-06-15T20:05:01.043551Z","steps":["trace[1742169461] 'process raft request' (duration: 173.15336ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:05:04.238151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:05:03.816809Z","time spent":"421.338602ms","remote":"127.0.0.1:54406","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-15T20:05:06.815864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000134671s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-15T20:05:06.815977Z","caller":"traceutil/trace.go:171","msg":"trace[1238742398] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000341546s","start":"2026-06-15T20:05:04.815615Z","end":"2026-06-15T20:05:06.815957Z","steps":["trace[1238742398] 'agreement among raft nodes before linearized reading' (duration: 2.000166111s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:05:06.816023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:05:04.815596Z","time spent":"2.000416627s","remote":"127.0.0.1:54374","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-15T20:05:06.985764Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.747511642s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-15T20:05:07.247447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.224337ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:05:07.247543Z","caller":"traceutil/trace.go:171","msg":"trace[490502056] linearizableReadLoop","detail":"{readStateIndex:1487; appliedIndex:1486; }","duration":"3.102164944s","start":"2026-06-15T20:05:04.145365Z","end":"2026-06-15T20:05:07.24753Z","steps":["trace[490502056] 'read index received' (duration: 92.984834ms)","trace[490502056] 'applied index is now lower than readState.Index' (duration: 3.0091792s)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:05:07.247591Z","caller":"traceutil/trace.go:171","msg":"trace[618248058] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"3.190902871s","start":"2026-06-15T20:05:04.056679Z","end":"2026-06-15T20:05:07.247582Z","steps":["trace[618248058] 'process raft request' (duration: 2.929438546s)","trace[618248058] 'compare' (duration: 260.767446ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:05:07.24763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:05:04.0566Z","time spent":"3.191007943s","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:05:07.247689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.102318609s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T20:05:07.247765Z","caller":"traceutil/trace.go:171","msg":"trace[1449115748] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1422; }","duration":"3.102398081s","start":"2026-06-15T20:05:04.145355Z","end":"2026-06-15T20:05:07.247753Z","steps":["trace[1449115748] 'agreement among raft nodes before linearized reading' (duration: 3.102287748s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:05:07.247798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:05:04.1453Z","time spent":"3.102491404s","remote":"127.0.0.1:54478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-06-15T20:05:07.681672Z","caller":"traceutil/trace.go:171","msg":"trace[1178352192] linearizableReadLoop","detail":"{readStateIndex:1488; appliedIndex:1487; }","duration":"434.064228ms","start":"2026-06-15T20:05:07.247561Z","end":"2026-06-15T20:05:07.681625Z","steps":["trace[1178352192] 'read index received' (duration: 365.783158ms)","trace[1178352192] 'applied index is now lower than readState.Index' (duration: 68.28031ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:05:07.681889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.861789112s","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":"info","ts":"2026-06-15T20:05:07.681964Z","caller":"traceutil/trace.go:171","msg":"trace[1905787307] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1423; }","duration":"2.861929166s","start":"2026-06-15T20:05:04.820017Z","end":"2026-06-15T20:05:07.681946Z","steps":["trace[1905787307] 'agreement among raft nodes before linearized reading' (duration: 2.861704769s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:05:07.681995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:05:04.81999Z","time spent":"2.862000257s","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-15T20:05:07.682037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.155321288s","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-15T20:05:07.682094Z","caller":"traceutil/trace.go:171","msg":"trace[674811529] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1423; }","duration":"3.15538291s","start":"2026-06-15T20:05:04.526679Z","end":"2026-06-15T20:05:07.682062Z","steps":["trace[674811529] 'agreement among raft nodes before linearized reading' (duration: 3.155170654s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:05:07.682176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:05:04.526672Z","time spent":"3.155445951s","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-15T20:05:07.682339Z","caller":"traceutil/trace.go:171","msg":"trace[1291753254] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"3.442080179s","start":"2026-06-15T20:05:04.240242Z","end":"2026-06-15T20:05:07.682323Z","steps":["trace[1291753254] 'process raft request' (duration: 3.373173153s)","trace[1291753254] 'compare' (duration: 67.921861ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:05:07.68229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.633733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-15T20:05:07.682427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:05:04.240221Z","time spent":"3.442155592s","remote":"127.0.0.1:54406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:05:07.682354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.156883657s","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-15T20:05:07.682549Z","caller":"traceutil/trace.go:171","msg":"trace[1802323015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1423; }","duration":"3.157123533s","start":"2026-06-15T20:05:04.525418Z","end":"2026-06-15T20:05:07.682542Z","steps":["trace[1802323015] 'agreement among raft nodes before linearized reading' (duration: 3.156866616s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:05:07.68257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:05:04.52539Z","time spent":"3.157173925s","remote":"127.0.0.1:54600","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-15T20:05:07.682547Z","caller":"traceutil/trace.go:171","msg":"trace[1368233675] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1423; }","duration":"637.270949ms","start":"2026-06-15T20:05:07.045167Z","end":"2026-06-15T20:05:07.682438Z","steps":["trace[1368233675] 'agreement among raft nodes before linearized reading' (duration: 636.593502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:05:07.682622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:05:07.045136Z","time spent":"637.468495ms","remote":"127.0.0.1:54688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":55,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:05:07.682697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.157473062s","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-15T20:05:07.682718Z","caller":"traceutil/trace.go:171","msg":"trace[2053934699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1423; }","duration":"3.157525033s","start":"2026-06-15T20:05:04.525189Z","end":"2026-06-15T20:05:07.682714Z","steps":["trace[2053934699] 'agreement among raft nodes before linearized reading' (duration: 3.157456742s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:05:07.682734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:05:04.525172Z","time spent":"3.157558485s","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T20:05:07.682818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.577267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-06-15T20:05:07.682839Z","caller":"traceutil/trace.go:171","msg":"trace[659225639] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1423; }","duration":"802.634528ms","start":"2026-06-15T20:05:06.880198Z","end":"2026-06-15T20:05:07.682833Z","steps":["trace[659225639] 'agreement among raft nodes before linearized reading' (duration: 802.583407ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:05:07.682799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.35864689s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-15T20:05:07.6829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.353703ms","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":"info","ts":"2026-06-15T20:05:07.682903Z","caller":"traceutil/trace.go:171","msg":"trace[1582925464] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1423; }","duration":"3.358770224s","start":"2026-06-15T20:05:04.32411Z","end":"2026-06-15T20:05:07.68288Z","steps":["trace[1582925464] 'agreement among raft nodes before linearized reading' (duration: 3.358455356s)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:05:07.682963Z","caller":"traceutil/trace.go:171","msg":"trace[445335350] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1423; }","duration":"240.430985ms","start":"2026-06-15T20:05:07.442496Z","end":"2026-06-15T20:05:07.682927Z","steps":["trace[445335350] 'agreement among raft nodes before linearized reading' (duration: 240.316002ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:05:07.682964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:05:04.324048Z","time spent":"3.358906276s","remote":"127.0.0.1:54600","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-15T20:05:07.682854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:05:06.880181Z","time spent":"802.67011ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-15T20:05:07.683109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.157812981s","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-15T20:05:07.683139Z","caller":"traceutil/trace.go:171","msg":"trace[784621488] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1423; }","duration":"3.157878453s","start":"2026-06-15T20:05:04.525254Z","end":"2026-06-15T20:05:07.683132Z","steps":["trace[784621488] 'agreement among raft nodes before linearized reading' (duration: 3.157115384s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:05:07.68318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:05:04.525234Z","time spent":"3.157939674s","remote":"127.0.0.1:54600","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-15T20:05:07.683492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.756812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:05:07.684299Z","caller":"traceutil/trace.go:171","msg":"trace[565665027] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1423; }","duration":"867.601793ms","start":"2026-06-15T20:05:06.816682Z","end":"2026-06-15T20:05:07.684283Z","steps":["trace[565665027] 'agreement among raft nodes before linearized reading' (duration: 866.014793ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:05:07.684359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:05:06.816659Z","time spent":"867.690995ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-15T20:05:07.870354Z","caller":"traceutil/trace.go:171","msg":"trace[2090743682] transaction","detail":"{read_only:false; response_revision:1425; number_of_response:1; }","duration":"181.649637ms","start":"2026-06-15T20:05:07.688663Z","end":"2026-06-15T20:05:07.870312Z","steps":["trace[2090743682] 'process raft request' (duration: 181.262197ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:05:07.870346Z","caller":"traceutil/trace.go:171","msg":"trace[1850370447] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"181.600546ms","start":"2026-06-15T20:05:07.688683Z","end":"2026-06-15T20:05:07.870284Z","steps":["trace[1850370447] 'process raft request' (duration: 181.500894ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:05:07.870718Z","caller":"traceutil/trace.go:171","msg":"trace[1202274227] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"179.244985ms","start":"2026-06-15T20:05:07.691462Z","end":"2026-06-15T20:05:07.870707Z","steps":["trace[1202274227] 'process raft request' (duration: 179.141463ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:05:07.87072Z","caller":"traceutil/trace.go:171","msg":"trace[1218519570] linearizableReadLoop","detail":"{readStateIndex:1496; appliedIndex:1489; }","duration":"160.048323ms","start":"2026-06-15T20:05:07.71065Z","end":"2026-06-15T20:05:07.870698Z","steps":["trace[1218519570] 'read index received' (duration: 159.249353ms)","trace[1218519570] 'applied index is now lower than readState.Index' (duration: 798.06µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:05:07.870777Z","caller":"traceutil/trace.go:171","msg":"trace[746017927] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"181.943585ms","start":"2026-06-15T20:05:07.688801Z","end":"2026-06-15T20:05:07.870744Z","steps":["trace[746017927] 'process raft request' (duration: 181.447233ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:05:07.870854Z","caller":"traceutil/trace.go:171","msg":"trace[612874918] transaction","detail":"{read_only:false; response_revision:1430; number_of_response:1; }","duration":"179.317768ms","start":"2026-06-15T20:05:07.691523Z","end":"2026-06-15T20:05:07.870841Z","steps":["trace[612874918] 'process raft request' (duration: 179.111402ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:05:07.870868Z","caller":"traceutil/trace.go:171","msg":"trace[994058606] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"180.907239ms","start":"2026-06-15T20:05:07.689944Z","end":"2026-06-15T20:05:07.870851Z","steps":["trace[994058606] 'process raft request' (duration: 180.55674ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:05:07.870959Z","caller":"traceutil/trace.go:171","msg":"trace[1876122843] transaction","detail":"{read_only:false; response_revision:1431; number_of_response:1; }","duration":"179.345218ms","start":"2026-06-15T20:05:07.691602Z","end":"2026-06-15T20:05:07.870947Z","steps":["trace[1876122843] 'process raft request' (duration: 179.062881ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:05:07.871017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.156664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:05:07.871051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.16627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18b959b40daf672b\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-06-15T20:05:07.87107Z","caller":"traceutil/trace.go:171","msg":"trace[881673584] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1431; }","duration":"158.260757ms","start":"2026-06-15T20:05:07.712795Z","end":"2026-06-15T20:05:07.871056Z","steps":["trace[881673584] 'agreement among raft nodes before linearized reading' (duration: 158.171155ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:05:07.871094Z","caller":"traceutil/trace.go:171","msg":"trace[1344183296] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18b959b40daf672b; range_end:; response_count:1; response_revision:1431; }","duration":"182.241793ms","start":"2026-06-15T20:05:07.68884Z","end":"2026-06-15T20:05:07.871082Z","steps":["trace[1344183296] 'agreement among raft nodes before linearized reading' (duration: 182.003827ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:05:07.871152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.591813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:05:07.871213Z","caller":"traceutil/trace.go:171","msg":"trace[1619728490] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1431; }","duration":"177.668894ms","start":"2026-06-15T20:05:07.693524Z","end":"2026-06-15T20:05:07.871193Z","steps":["trace[1619728490] 'agreement among raft nodes before linearized reading' (duration: 177.569962ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:05:09.131285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.850458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:05:09.131625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.287796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:05:09.131664Z","caller":"traceutil/trace.go:171","msg":"trace[1224071847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1434; }","duration":"319.363348ms","start":"2026-06-15T20:05:08.812289Z","end":"2026-06-15T20:05:09.131653Z","steps":["trace[1224071847] 'range keys from in-memory index tree' (duration: 319.130253ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:05:09.131623Z","caller":"traceutil/trace.go:171","msg":"trace[1349318344] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1434; }","duration":"410.227138ms","start":"2026-06-15T20:05:08.721369Z","end":"2026-06-15T20:05:09.131596Z","steps":["trace[1349318344] 'range keys from in-memory index tree' (duration: 409.785676ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:05:09.131705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:05:08.812269Z","time spent":"319.42629ms","remote":"127.0.0.1:54374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-15T20:05:09.131755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.237195ms","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-15T20:05:09.131908Z","caller":"traceutil/trace.go:171","msg":"trace[457287881] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1434; }","duration":"234.41236ms","start":"2026-06-15T20:05:08.897473Z","end":"2026-06-15T20:05:09.131886Z","steps":["trace[457287881] 'range keys from in-memory index tree' (duration: 234.214925ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:05:09.131755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:05:08.721352Z","time spent":"410.366721ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-06-15T20:05:09.557544Z","caller":"traceutil/trace.go:171","msg":"trace[1579233021] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"134.18454ms","start":"2026-06-15T20:05:09.423326Z","end":"2026-06-15T20:05:09.55751Z","steps":["trace[1579233021] 'process raft request' (duration: 133.975075ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:05:38.022717Z","caller":"traceutil/trace.go:171","msg":"trace[772881405] transaction","detail":"{read_only:false; response_revision:1580; number_of_response:1; }","duration":"129.057295ms","start":"2026-06-15T20:05:37.893627Z","end":"2026-06-15T20:05:38.022685Z","steps":["trace[772881405] 'process raft request' (duration: 128.827489ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:05:40.199114Z","caller":"traceutil/trace.go:171","msg":"trace[1451606512] linearizableReadLoop","detail":"{readStateIndex:1663; appliedIndex:1662; }","duration":"162.588532ms","start":"2026-06-15T20:05:40.036488Z","end":"2026-06-15T20:05:40.199077Z","steps":["trace[1451606512] 'read index received' (duration: 162.429158ms)","trace[1451606512] 'applied index is now lower than readState.Index' (duration: 158.704µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:05:40.199593Z","caller":"traceutil/trace.go:171","msg":"trace[1402422712] transaction","detail":"{read_only:false; response_revision:1591; number_of_response:1; }","duration":"165.084215ms","start":"2026-06-15T20:05:40.034481Z","end":"2026-06-15T20:05:40.199566Z","steps":["trace[1402422712] 'process raft request' (duration: 164.353697ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:05:40.200426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.922396ms","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-15T20:05:40.200479Z","caller":"traceutil/trace.go:171","msg":"trace[315080417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1591; }","duration":"164.023719ms","start":"2026-06-15T20:05:40.036444Z","end":"2026-06-15T20:05:40.200468Z","steps":["trace[315080417] 'agreement among raft nodes before linearized reading' (duration: 163.078734ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:05:40.354116Z","caller":"traceutil/trace.go:171","msg":"trace[353346170] transaction","detail":"{read_only:false; response_revision:1593; number_of_response:1; }","duration":"147.516977ms","start":"2026-06-15T20:05:40.206564Z","end":"2026-06-15T20:05:40.354081Z","steps":["trace[353346170] 'process raft request' (duration: 117.46774ms)","trace[353346170] 'compare' (duration: 29.697258ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:05:40.354418Z","caller":"traceutil/trace.go:171","msg":"trace[267050521] transaction","detail":"{read_only:false; response_revision:1596; number_of_response:1; }","duration":"146.705487ms","start":"2026-06-15T20:05:40.207684Z","end":"2026-06-15T20:05:40.35439Z","steps":["trace[267050521] 'process raft request' (duration: 146.657686ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:05:40.354597Z","caller":"traceutil/trace.go:171","msg":"trace[723350885] transaction","detail":"{read_only:false; response_revision:1594; number_of_response:1; }","duration":"147.932488ms","start":"2026-06-15T20:05:40.206655Z","end":"2026-06-15T20:05:40.354587Z","steps":["trace[723350885] 'process raft request' (duration: 147.333322ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:05:40.354771Z","caller":"traceutil/trace.go:171","msg":"trace[1188946474] transaction","detail":"{read_only:false; response_revision:1595; number_of_response:1; }","duration":"147.507277ms","start":"2026-06-15T20:05:40.207254Z","end":"2026-06-15T20:05:40.354761Z","steps":["trace[1188946474] 'process raft request' (duration: 146.987613ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:05:41.61969Z","caller":"traceutil/trace.go:171","msg":"trace[1140493824] transaction","detail":"{read_only:false; response_revision:1597; number_of_response:1; }","duration":"244.021301ms","start":"2026-06-15T20:05:41.375644Z","end":"2026-06-15T20:05:41.619666Z","steps":["trace[1140493824] 'process raft request' (duration: 243.852397ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:07:28.176485Z","caller":"traceutil/trace.go:171","msg":"trace[1732771148] transaction","detail":"{read_only:false; response_revision:2086; number_of_response:1; }","duration":"128.49059ms","start":"2026-06-15T20:07:28.047965Z","end":"2026-06-15T20:07:28.176456Z","steps":["trace[1732771148] 'process raft request' (duration: 128.290575ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:09:07.749716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.265096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-15T20:09:07.749822Z","caller":"traceutil/trace.go:171","msg":"trace[1816987147] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2566; }","duration":"103.400139ms","start":"2026-06-15T20:09:07.646398Z","end":"2026-06-15T20:09:07.749798Z","steps":["trace[1816987147] 'agreement among raft nodes before linearized reading' (duration: 98.520173ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:09:14.237621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.280116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-06-15T20:09:14.23776Z","caller":"traceutil/trace.go:171","msg":"trace[755226493] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2594; }","duration":"566.476901ms","start":"2026-06-15T20:09:13.671254Z","end":"2026-06-15T20:09:14.23773Z","steps":["trace[755226493] 'range keys from in-memory index tree' (duration: 565.999889ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:09:14.237811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:09:13.67123Z","time spent":"566.568814ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-15T20:09:14.237837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.590722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.193\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-06-15T20:09:14.238094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.725003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-15T20:09:14.238192Z","caller":"traceutil/trace.go:171","msg":"trace[514904241] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2594; }","duration":"460.830845ms","start":"2026-06-15T20:09:13.777344Z","end":"2026-06-15T20:09:14.238175Z","steps":["trace[514904241] 'range keys from in-memory index tree' (duration: 460.627701ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:09:14.238263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:09:13.777337Z","time spent":"460.913387ms","remote":"127.0.0.1:54600","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-15T20:09:14.238288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.545253ms","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-15T20:09:14.238336Z","caller":"traceutil/trace.go:171","msg":"trace[1992335996] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2594; }","duration":"466.637165ms","start":"2026-06-15T20:09:13.771688Z","end":"2026-06-15T20:09:14.238325Z","steps":["trace[1992335996] 'range keys from in-memory index tree' (duration: 466.377208ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:09:14.238371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:09:13.771654Z","time spent":"466.709797ms","remote":"127.0.0.1:54600","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-15T20:09:14.238444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.470236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:09:14.238481Z","caller":"traceutil/trace.go:171","msg":"trace[2100223592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2594; }","duration":"424.532967ms","start":"2026-06-15T20:09:13.813938Z","end":"2026-06-15T20:09:14.238471Z","steps":["trace[2100223592] 'range keys from in-memory index tree' (duration: 424.402134ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:09:14.238507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:09:13.813915Z","time spent":"424.586699ms","remote":"127.0.0.1:54374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-15T20:09:14.238595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.484682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-15T20:09:14.2386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.227893ms","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":"info","ts":"2026-06-15T20:09:14.238769Z","caller":"traceutil/trace.go:171","msg":"trace[589720460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2594; }","duration":"473.705197ms","start":"2026-06-15T20:09:13.765031Z","end":"2026-06-15T20:09:14.238736Z","steps":["trace[589720460] 'range keys from in-memory index tree' (duration: 473.146142ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:09:14.238796Z","caller":"traceutil/trace.go:171","msg":"trace[79753454] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2594; }","duration":"564.473019ms","start":"2026-06-15T20:09:13.674291Z","end":"2026-06-15T20:09:14.238764Z","steps":["trace[79753454] 'range keys from in-memory index tree' (duration: 564.013257ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:09:14.23868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.369886ms","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-15T20:09:14.238836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:09:13.765018Z","time spent":"473.80326ms","remote":"127.0.0.1:54600","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-15T20:09:14.238854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:09:13.674281Z","time spent":"564.561721ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-06-15T20:09:14.238899Z","caller":"traceutil/trace.go:171","msg":"trace[1300338373] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2594; }","duration":"495.676154ms","start":"2026-06-15T20:09:13.74319Z","end":"2026-06-15T20:09:14.238866Z","steps":["trace[1300338373] 'range keys from in-memory index tree' (duration: 495.007257ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:09:14.23892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.170309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-15T20:09:14.239006Z","caller":"traceutil/trace.go:171","msg":"trace[195120938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2594; }","duration":"467.294442ms","start":"2026-06-15T20:09:13.771688Z","end":"2026-06-15T20:09:14.238982Z","steps":["trace[195120938] 'range keys from in-memory index tree' (duration: 466.389948ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:09:14.239061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:09:13.771655Z","time spent":"467.390984ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-15T20:09:14.237955Z","caller":"traceutil/trace.go:171","msg":"trace[129111774] range","detail":"{range_begin:/registry/masterleases/199.19.213.193; range_end:; response_count:1; response_revision:2594; }","duration":"409.783377ms","start":"2026-06-15T20:09:13.828142Z","end":"2026-06-15T20:09:14.237925Z","steps":["trace[129111774] 'range keys from in-memory index tree' (duration: 409.247533ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:09:14.238966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:09:13.743163Z","time spent":"495.789057ms","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-15T20:09:14.239152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:09:13.828116Z","time spent":"411.018499ms","remote":"127.0.0.1:54406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.193\" "} {"level":"warn","ts":"2026-06-15T20:09:24.467621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.705068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:09:24.467758Z","caller":"traceutil/trace.go:171","msg":"trace[1257054142] linearizableReadLoop","detail":"{readStateIndex:2770; appliedIndex:2769; }","duration":"133.015704ms","start":"2026-06-15T20:09:24.334724Z","end":"2026-06-15T20:09:24.46774Z","steps":["trace[1257054142] 'read index received' (duration: 27.009197ms)","trace[1257054142] 'applied index is now lower than readState.Index' (duration: 106.005197ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:09:24.468048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.329112ms","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-15T20:09:24.468082Z","caller":"traceutil/trace.go:171","msg":"trace[539089368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2651; }","duration":"133.411884ms","start":"2026-06-15T20:09:24.334661Z","end":"2026-06-15T20:09:24.468073Z","steps":["trace[539089368] 'agreement among raft nodes before linearized reading' (duration: 133.184238ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:09:24.467942Z","caller":"traceutil/trace.go:171","msg":"trace[1178606990] transaction","detail":"{read_only:false; response_revision:2651; number_of_response:1; }","duration":"146.515432ms","start":"2026-06-15T20:09:24.321324Z","end":"2026-06-15T20:09:24.467839Z","steps":["trace[1178606990] 'process raft request' (duration: 40.494045ms)","trace[1178606990] 'compare' (duration: 105.535094ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:09:24.468515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.660976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-15T20:09:24.468474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.676617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:cronjob-controller\" ","response":"range_response_count:1 size:835"} {"level":"info","ts":"2026-06-15T20:09:24.468631Z","caller":"traceutil/trace.go:171","msg":"trace[1991184355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2651; }","duration":"129.847321ms","start":"2026-06-15T20:09:24.33875Z","end":"2026-06-15T20:09:24.468597Z","steps":["trace[1991184355] 'agreement among raft nodes before linearized reading' (duration: 129.498813ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:09:24.468675Z","caller":"traceutil/trace.go:171","msg":"trace[1528753750] range","detail":"{range_begin:/registry/clusterroles/system:controller:cronjob-controller; range_end:; response_count:1; response_revision:2651; }","duration":"129.975595ms","start":"2026-06-15T20:09:24.338678Z","end":"2026-06-15T20:09:24.468654Z","steps":["trace[1528753750] 'agreement among raft nodes before linearized reading' (duration: 129.612576ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:09:24.46888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.219142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-15T20:09:24.470342Z","caller":"traceutil/trace.go:171","msg":"trace[302936732] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2651; }","duration":"131.719489ms","start":"2026-06-15T20:09:24.338563Z","end":"2026-06-15T20:09:24.470282Z","steps":["trace[302936732] 'agreement among raft nodes before linearized reading' (duration: 129.445331ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:09:24.469026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.855942ms","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-15T20:09:24.470578Z","caller":"traceutil/trace.go:171","msg":"trace[232080620] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2651; }","duration":"131.547775ms","start":"2026-06-15T20:09:24.339012Z","end":"2026-06-15T20:09:24.470559Z","steps":["trace[232080620] 'agreement among raft nodes before linearized reading' (duration: 128.993129ms)"],"step_count":1}