{"level":"warn","ts":"2026-05-30T09:49:59.077068Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-30T09:49:59.077347Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.157:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.157:2380","--initial-cluster=instance=https://199.204.45.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.157: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-05-30T09:49:59.077417Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-30T09:49:59.077424Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-05-30T09:49:59.077442Z","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-05-30T09:49:59.078122Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"]} {"level":"info","ts":"2026-05-30T09:49:59.078238Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.157: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-05-30T09:49:59.084045Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.444415ms"} {"level":"info","ts":"2026-05-30T09:49:59.099318Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f170c75a1d97cc","cluster-id":"44a3176ec85feb9"} {"level":"info","ts":"2026-05-30T09:49:59.099412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=()"} {"level":"info","ts":"2026-05-30T09:49:59.099438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 0"} {"level":"info","ts":"2026-05-30T09:49:59.09945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f170c75a1d97cc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-30T09:49:59.099482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 1"} {"level":"info","ts":"2026-05-30T09:49:59.099527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"warn","ts":"2026-05-30T09:49:59.108437Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-30T09:49:59.110474Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-30T09:49:59.111956Z","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-05-30T09:49:59.113801Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f170c75a1d97cc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-30T09:49:59.113935Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f170c75a1d97cc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-30T09:49:59.114104Z","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-05-30T09:49:59.114206Z","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-05-30T09:49:59.114232Z","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-05-30T09:49:59.116464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"info","ts":"2026-05-30T09:49:59.117972Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","added-peer-id":"5f170c75a1d97cc","added-peer-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-05-30T09:49:59.119079Z","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-05-30T09:49:59.119251Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-05-30T09:49:59.119314Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f170c75a1d97cc","initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-30T09:49:59.119346Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-30T09:49:59.119299Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-05-30T09:49:59.700702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc is starting a new election at term 1"} {"level":"info","ts":"2026-05-30T09:49:59.700768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became pre-candidate at term 1"} {"level":"info","ts":"2026-05-30T09:49:59.700804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgPreVoteResp from 5f170c75a1d97cc at term 1"} {"level":"info","ts":"2026-05-30T09:49:59.700823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became candidate at term 2"} {"level":"info","ts":"2026-05-30T09:49:59.700897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgVoteResp from 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-05-30T09:49:59.700917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became leader at term 2"} {"level":"info","ts":"2026-05-30T09:49:59.700971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f170c75a1d97cc elected leader 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-05-30T09:49:59.702149Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:49:59.703079Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f170c75a1d97cc","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.157:2379]}","request-path":"/0/members/5f170c75a1d97cc/attributes","cluster-id":"44a3176ec85feb9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-30T09:49:59.703092Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T09:49:59.703146Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T09:49:59.703861Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:49:59.70408Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:49:59.704176Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:49:59.704285Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-30T09:49:59.704315Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-30T09:49:59.705743Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.157:2379"} {"level":"info","ts":"2026-05-30T09:49:59.70643Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-30T09:50:06.499924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.910878ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:50:06.500174Z","caller":"traceutil/trace.go:171","msg":"trace[692392707] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"576.628352ms","start":"2026-05-30T09:50:05.92349Z","end":"2026-05-30T09:50:06.500118Z","steps":["trace[692392707] 'process raft request' (duration: 212.374717ms)","trace[692392707] 'compare' (duration: 362.708142ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:50:06.50042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:05.923465Z","time spent":"576.86739ms","remote":"127.0.0.1:47828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T09:50:07.005841Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291834456751599,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-30T09:50:07.244484Z","caller":"traceutil/trace.go:171","msg":"trace[1827574418] linearizableReadLoop","detail":"{readStateIndex:340; appliedIndex:339; }","duration":"739.490384ms","start":"2026-05-30T09:50:06.50497Z","end":"2026-05-30T09:50:07.24446Z","steps":["trace[1827574418] 'read index received' (duration: 739.36997ms)","trace[1827574418] 'applied index is now lower than readState.Index' (duration: 116.444µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:50:07.244568Z","caller":"traceutil/trace.go:171","msg":"trace[114071491] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"740.44497ms","start":"2026-05-30T09:50:06.504092Z","end":"2026-05-30T09:50:07.244537Z","steps":["trace[114071491] 'process raft request' (duration: 740.203793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:07.244669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.677409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-30T09:50:07.244707Z","caller":"traceutil/trace.go:171","msg":"trace[1284877898] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:329; }","duration":"739.774881ms","start":"2026-05-30T09:50:06.50492Z","end":"2026-05-30T09:50:07.244695Z","steps":["trace[1284877898] 'agreement among raft nodes before linearized reading' (duration: 739.681018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:07.244748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:06.504898Z","time spent":"739.838083ms","remote":"127.0.0.1:47746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-30T09:50:07.244798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.903143ms","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-05-30T09:50:07.244906Z","caller":"traceutil/trace.go:171","msg":"trace[449884330] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:329; }","duration":"252.055168ms","start":"2026-05-30T09:50:06.992817Z","end":"2026-05-30T09:50:07.244872Z","steps":["trace[449884330] 'agreement among raft nodes before linearized reading' (duration: 251.865843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:07.244923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:06.504071Z","time spent":"740.577364ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:50:07.436931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.785715ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:50:07.437142Z","caller":"traceutil/trace.go:171","msg":"trace[654117420] linearizableReadLoop","detail":"{readStateIndex:342; appliedIndex:340; }","duration":"122.582391ms","start":"2026-05-30T09:50:07.314542Z","end":"2026-05-30T09:50:07.437125Z","steps":["trace[654117420] 'read index received' (duration: 116.50104ms)","trace[654117420] 'applied index is now lower than readState.Index' (duration: 6.080151ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:50:07.437225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.682524ms","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-05-30T09:50:07.437226Z","caller":"traceutil/trace.go:171","msg":"trace[784611651] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"188.010789ms","start":"2026-05-30T09:50:07.249177Z","end":"2026-05-30T09:50:07.437188Z","steps":["trace[784611651] 'process raft request' (duration: 57.894754ms)","trace[784611651] 'compare' (duration: 129.664402ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:50:07.437273Z","caller":"traceutil/trace.go:171","msg":"trace[957684600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:331; }","duration":"122.733075ms","start":"2026-05-30T09:50:07.314527Z","end":"2026-05-30T09:50:07.43726Z","steps":["trace[957684600] 'agreement among raft nodes before linearized reading' (duration: 122.657253ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:07.437428Z","caller":"traceutil/trace.go:171","msg":"trace[380963243] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"188.146633ms","start":"2026-05-30T09:50:07.249272Z","end":"2026-05-30T09:50:07.437419Z","steps":["trace[380963243] 'process raft request' (duration: 187.773011ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:35.798077Z","caller":"traceutil/trace.go:171","msg":"trace[1262529096] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:540; }","duration":"205.214434ms","start":"2026-05-30T09:50:35.592838Z","end":"2026-05-30T09:50:35.798053Z","steps":["trace[1262529096] 'read index received' (duration: 204.978578ms)","trace[1262529096] 'applied index is now lower than readState.Index' (duration: 234.766µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:50:35.7982Z","caller":"traceutil/trace.go:171","msg":"trace[254429972] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"431.447094ms","start":"2026-05-30T09:50:35.36674Z","end":"2026-05-30T09:50:35.798187Z","steps":["trace[254429972] 'process raft request' (duration: 431.149526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:35.79841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:35.366718Z","time spent":"431.558917ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:50:35.79855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.383366ms","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-05-30T09:50:35.798572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.71791ms","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-05-30T09:50:35.798622Z","caller":"traceutil/trace.go:171","msg":"trace[317425216] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:523; }","duration":"201.48808ms","start":"2026-05-30T09:50:35.597117Z","end":"2026-05-30T09:50:35.798605Z","steps":["trace[317425216] 'agreement among raft nodes before linearized reading' (duration: 201.334076ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:35.798651Z","caller":"traceutil/trace.go:171","msg":"trace[1879827493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:523; }","duration":"205.860543ms","start":"2026-05-30T09:50:35.592773Z","end":"2026-05-30T09:50:35.798633Z","steps":["trace[1879827493] 'agreement among raft nodes before linearized reading' (duration: 205.703709ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:36.61665Z","caller":"traceutil/trace.go:171","msg":"trace[1917777976] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"105.783167ms","start":"2026-05-30T09:50:36.510838Z","end":"2026-05-30T09:50:36.616621Z","steps":["trace[1917777976] 'process raft request' (duration: 46.76388ms)","trace[1917777976] 'compare' (duration: 58.804431ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:50:36.811801Z","caller":"traceutil/trace.go:171","msg":"trace[627207474] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"135.069305ms","start":"2026-05-30T09:50:36.676688Z","end":"2026-05-30T09:50:36.811757Z","steps":["trace[627207474] 'process raft request' (duration: 125.6997ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:36.812555Z","caller":"traceutil/trace.go:171","msg":"trace[1131896931] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"105.232382ms","start":"2026-05-30T09:50:36.707286Z","end":"2026-05-30T09:50:36.812518Z","steps":["trace[1131896931] 'process raft request' (duration: 104.414748ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:42.048619Z","caller":"traceutil/trace.go:171","msg":"trace[434360822] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"219.767201ms","start":"2026-05-30T09:50:41.828826Z","end":"2026-05-30T09:50:42.048593Z","steps":["trace[434360822] 'process raft request' (duration: 219.607296ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:42.049215Z","caller":"traceutil/trace.go:171","msg":"trace[747348524] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:650; }","duration":"219.631397ms","start":"2026-05-30T09:50:41.829571Z","end":"2026-05-30T09:50:42.049202Z","steps":["trace[747348524] 'read index received' (duration: 219.625597ms)","trace[747348524] 'applied index is now lower than readState.Index' (duration: 4.82µs)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:50:42.0494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.814182ms","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-05-30T09:50:42.049442Z","caller":"traceutil/trace.go:171","msg":"trace[1432440889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:626; }","duration":"219.895075ms","start":"2026-05-30T09:50:41.829536Z","end":"2026-05-30T09:50:42.049431Z","steps":["trace[1432440889] 'agreement among raft nodes before linearized reading' (duration: 219.753092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:42.549842Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291834456752251,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-30T09:50:42.676291Z","caller":"traceutil/trace.go:171","msg":"trace[1921170797] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"816.746092ms","start":"2026-05-30T09:50:41.859521Z","end":"2026-05-30T09:50:42.676267Z","steps":["trace[1921170797] 'process raft request' (duration: 816.220267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:42.67645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:41.85949Z","time spent":"816.868546ms","remote":"127.0.0.1:47740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:50:42.72666Z","caller":"traceutil/trace.go:171","msg":"trace[1158722722] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:650; }","duration":"677.379642ms","start":"2026-05-30T09:50:42.049263Z","end":"2026-05-30T09:50:42.726643Z","steps":["trace[1158722722] 'read index received' (duration: 626.125161ms)","trace[1158722722] 'applied index is now lower than readState.Index' (duration: 51.253981ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:50:42.726771Z","caller":"traceutil/trace.go:171","msg":"trace[1421486563] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"670.422946ms","start":"2026-05-30T09:50:42.056313Z","end":"2026-05-30T09:50:42.726736Z","steps":["trace[1421486563] 'process raft request' (duration: 627.615613ms)","trace[1421486563] 'compare' (duration: 42.636609ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:50:42.726847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.730938ms","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-05-30T09:50:42.726882Z","caller":"traceutil/trace.go:171","msg":"trace[1272497234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"882.803001ms","start":"2026-05-30T09:50:41.844064Z","end":"2026-05-30T09:50:42.726867Z","steps":["trace[1272497234] 'agreement among raft nodes before linearized reading' (duration: 882.669216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:42.726911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:41.844049Z","time spent":"882.854111ms","remote":"127.0.0.1:47886","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-05-30T09:50:42.727032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.233568ms","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-05-30T09:50:42.727056Z","caller":"traceutil/trace.go:171","msg":"trace[556096700] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"896.281519ms","start":"2026-05-30T09:50:41.830768Z","end":"2026-05-30T09:50:42.727049Z","steps":["trace[556096700] 'agreement among raft nodes before linearized reading' (duration: 896.221428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:42.727075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:41.830754Z","time spent":"896.31536ms","remote":"127.0.0.1:47886","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-05-30T09:50:42.727081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:42.056292Z","time spent":"670.55278ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:50:42.727114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.791495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wv625\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-30T09:50:42.727317Z","caller":"traceutil/trace.go:171","msg":"trace[1317976671] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wv625; range_end:; response_count:1; response_revision:628; }","duration":"288.035122ms","start":"2026-05-30T09:50:42.439262Z","end":"2026-05-30T09:50:42.727297Z","steps":["trace[1317976671] 'agreement among raft nodes before linearized reading' (duration: 287.687371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:42.727269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.369106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-30T09:50:42.727753Z","caller":"traceutil/trace.go:171","msg":"trace[836637585] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:628; }","duration":"125.889991ms","start":"2026-05-30T09:50:42.601847Z","end":"2026-05-30T09:50:42.727737Z","steps":["trace[836637585] 'agreement among raft nodes before linearized reading' (duration: 125.181061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:43.424378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.542662ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:50:43.424629Z","caller":"traceutil/trace.go:171","msg":"trace[1591172111] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"696.623283ms","start":"2026-05-30T09:50:42.727969Z","end":"2026-05-30T09:50:43.424592Z","steps":["trace[1591172111] 'process raft request' (duration: 293.811833ms)","trace[1591172111] 'compare' (duration: 402.412219ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:50:43.424708Z","caller":"traceutil/trace.go:171","msg":"trace[804709328] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:652; }","duration":"370.80886ms","start":"2026-05-30T09:50:43.053889Z","end":"2026-05-30T09:50:43.424698Z","steps":["trace[804709328] 'read index received' (duration: 90.963019ms)","trace[804709328] 'applied index is now lower than readState.Index' (duration: 279.845231ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:50:43.424719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:42.727954Z","time spent":"696.729536ms","remote":"127.0.0.1:47978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-30T09:50:43.42477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.881172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:50:43.424791Z","caller":"traceutil/trace.go:171","msg":"trace[970726092] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"370.942203ms","start":"2026-05-30T09:50:43.053841Z","end":"2026-05-30T09:50:43.424783Z","steps":["trace[970726092] 'agreement among raft nodes before linearized reading' (duration: 370.886952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:43.424813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:43.05382Z","time spent":"370.987745ms","remote":"127.0.0.1:47618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-30T09:50:43.424797Z","caller":"traceutil/trace.go:171","msg":"trace[1294746562] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"694.264408ms","start":"2026-05-30T09:50:42.730498Z","end":"2026-05-30T09:50:43.424762Z","steps":["trace[1294746562] 'process raft request' (duration: 694.044862ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:43.424943Z","caller":"traceutil/trace.go:171","msg":"trace[2063625132] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"689.173244ms","start":"2026-05-30T09:50:42.735761Z","end":"2026-05-30T09:50:43.424935Z","steps":["trace[2063625132] 'process raft request' (duration: 688.872216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:43.425026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:42.735743Z","time spent":"689.225836ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:50:43.425022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:42.730482Z","time spent":"694.405151ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:50:43.425047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:42.728917Z","time spent":"696.126749ms","remote":"127.0.0.1:47672","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-30T09:50:43.4252Z","caller":"traceutil/trace.go:171","msg":"trace[1947066693] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"684.966276ms","start":"2026-05-30T09:50:42.740227Z","end":"2026-05-30T09:50:43.425193Z","steps":["trace[1947066693] 'process raft request' (duration: 684.436911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:43.425244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:42.740198Z","time spent":"685.024917ms","remote":"127.0.0.1:47802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:50:43.928968Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291834456752261,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-30T09:50:44.319743Z","caller":"traceutil/trace.go:171","msg":"trace[1389475952] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"891.681961ms","start":"2026-05-30T09:50:43.42802Z","end":"2026-05-30T09:50:44.319702Z","steps":["trace[1389475952] 'read index received' (duration: 836.350114ms)","trace[1389475952] 'applied index is now lower than readState.Index' (duration: 55.330087ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:50:44.319768Z","caller":"traceutil/trace.go:171","msg":"trace[1958437125] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"893.255674ms","start":"2026-05-30T09:50:43.426473Z","end":"2026-05-30T09:50:44.319728Z","steps":["trace[1958437125] 'process raft request' (duration: 837.944228ms)","trace[1958437125] 'compare' (duration: 55.169942ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:50:44.319928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:43.426459Z","time spent":"893.381397ms","remote":"127.0.0.1:47672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:50:44.319968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.926327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:50:44.320007Z","caller":"traceutil/trace.go:171","msg":"trace[2041832564] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"892.006419ms","start":"2026-05-30T09:50:43.427989Z","end":"2026-05-30T09:50:44.319995Z","steps":["trace[2041832564] 'agreement among raft nodes before linearized reading' (duration: 891.878385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:44.320038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:43.427968Z","time spent":"892.063821ms","remote":"127.0.0.1:47616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-30T09:50:44.320183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.342342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:50:44.320211Z","caller":"traceutil/trace.go:171","msg":"trace[1378313942] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:633; }","duration":"885.395603ms","start":"2026-05-30T09:50:43.434805Z","end":"2026-05-30T09:50:44.320201Z","steps":["trace[1378313942] 'agreement among raft nodes before linearized reading' (duration: 885.355932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:44.320237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:43.43479Z","time spent":"885.440365ms","remote":"127.0.0.1:48146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"warn","ts":"2026-05-30T09:50:44.320503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.550551ms","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-05-30T09:50:44.32054Z","caller":"traceutil/trace.go:171","msg":"trace[813818082] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"264.623673ms","start":"2026-05-30T09:50:44.055907Z","end":"2026-05-30T09:50:44.320531Z","steps":["trace[813818082] 'agreement among raft nodes before linearized reading' (duration: 264.50655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:45.806548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.761108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:50:45.806674Z","caller":"traceutil/trace.go:171","msg":"trace[1584863241] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:658; }","duration":"232.927561ms","start":"2026-05-30T09:50:45.573728Z","end":"2026-05-30T09:50:45.806656Z","steps":["trace[1584863241] 'range keys from in-memory index tree' (duration: 232.673294ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:58.979057Z","caller":"traceutil/trace.go:171","msg":"trace[771867276] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"430.893999ms","start":"2026-05-30T09:50:58.548146Z","end":"2026-05-30T09:50:58.97904Z","steps":["trace[771867276] 'process raft request' (duration: 430.795536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:58.979183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:58.548123Z","time spent":"431.006363ms","remote":"127.0.0.1:47850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:50:58.982323Z","caller":"traceutil/trace.go:171","msg":"trace[514064562] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:824; }","duration":"148.277531ms","start":"2026-05-30T09:50:58.834034Z","end":"2026-05-30T09:50:58.982311Z","steps":["trace[514064562] 'read index received' (duration: 144.982699ms)","trace[514064562] 'applied index is now lower than readState.Index' (duration: 3.294172ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:50:58.98243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.394564ms","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-05-30T09:50:58.982457Z","caller":"traceutil/trace.go:171","msg":"trace[72958151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:796; }","duration":"148.471896ms","start":"2026-05-30T09:50:58.833979Z","end":"2026-05-30T09:50:58.982451Z","steps":["trace[72958151] 'agreement among raft nodes before linearized reading' (duration: 148.376664ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:35.531585Z","caller":"traceutil/trace.go:171","msg":"trace[125124016] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1053; }","duration":"477.102859ms","start":"2026-05-30T09:51:35.054461Z","end":"2026-05-30T09:51:35.531564Z","steps":["trace[125124016] 'read index received' (duration: 476.905484ms)","trace[125124016] 'applied index is now lower than readState.Index' (duration: 196.105µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:51:35.531628Z","caller":"traceutil/trace.go:171","msg":"trace[996258882] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"796.182914ms","start":"2026-05-30T09:51:34.735415Z","end":"2026-05-30T09:51:35.531598Z","steps":["trace[996258882] 'process raft request' (duration: 795.9984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:35.531741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.269153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:51:35.531803Z","caller":"traceutil/trace.go:171","msg":"trace[162360456] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1014; }","duration":"477.380498ms","start":"2026-05-30T09:51:35.054408Z","end":"2026-05-30T09:51:35.531789Z","steps":["trace[162360456] 'agreement among raft nodes before linearized reading' (duration: 477.250934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:35.531834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:35.054385Z","time spent":"477.443209ms","remote":"127.0.0.1:47618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-30T09:51:35.53185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.475529ms","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-05-30T09:51:35.53189Z","caller":"traceutil/trace.go:171","msg":"trace[1069024211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1014; }","duration":"306.561363ms","start":"2026-05-30T09:51:35.225315Z","end":"2026-05-30T09:51:35.531876Z","steps":["trace[1069024211] 'agreement among raft nodes before linearized reading' (duration: 306.375268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:35.531861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:34.735392Z","time spent":"796.392821ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:51:35.531923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:35.225295Z","time spent":"306.620864ms","remote":"127.0.0.1:47886","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-05-30T09:51:36.0949Z","caller":"traceutil/trace.go:171","msg":"trace[1280709992] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1054; }","duration":"457.786266ms","start":"2026-05-30T09:51:35.637099Z","end":"2026-05-30T09:51:36.094885Z","steps":["trace[1280709992] 'read index received' (duration: 375.979545ms)","trace[1280709992] 'applied index is now lower than readState.Index' (duration: 81.805911ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:51:36.094978Z","caller":"traceutil/trace.go:171","msg":"trace[487105987] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"559.259399ms","start":"2026-05-30T09:51:35.535711Z","end":"2026-05-30T09:51:36.094971Z","steps":["trace[487105987] 'process raft request' (duration: 477.355835ms)","trace[487105987] 'compare' (duration: 81.75649ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:51:36.095072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:35.535695Z","time spent":"559.325962ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:51:36.095246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.154136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:51:36.095277Z","caller":"traceutil/trace.go:171","msg":"trace[111867606] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1015; }","duration":"458.222968ms","start":"2026-05-30T09:51:35.637046Z","end":"2026-05-30T09:51:36.095269Z","steps":["trace[111867606] 'agreement among raft nodes before linearized reading' (duration: 458.152107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:36.095298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:35.637023Z","time spent":"458.2697ms","remote":"127.0.0.1:47838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-05-30T09:51:36.097824Z","caller":"traceutil/trace.go:171","msg":"trace[480106997] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"320.577747ms","start":"2026-05-30T09:51:35.777218Z","end":"2026-05-30T09:51:36.097796Z","steps":["trace[480106997] 'process raft request' (duration: 320.35733ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:36.098137Z","caller":"traceutil/trace.go:171","msg":"trace[1062590173] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"160.922867ms","start":"2026-05-30T09:51:35.93719Z","end":"2026-05-30T09:51:36.098113Z","steps":["trace[1062590173] 'process raft request' (duration: 160.510945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:36.098471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:35.777198Z","time spent":"320.889185ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:51:36.0979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.06627ms","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":"info","ts":"2026-05-30T09:51:36.099332Z","caller":"traceutil/trace.go:171","msg":"trace[2032977992] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1017; }","duration":"327.546073ms","start":"2026-05-30T09:51:35.771765Z","end":"2026-05-30T09:51:36.099311Z","steps":["trace[2032977992] 'agreement among raft nodes before linearized reading' (duration: 326.065111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:36.09866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.195945ms","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-05-30T09:51:36.099733Z","caller":"traceutil/trace.go:171","msg":"trace[1952734879] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1017; }","duration":"418.302276ms","start":"2026-05-30T09:51:35.681413Z","end":"2026-05-30T09:51:36.099716Z","steps":["trace[1952734879] 'agreement among raft nodes before linearized reading' (duration: 417.119042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:36.099797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:35.681392Z","time spent":"418.379828ms","remote":"127.0.0.1:47886","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-05-30T09:51:36.100107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:35.771744Z","time spent":"328.335055ms","remote":"127.0.0.1:47772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-05-30T09:51:36.10078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.242931ms","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-05-30T09:51:36.101027Z","caller":"traceutil/trace.go:171","msg":"trace[471557028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1017; }","duration":"441.538249ms","start":"2026-05-30T09:51:35.65947Z","end":"2026-05-30T09:51:36.101008Z","steps":["trace[471557028] 'agreement among raft nodes before linearized reading' (duration: 438.276757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:36.101337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:35.659457Z","time spent":"441.857107ms","remote":"127.0.0.1:47886","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-05-30T09:51:58.669869Z","caller":"traceutil/trace.go:171","msg":"trace[708170181] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"493.403093ms","start":"2026-05-30T09:51:58.176428Z","end":"2026-05-30T09:51:58.669831Z","steps":["trace[708170181] 'process raft request' (duration: 493.183237ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:58.670061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.823426ms","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-05-30T09:51:58.670063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:58.176404Z","time spent":"493.573678ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:51:58.670074Z","caller":"traceutil/trace.go:171","msg":"trace[2055649787] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1132; }","duration":"455.518219ms","start":"2026-05-30T09:51:58.214243Z","end":"2026-05-30T09:51:58.669761Z","steps":["trace[2055649787] 'read index received' (duration: 455.361244ms)","trace[2055649787] 'applied index is now lower than readState.Index' (duration: 155.685µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:51:58.670116Z","caller":"traceutil/trace.go:171","msg":"trace[507767861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1088; }","duration":"455.93136ms","start":"2026-05-30T09:51:58.214172Z","end":"2026-05-30T09:51:58.670103Z","steps":["trace[507767861] 'agreement among raft nodes before linearized reading' (duration: 455.762135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:58.670325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.507637ms","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-05-30T09:51:58.670368Z","caller":"traceutil/trace.go:171","msg":"trace[938711634] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1088; }","duration":"339.60411ms","start":"2026-05-30T09:51:58.330751Z","end":"2026-05-30T09:51:58.670355Z","steps":["trace[938711634] 'agreement among raft nodes before linearized reading' (duration: 339.451046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:58.670406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:58.330721Z","time spent":"339.676893ms","remote":"127.0.0.1:47886","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-05-30T09:51:58.67041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.062016ms","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-05-30T09:51:58.670595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:58.214152Z","time spent":"456.049623ms","remote":"127.0.0.1:47886","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-05-30T09:51:58.670587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.022524ms","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-05-30T09:51:58.670627Z","caller":"traceutil/trace.go:171","msg":"trace[292276211] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1088; }","duration":"276.292882ms","start":"2026-05-30T09:51:58.394313Z","end":"2026-05-30T09:51:58.670606Z","steps":["trace[292276211] 'agreement among raft nodes before linearized reading' (duration: 275.900922ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:58.670671Z","caller":"traceutil/trace.go:171","msg":"trace[1622626990] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1088; }","duration":"202.172138ms","start":"2026-05-30T09:51:58.468483Z","end":"2026-05-30T09:51:58.670655Z","steps":["trace[1622626990] 'agreement among raft nodes before linearized reading' (duration: 201.973722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:58.670496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.356629ms","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-05-30T09:51:58.670722Z","caller":"traceutil/trace.go:171","msg":"trace[55452466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1088; }","duration":"278.615557ms","start":"2026-05-30T09:51:58.392096Z","end":"2026-05-30T09:51:58.670711Z","steps":["trace[55452466] 'agreement among raft nodes before linearized reading' (duration: 278.287608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:59.20166Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291834456753243,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:51:59.687518Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.012404184s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-30T09:51:59.710256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.962896ms","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-05-30T09:51:59.710388Z","caller":"traceutil/trace.go:171","msg":"trace[1073167170] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1088; }","duration":"520.11752ms","start":"2026-05-30T09:51:59.190252Z","end":"2026-05-30T09:51:59.71037Z","steps":["trace[1073167170] 'range keys from in-memory index tree' (duration: 519.937246ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:59.710632Z","caller":"traceutil/trace.go:171","msg":"trace[1562378321] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"1.035552311s","start":"2026-05-30T09:51:58.675028Z","end":"2026-05-30T09:51:59.71058Z","steps":["trace[1562378321] 'process raft request' (duration: 1.012757562s)","trace[1562378321] 'compare' (duration: 22.543462ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:51:59.710799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:58.675002Z","time spent":"1.035709135s","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:51:59.717006Z","caller":"traceutil/trace.go:171","msg":"trace[180838603] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"1.041900247s","start":"2026-05-30T09:51:58.675094Z","end":"2026-05-30T09:51:59.716994Z","steps":["trace[180838603] 'process raft request' (duration: 1.041817955s)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:51:59.717026Z","caller":"traceutil/trace.go:171","msg":"trace[292319718] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"1.041941328s","start":"2026-05-30T09:51:58.675045Z","end":"2026-05-30T09:51:59.716986Z","steps":["trace[292319718] 'process raft request' (duration: 1.041774733s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:59.717103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:58.675088Z","time spent":"1.041963088s","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:51:59.71734Z","caller":"traceutil/trace.go:171","msg":"trace[1836444525] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1133; }","duration":"1.016680911s","start":"2026-05-30T09:51:58.700626Z","end":"2026-05-30T09:51:59.717307Z","steps":["trace[1836444525] 'read index received' (duration: 987.172877ms)","trace[1836444525] 'applied index is now lower than readState.Index' (duration: 29.506174ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:51:59.717377Z","caller":"traceutil/trace.go:171","msg":"trace[1727059532] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"1.041057104s","start":"2026-05-30T09:51:58.676307Z","end":"2026-05-30T09:51:59.717364Z","steps":["trace[1727059532] 'process raft request' (duration: 1.040651443s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:59.717629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:58.676285Z","time spent":"1.04131379s","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:51:59.717393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:58.675026Z","time spent":"1.042272377s","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:51:59.717462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016835576s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:51:59.717827Z","caller":"traceutil/trace.go:171","msg":"trace[2070152871] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1092; }","duration":"1.017244185s","start":"2026-05-30T09:51:58.700574Z","end":"2026-05-30T09:51:59.717818Z","steps":["trace[2070152871] 'agreement among raft nodes before linearized reading' (duration: 1.016854045s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:59.71787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:58.700557Z","time spent":"1.017304677s","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-05-30T09:51:59.718076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.01521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:51:59.718692Z","caller":"traceutil/trace.go:171","msg":"trace[428259476] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1092; }","duration":"938.681609ms","start":"2026-05-30T09:51:58.779995Z","end":"2026-05-30T09:51:59.718676Z","steps":["trace[428259476] 'agreement among raft nodes before linearized reading' (duration: 938.003001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:59.718742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:58.779977Z","time spent":"938.752361ms","remote":"127.0.0.1:47996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-30T09:51:59.718186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.301422ms","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":"info","ts":"2026-05-30T09:51:59.718796Z","caller":"traceutil/trace.go:171","msg":"trace[1137823700] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1092; }","duration":"472.936088ms","start":"2026-05-30T09:51:59.245842Z","end":"2026-05-30T09:51:59.718778Z","steps":["trace[1137823700] 'agreement among raft nodes before linearized reading' (duration: 472.29894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:59.718219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.078193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T09:51:59.718941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:59.24582Z","time spent":"473.052012ms","remote":"127.0.0.1:47974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-05-30T09:51:59.718988Z","caller":"traceutil/trace.go:171","msg":"trace[1954616552] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1092; }","duration":"664.870494ms","start":"2026-05-30T09:51:59.054103Z","end":"2026-05-30T09:51:59.718973Z","steps":["trace[1954616552] 'agreement among raft nodes before linearized reading' (duration: 664.104894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:51:59.719046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:51:59.054082Z","time spent":"664.951748ms","remote":"127.0.0.1:47618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-30T09:52:01.493226Z","caller":"traceutil/trace.go:171","msg":"trace[1789522418] linearizableReadLoop","detail":"{readStateIndex:1139; appliedIndex:1138; }","duration":"438.956061ms","start":"2026-05-30T09:52:01.054242Z","end":"2026-05-30T09:52:01.493198Z","steps":["trace[1789522418] 'read index received' (duration: 438.730594ms)","trace[1789522418] 'applied index is now lower than readState.Index' (duration: 224.087µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:52:01.493355Z","caller":"traceutil/trace.go:171","msg":"trace[1980328642] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"817.958607ms","start":"2026-05-30T09:52:00.675383Z","end":"2026-05-30T09:52:01.493342Z","steps":["trace[1980328642] 'process raft request' (duration: 817.664049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:01.493496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.241288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T09:52:01.493554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.773407ms","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-05-30T09:52:01.493583Z","caller":"traceutil/trace.go:171","msg":"trace[320581342] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1094; }","duration":"439.385183ms","start":"2026-05-30T09:52:01.054179Z","end":"2026-05-30T09:52:01.493564Z","steps":["trace[320581342] 'agreement among raft nodes before linearized reading' (duration: 439.220139ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:01.493608Z","caller":"traceutil/trace.go:171","msg":"trace[2107532048] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1094; }","duration":"326.876468ms","start":"2026-05-30T09:52:01.16672Z","end":"2026-05-30T09:52:01.493597Z","steps":["trace[2107532048] 'agreement among raft nodes before linearized reading' (duration: 326.778825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:01.493612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:00.675357Z","time spent":"818.146622ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:52:01.493655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:01.054153Z","time spent":"439.485686ms","remote":"127.0.0.1:47616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-30T09:52:01.493644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:01.166696Z","time spent":"326.937451ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-05-30T09:52:04.347021Z","caller":"traceutil/trace.go:171","msg":"trace[788113091] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1151; }","duration":"292.666535ms","start":"2026-05-30T09:52:04.05434Z","end":"2026-05-30T09:52:04.347006Z","steps":["trace[788113091] 'read index received' (duration: 292.487659ms)","trace[788113091] 'applied index is now lower than readState.Index' (duration: 177.686µs)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:52:04.347137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.782769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:52:04.347099Z","caller":"traceutil/trace.go:171","msg":"trace[2082254993] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"502.056612ms","start":"2026-05-30T09:52:03.845012Z","end":"2026-05-30T09:52:04.347069Z","steps":["trace[2082254993] 'process raft request' (duration: 501.825596ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:04.347154Z","caller":"traceutil/trace.go:171","msg":"trace[1806456857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1107; }","duration":"292.854019ms","start":"2026-05-30T09:52:04.054295Z","end":"2026-05-30T09:52:04.347149Z","steps":["trace[1806456857] 'agreement among raft nodes before linearized reading' (duration: 292.774927ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:04.347203Z","caller":"traceutil/trace.go:171","msg":"trace[766249225] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"501.756934ms","start":"2026-05-30T09:52:03.845416Z","end":"2026-05-30T09:52:04.347173Z","steps":["trace[766249225] 'process raft request' (duration: 501.559788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:04.347259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:03.844994Z","time spent":"502.182056ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:52:04.347361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:03.845398Z","time spent":"501.877367ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:52:22.815833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.950157ms","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-05-30T09:52:22.815915Z","caller":"traceutil/trace.go:171","msg":"trace[377701801] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1213; }","duration":"362.08081ms","start":"2026-05-30T09:52:22.453815Z","end":"2026-05-30T09:52:22.815896Z","steps":["trace[377701801] 'range keys from in-memory index tree' (duration: 361.772722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:22.815957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:22.453795Z","time spent":"362.152693ms","remote":"127.0.0.1:47886","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-05-30T09:52:22.815989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.123984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-30T09:52:22.81604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017830253s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:52:22.816074Z","caller":"traceutil/trace.go:171","msg":"trace[2587879] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1213; }","duration":"589.257689ms","start":"2026-05-30T09:52:22.226793Z","end":"2026-05-30T09:52:22.816051Z","steps":["trace[2587879] 'count revisions from in-memory index tree' (duration: 589.057443ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:22.816102Z","caller":"traceutil/trace.go:171","msg":"trace[455368453] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1213; }","duration":"1.017932905s","start":"2026-05-30T09:52:21.798154Z","end":"2026-05-30T09:52:22.816087Z","steps":["trace[455368453] 'count revisions from in-memory index tree' (duration: 1.017762711s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:22.816072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.725731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-30T09:52:22.816119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:22.226772Z","time spent":"589.33573ms","remote":"127.0.0.1:48178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-05-30T09:52:22.816135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:21.798139Z","time spent":"1.017988977s","remote":"127.0.0.1:37626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-05-30T09:52:22.816144Z","caller":"traceutil/trace.go:171","msg":"trace[122800476] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1213; }","duration":"365.827023ms","start":"2026-05-30T09:52:22.450303Z","end":"2026-05-30T09:52:22.81613Z","steps":["trace[122800476] 'range keys from in-memory index tree' (duration: 365.586607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:22.816178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:22.450287Z","time spent":"365.882015ms","remote":"127.0.0.1:47886","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-05-30T09:52:22.81623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.937943ms","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-05-30T09:52:22.816309Z","caller":"traceutil/trace.go:171","msg":"trace[797706370] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1213; }","duration":"832.088317ms","start":"2026-05-30T09:52:21.984204Z","end":"2026-05-30T09:52:22.816293Z","steps":["trace[797706370] 'range keys from in-memory index tree' (duration: 831.753629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:22.816337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.638372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-30T09:52:22.816343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:21.984185Z","time spent":"832.152ms","remote":"127.0.0.1:47886","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-05-30T09:52:22.816366Z","caller":"traceutil/trace.go:171","msg":"trace[915062483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1213; }","duration":"745.671973ms","start":"2026-05-30T09:52:22.070685Z","end":"2026-05-30T09:52:22.816356Z","steps":["trace[915062483] 'range keys from in-memory index tree' (duration: 745.53194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:22.816392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:22.070681Z","time spent":"745.706524ms","remote":"127.0.0.1:47886","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-05-30T09:52:22.816397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.269674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-30T09:52:22.816436Z","caller":"traceutil/trace.go:171","msg":"trace[1662466135] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:1213; }","duration":"209.350176ms","start":"2026-05-30T09:52:22.607073Z","end":"2026-05-30T09:52:22.816423Z","steps":["trace[1662466135] 'range keys from in-memory index tree' (duration: 208.532164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:22.816454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.870009ms","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-05-30T09:52:22.816474Z","caller":"traceutil/trace.go:171","msg":"trace[384485955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1213; }","duration":"745.91416ms","start":"2026-05-30T09:52:22.070554Z","end":"2026-05-30T09:52:22.816468Z","steps":["trace[384485955] 'range keys from in-memory index tree' (duration: 745.777876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:22.816488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:22.070541Z","time spent":"745.944372ms","remote":"127.0.0.1:47886","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-05-30T09:52:38.489066Z","caller":"traceutil/trace.go:171","msg":"trace[63582615] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1324; }","duration":"164.571181ms","start":"2026-05-30T09:52:38.324469Z","end":"2026-05-30T09:52:38.48904Z","steps":["trace[63582615] 'read index received' (duration: 149.126525ms)","trace[63582615] 'applied index is now lower than readState.Index' (duration: 15.443346ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:52:38.489109Z","caller":"traceutil/trace.go:171","msg":"trace[1587296465] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"165.000192ms","start":"2026-05-30T09:52:38.324086Z","end":"2026-05-30T09:52:38.489086Z","steps":["trace[1587296465] 'process raft request' (duration: 164.840978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:38.48928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.790727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:52:38.489331Z","caller":"traceutil/trace.go:171","msg":"trace[1030939794] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1271; }","duration":"164.857919ms","start":"2026-05-30T09:52:38.324461Z","end":"2026-05-30T09:52:38.489318Z","steps":["trace[1030939794] 'agreement among raft nodes before linearized reading' (duration: 164.693424ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:38.939039Z","caller":"traceutil/trace.go:171","msg":"trace[1274216108] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"108.930436ms","start":"2026-05-30T09:52:38.830085Z","end":"2026-05-30T09:52:38.939016Z","steps":["trace[1274216108] 'process raft request' (duration: 108.777312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:39.162036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.104941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:52:39.162245Z","caller":"traceutil/trace.go:171","msg":"trace[1882283767] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1328; }","duration":"154.252515ms","start":"2026-05-30T09:52:39.007975Z","end":"2026-05-30T09:52:39.162228Z","steps":["trace[1882283767] 'read index received' (duration: 32.769154ms)","trace[1882283767] 'applied index is now lower than readState.Index' (duration: 121.480741ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:52:39.162349Z","caller":"traceutil/trace.go:171","msg":"trace[1854652887] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"196.942274ms","start":"2026-05-30T09:52:38.965369Z","end":"2026-05-30T09:52:39.162311Z","steps":["trace[1854652887] 'process raft request' (duration: 75.426752ms)","trace[1854652887] 'compare' (duration: 120.983837ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:52:39.162583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.585995ms","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-05-30T09:52:39.16266Z","caller":"traceutil/trace.go:171","msg":"trace[275741219] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1274; }","duration":"154.702968ms","start":"2026-05-30T09:52:39.007939Z","end":"2026-05-30T09:52:39.162642Z","steps":["trace[275741219] 'agreement among raft nodes before linearized reading' (duration: 154.492562ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:39.197916Z","caller":"traceutil/trace.go:171","msg":"trace[550945647] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"141.124463ms","start":"2026-05-30T09:52:39.056753Z","end":"2026-05-30T09:52:39.197878Z","steps":["trace[550945647] 'process raft request' (duration: 140.950978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:39.197929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.732907ms","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-05-30T09:52:39.197953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.609375ms","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-05-30T09:52:39.198001Z","caller":"traceutil/trace.go:171","msg":"trace[2093809016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1275; }","duration":"188.82777ms","start":"2026-05-30T09:52:39.009157Z","end":"2026-05-30T09:52:39.197984Z","steps":["trace[2093809016] 'agreement among raft nodes before linearized reading' (duration: 188.646225ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:39.198006Z","caller":"traceutil/trace.go:171","msg":"trace[1905263242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1275; }","duration":"188.720127ms","start":"2026-05-30T09:52:39.009273Z","end":"2026-05-30T09:52:39.197993Z","steps":["trace[1905263242] 'agreement among raft nodes before linearized reading' (duration: 188.553723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:39.197928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.679455ms","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-05-30T09:52:39.198162Z","caller":"traceutil/trace.go:171","msg":"trace[1779392400] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1275; }","duration":"188.925663ms","start":"2026-05-30T09:52:39.009206Z","end":"2026-05-30T09:52:39.198132Z","steps":["trace[1779392400] 'agreement among raft nodes before linearized reading' (duration: 188.629315ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:41.339221Z","caller":"traceutil/trace.go:171","msg":"trace[1842185760] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1336; }","duration":"132.288799ms","start":"2026-05-30T09:52:41.206908Z","end":"2026-05-30T09:52:41.339197Z","steps":["trace[1842185760] 'read index received' (duration: 131.993922ms)","trace[1842185760] 'applied index is now lower than readState.Index' (duration: 291.847µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:52:41.339386Z","caller":"traceutil/trace.go:171","msg":"trace[1390835295] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"168.342974ms","start":"2026-05-30T09:52:41.171002Z","end":"2026-05-30T09:52:41.339345Z","steps":["trace[1390835295] 'process raft request' (duration: 168.027065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:41.339516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.978139ms","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-05-30T09:52:41.339517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.598398ms","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-05-30T09:52:41.339553Z","caller":"traceutil/trace.go:171","msg":"trace[2102390945] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1282; }","duration":"108.051532ms","start":"2026-05-30T09:52:41.231492Z","end":"2026-05-30T09:52:41.339543Z","steps":["trace[2102390945] 'agreement among raft nodes before linearized reading' (duration: 107.957769ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:41.339566Z","caller":"traceutil/trace.go:171","msg":"trace[765675323] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1282; }","duration":"132.694661ms","start":"2026-05-30T09:52:41.206858Z","end":"2026-05-30T09:52:41.339552Z","steps":["trace[765675323] 'agreement among raft nodes before linearized reading' (duration: 132.501265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:41.943893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.35076ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:52:41.943968Z","caller":"traceutil/trace.go:171","msg":"trace[2035725651] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"598.687088ms","start":"2026-05-30T09:52:41.345267Z","end":"2026-05-30T09:52:41.943954Z","steps":["trace[2035725651] 'process raft request' (duration: 320.205275ms)","trace[2035725651] 'compare' (duration: 278.272388ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:52:41.944023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:41.345246Z","time spent":"598.744679ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:52:42.183676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.423687ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:52:42.184085Z","caller":"traceutil/trace.go:171","msg":"trace[735785631] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1338; }","duration":"129.520344ms","start":"2026-05-30T09:52:42.054548Z","end":"2026-05-30T09:52:42.184068Z","steps":["trace[735785631] 'read index received' (duration: 14.585532ms)","trace[735785631] 'applied index is now lower than readState.Index' (duration: 114.933822ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:52:42.184147Z","caller":"traceutil/trace.go:171","msg":"trace[1870952637] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"838.656148ms","start":"2026-05-30T09:52:41.345464Z","end":"2026-05-30T09:52:42.18412Z","steps":["trace[1870952637] 'process raft request' (duration: 838.448163ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:42.184217Z","caller":"traceutil/trace.go:171","msg":"trace[1570339060] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"838.68243ms","start":"2026-05-30T09:52:41.345524Z","end":"2026-05-30T09:52:42.184207Z","steps":["trace[1570339060] 'process raft request' (duration: 838.468714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:42.184308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:41.34546Z","time spent":"838.753361ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:52:42.184352Z","caller":"traceutil/trace.go:171","msg":"trace[835065667] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"169.952899ms","start":"2026-05-30T09:52:42.014391Z","end":"2026-05-30T09:52:42.184344Z","steps":["trace[835065667] 'process raft request' (duration: 169.64342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:42.184182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.626555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:52:42.184485Z","caller":"traceutil/trace.go:171","msg":"trace[2068834363] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1287; }","duration":"129.959105ms","start":"2026-05-30T09:52:42.054515Z","end":"2026-05-30T09:52:42.184474Z","steps":["trace[2068834363] 'agreement among raft nodes before linearized reading' (duration: 129.611686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:42.184316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:41.345504Z","time spent":"838.776992ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:52:42.184646Z","caller":"traceutil/trace.go:171","msg":"trace[1648389290] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"839.210075ms","start":"2026-05-30T09:52:41.345425Z","end":"2026-05-30T09:52:42.184635Z","steps":["trace[1648389290] 'process raft request' (duration: 698.74344ms)","trace[1648389290] 'compare' (duration: 139.1917ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:52:42.184707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:41.345417Z","time spent":"839.258456ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:52:43.045228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.653677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-30T09:52:43.045321Z","caller":"traceutil/trace.go:171","msg":"trace[1933337578] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:1288; }","duration":"436.80117ms","start":"2026-05-30T09:52:42.608498Z","end":"2026-05-30T09:52:43.045299Z","steps":["trace[1933337578] 'range keys from in-memory index tree' (duration: 436.449012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:43.045376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:42.608468Z","time spent":"436.894504ms","remote":"127.0.0.1:47672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.157\" "} {"level":"info","ts":"2026-05-30T09:52:43.695678Z","caller":"traceutil/trace.go:171","msg":"trace[1562071714] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1344; }","duration":"349.226494ms","start":"2026-05-30T09:52:43.346433Z","end":"2026-05-30T09:52:43.695659Z","steps":["trace[1562071714] 'read index received' (duration: 328.682428ms)","trace[1562071714] 'applied index is now lower than readState.Index' (duration: 20.532736ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:52:43.695712Z","caller":"traceutil/trace.go:171","msg":"trace[1995009106] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"464.042074ms","start":"2026-05-30T09:52:43.231645Z","end":"2026-05-30T09:52:43.695687Z","steps":["trace[1995009106] 'process raft request' (duration: 443.570369ms)","trace[1995009106] 'compare' (duration: 20.260289ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:52:43.695809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:43.231621Z","time spent":"464.136925ms","remote":"127.0.0.1:47672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:52:43.695887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.446221ms","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-05-30T09:52:43.695956Z","caller":"traceutil/trace.go:171","msg":"trace[1648535627] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1289; }","duration":"349.556944ms","start":"2026-05-30T09:52:43.346386Z","end":"2026-05-30T09:52:43.695943Z","steps":["trace[1648535627] 'agreement among raft nodes before linearized reading' (duration: 349.326118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:43.695997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:43.34636Z","time spent":"349.629616ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-30T09:52:44.365127Z","caller":"traceutil/trace.go:171","msg":"trace[1971488153] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"414.25267ms","start":"2026-05-30T09:52:43.950851Z","end":"2026-05-30T09:52:44.365104Z","steps":["trace[1971488153] 'process raft request' (duration: 414.127036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:44.365292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:43.950829Z","time spent":"414.370232ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:52:44.366054Z","caller":"traceutil/trace.go:171","msg":"trace[1799975365] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1347; }","duration":"314.162118ms","start":"2026-05-30T09:52:44.051879Z","end":"2026-05-30T09:52:44.366041Z","steps":["trace[1799975365] 'read index received' (duration: 314.155928ms)","trace[1799975365] 'applied index is now lower than readState.Index' (duration: 5.3µs)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:52:44.366147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.268421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:52:44.36619Z","caller":"traceutil/trace.go:171","msg":"trace[1505949266] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1291; }","duration":"314.338623ms","start":"2026-05-30T09:52:44.051838Z","end":"2026-05-30T09:52:44.366177Z","steps":["trace[1505949266] 'agreement among raft nodes before linearized reading' (duration: 314.275731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:44.366227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:44.051823Z","time spent":"314.395725ms","remote":"127.0.0.1:47618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-30T09:52:44.584725Z","caller":"traceutil/trace.go:171","msg":"trace[511641932] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"394.929266ms","start":"2026-05-30T09:52:44.189756Z","end":"2026-05-30T09:52:44.584686Z","steps":["trace[511641932] 'process raft request' (duration: 302.371613ms)","trace[511641932] 'compare' (duration: 92.41719ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:52:44.584871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.965987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-30T09:52:44.584914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:44.189748Z","time spent":"395.061011ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:52:44.584899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.390339ms","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-05-30T09:52:44.584936Z","caller":"traceutil/trace.go:171","msg":"trace[1218260983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1292; }","duration":"395.083181ms","start":"2026-05-30T09:52:44.189843Z","end":"2026-05-30T09:52:44.584926Z","steps":["trace[1218260983] 'agreement among raft nodes before linearized reading' (duration: 394.860695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:44.584945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.736519ms","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-05-30T09:52:44.584978Z","caller":"traceutil/trace.go:171","msg":"trace[241051820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1292; }","duration":"395.488291ms","start":"2026-05-30T09:52:44.189471Z","end":"2026-05-30T09:52:44.58496Z","steps":["trace[241051820] 'agreement among raft nodes before linearized reading' (duration: 395.260875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:44.584987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:44.189822Z","time spent":"395.156812ms","remote":"127.0.0.1:47886","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-05-30T09:52:44.58502Z","caller":"traceutil/trace.go:171","msg":"trace[1326812637] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1292; }","duration":"395.849891ms","start":"2026-05-30T09:52:44.189154Z","end":"2026-05-30T09:52:44.585004Z","steps":["trace[1326812637] 'agreement among raft nodes before linearized reading' (duration: 395.677227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:44.585027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:44.189466Z","time spent":"395.549044ms","remote":"127.0.0.1:47886","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-05-30T09:52:44.58507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:44.189126Z","time spent":"395.933474ms","remote":"127.0.0.1:47886","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-05-30T09:52:44.584673Z","caller":"traceutil/trace.go:171","msg":"trace[1573395008] linearizableReadLoop","detail":"{readStateIndex:1348; appliedIndex:1347; }","duration":"218.55239ms","start":"2026-05-30T09:52:44.366096Z","end":"2026-05-30T09:52:44.584648Z","steps":["trace[1573395008] 'read index received' (duration: 126.011227ms)","trace[1573395008] 'applied index is now lower than readState.Index' (duration: 92.540213ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:52:44.585197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.964384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:52:44.585373Z","caller":"traceutil/trace.go:171","msg":"trace[1248470546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1292; }","duration":"218.16039ms","start":"2026-05-30T09:52:44.367197Z","end":"2026-05-30T09:52:44.585357Z","steps":["trace[1248470546] 'agreement among raft nodes before linearized reading' (duration: 217.974234ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:50.926443Z","caller":"traceutil/trace.go:171","msg":"trace[2092263964] linearizableReadLoop","detail":"{readStateIndex:1414; appliedIndex:1413; }","duration":"298.065062ms","start":"2026-05-30T09:52:50.62835Z","end":"2026-05-30T09:52:50.926415Z","steps":["trace[2092263964] 'read index received' (duration: 297.823315ms)","trace[2092263964] 'applied index is now lower than readState.Index' (duration: 240.537µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:52:50.926499Z","caller":"traceutil/trace.go:171","msg":"trace[71770707] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"325.616064ms","start":"2026-05-30T09:52:50.600854Z","end":"2026-05-30T09:52:50.92647Z","steps":["trace[71770707] 'process raft request' (duration: 325.370788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:50.926643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:50.600826Z","time spent":"325.742258ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:52:50.926815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.417062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-30T09:52:50.926823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.350004ms","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-05-30T09:52:50.92687Z","caller":"traceutil/trace.go:171","msg":"trace[1711483807] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1357; }","duration":"298.547236ms","start":"2026-05-30T09:52:50.628308Z","end":"2026-05-30T09:52:50.926855Z","steps":["trace[1711483807] 'agreement among raft nodes before linearized reading' (duration: 298.244008ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:50.926891Z","caller":"traceutil/trace.go:171","msg":"trace[1145620758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1357; }","duration":"248.494628ms","start":"2026-05-30T09:52:50.678375Z","end":"2026-05-30T09:52:50.92687Z","steps":["trace[1145620758] 'agreement among raft nodes before linearized reading' (duration: 248.273942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:50.926906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.413319ms","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-05-30T09:52:50.926984Z","caller":"traceutil/trace.go:171","msg":"trace[1797950360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1357; }","duration":"247.547132ms","start":"2026-05-30T09:52:50.679418Z","end":"2026-05-30T09:52:50.926965Z","steps":["trace[1797950360] 'agreement among raft nodes before linearized reading' (duration: 247.288044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:51.479015Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291834456753835,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T09:52:51.702869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.939658ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:52:51.703005Z","caller":"traceutil/trace.go:171","msg":"trace[839583486] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"771.960377ms","start":"2026-05-30T09:52:50.931019Z","end":"2026-05-30T09:52:51.70298Z","steps":["trace[839583486] 'process raft request' (duration: 603.810804ms)","trace[839583486] 'compare' (duration: 167.821744ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:52:51.703126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:50.93099Z","time spent":"772.05582ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:52:51.705736Z","caller":"traceutil/trace.go:171","msg":"trace[2093881482] linearizableReadLoop","detail":"{readStateIndex:1417; appliedIndex:1414; }","duration":"727.594996ms","start":"2026-05-30T09:52:50.978119Z","end":"2026-05-30T09:52:51.705714Z","steps":["trace[2093881482] 'read index received' (duration: 556.721358ms)","trace[2093881482] 'applied index is now lower than readState.Index' (duration: 170.872698ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:52:51.705828Z","caller":"traceutil/trace.go:171","msg":"trace[1815686809] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"774.548369ms","start":"2026-05-30T09:52:50.931247Z","end":"2026-05-30T09:52:51.705795Z","steps":["trace[1815686809] 'process raft request' (duration: 774.332973ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:52:51.705894Z","caller":"traceutil/trace.go:171","msg":"trace[807780755] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"774.532677ms","start":"2026-05-30T09:52:50.931333Z","end":"2026-05-30T09:52:51.705865Z","steps":["trace[807780755] 'process raft request' (duration: 774.340812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:51.706084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:50.931233Z","time spent":"774.728663ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:52:51.706379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.390881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T09:52:51.706633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.295439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-30T09:52:51.706694Z","caller":"traceutil/trace.go:171","msg":"trace[1218592115] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1360; }","duration":"421.393051ms","start":"2026-05-30T09:52:51.285285Z","end":"2026-05-30T09:52:51.706678Z","steps":["trace[1218592115] 'agreement among raft nodes before linearized reading' (duration: 421.204836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:51.706745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:51.285264Z","time spent":"421.471413ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-30T09:52:51.706326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:50.93131Z","time spent":"774.800475ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:52:51.706969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.817428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-w62bc\" ","response":"range_response_count:1 size:12642"} {"level":"info","ts":"2026-05-30T09:52:51.707068Z","caller":"traceutil/trace.go:171","msg":"trace[2002346021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1360; }","duration":"652.462012ms","start":"2026-05-30T09:52:51.05395Z","end":"2026-05-30T09:52:51.706412Z","steps":["trace[2002346021] 'agreement among raft nodes before linearized reading' (duration: 652.388021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:51.707142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:51.053934Z","time spent":"653.196233ms","remote":"127.0.0.1:47618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-30T09:52:51.707142Z","caller":"traceutil/trace.go:171","msg":"trace[837748859] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-w62bc; range_end:; response_count:1; response_revision:1360; }","duration":"728.945743ms","start":"2026-05-30T09:52:50.978069Z","end":"2026-05-30T09:52:51.707015Z","steps":["trace[837748859] 'agreement among raft nodes before linearized reading' (duration: 728.417709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:52:51.707492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:52:50.978049Z","time spent":"729.315202ms","remote":"127.0.0.1:47802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":12664,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-w62bc\" "} {"level":"info","ts":"2026-05-30T09:53:02.709983Z","caller":"traceutil/trace.go:171","msg":"trace[1898762295] linearizableReadLoop","detail":"{readStateIndex:1475; appliedIndex:1474; }","duration":"101.178874ms","start":"2026-05-30T09:53:02.608782Z","end":"2026-05-30T09:53:02.709961Z","steps":["trace[1898762295] 'read index received' (duration: 101.027609ms)","trace[1898762295] 'applied index is now lower than readState.Index' (duration: 150.325µs)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:53:02.710179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.376789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-30T09:53:02.710207Z","caller":"traceutil/trace.go:171","msg":"trace[1434903647] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"101.485033ms","start":"2026-05-30T09:53:02.608704Z","end":"2026-05-30T09:53:02.710189Z","steps":["trace[1434903647] 'process raft request' (duration: 101.119123ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:02.710233Z","caller":"traceutil/trace.go:171","msg":"trace[507631571] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:1416; }","duration":"101.481812ms","start":"2026-05-30T09:53:02.608736Z","end":"2026-05-30T09:53:02.710218Z","steps":["trace[507631571] 'agreement among raft nodes before linearized reading' (duration: 101.319848ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:53:50.203892Z","caller":"traceutil/trace.go:171","msg":"trace[768646999] transaction","detail":"{read_only:false; response_revision:1634; number_of_response:1; }","duration":"105.973755ms","start":"2026-05-30T09:53:50.097899Z","end":"2026-05-30T09:53:50.203872Z","steps":["trace[768646999] 'process raft request' (duration: 103.397764ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:56:36.452683Z","caller":"traceutil/trace.go:171","msg":"trace[367785705] transaction","detail":"{read_only:false; response_revision:2417; number_of_response:1; }","duration":"547.789924ms","start":"2026-05-30T09:56:35.904869Z","end":"2026-05-30T09:56:36.452659Z","steps":["trace[367785705] 'process raft request' (duration: 547.651391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:56:36.452829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:56:35.904849Z","time spent":"547.906658ms","remote":"127.0.0.1:47886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T09:56:45.578323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.759031ms","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-05-30T09:56:45.578398Z","caller":"traceutil/trace.go:171","msg":"trace[1586469405] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2464; }","duration":"176.871715ms","start":"2026-05-30T09:56:45.401509Z","end":"2026-05-30T09:56:45.578381Z","steps":["trace[1586469405] 'range keys from in-memory index tree' (duration: 176.612747ms)"],"step_count":1}