{"level":"warn","ts":"2026-03-04T11:06:40.897342Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-04T11:06:40.897423Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.9:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.9:2380","--initial-cluster=instance=https://199.19.213.9:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.9:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.9: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-03-04T11:06:40.897483Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-04T11:06:40.897496Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.9:2380"]} {"level":"info","ts":"2026-03-04T11:06:40.897512Z","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-03-04T11:06:40.898097Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.9:2379"]} {"level":"info","ts":"2026-03-04T11:06:40.898167Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.9:2380"],"listen-peer-urls":["https://199.19.213.9:2380"],"advertise-client-urls":["https://199.19.213.9:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.9:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.9: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-03-04T11:06:40.902044Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.695358ms"} {"level":"info","ts":"2026-03-04T11:06:40.90796Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3a0c9d65f167b31b","cluster-id":"86e523b598166645"} {"level":"info","ts":"2026-03-04T11:06:40.908091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b switched to configuration voters=()"} {"level":"info","ts":"2026-03-04T11:06:40.908127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became follower at term 0"} {"level":"info","ts":"2026-03-04T11:06:40.90815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3a0c9d65f167b31b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-04T11:06:40.908166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became follower at term 1"} {"level":"info","ts":"2026-03-04T11:06:40.908238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b switched to configuration voters=(4182891215087711003)"} {"level":"warn","ts":"2026-03-04T11:06:40.918201Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-04T11:06:40.920359Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-04T11:06:40.921723Z","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-03-04T11:06:40.924449Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3a0c9d65f167b31b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-04T11:06:40.924688Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3a0c9d65f167b31b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-04T11:06:40.92498Z","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-03-04T11:06:40.925052Z","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-03-04T11:06:40.92509Z","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-03-04T11:06:40.925796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b switched to configuration voters=(4182891215087711003)"} {"level":"info","ts":"2026-03-04T11:06:40.932666Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"86e523b598166645","local-member-id":"3a0c9d65f167b31b","added-peer-id":"3a0c9d65f167b31b","added-peer-peer-urls":["https://199.19.213.9:2380"]} {"level":"info","ts":"2026-03-04T11:06:40.936503Z","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-03-04T11:06:40.936736Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.9:2380"} {"level":"info","ts":"2026-03-04T11:06:40.937016Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.9:2380"} {"level":"info","ts":"2026-03-04T11:06:40.938221Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3a0c9d65f167b31b","initial-advertise-peer-urls":["https://199.19.213.9:2380"],"listen-peer-urls":["https://199.19.213.9:2380"],"advertise-client-urls":["https://199.19.213.9:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.9:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-04T11:06:40.938273Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-04T11:06:41.708836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b is starting a new election at term 1"} {"level":"info","ts":"2026-03-04T11:06:41.708888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became pre-candidate at term 1"} {"level":"info","ts":"2026-03-04T11:06:41.70891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b received MsgPreVoteResp from 3a0c9d65f167b31b at term 1"} {"level":"info","ts":"2026-03-04T11:06:41.708923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became candidate at term 2"} {"level":"info","ts":"2026-03-04T11:06:41.708929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b received MsgVoteResp from 3a0c9d65f167b31b at term 2"} {"level":"info","ts":"2026-03-04T11:06:41.708937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became leader at term 2"} {"level":"info","ts":"2026-03-04T11:06:41.708959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3a0c9d65f167b31b elected leader 3a0c9d65f167b31b at term 2"} {"level":"info","ts":"2026-03-04T11:06:41.709718Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3a0c9d65f167b31b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.9:2379]}","request-path":"/0/members/3a0c9d65f167b31b/attributes","cluster-id":"86e523b598166645","publish-timeout":"7s"} {"level":"info","ts":"2026-03-04T11:06:41.709744Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-04T11:06:41.709805Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-04T11:06:41.709817Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T11:06:41.70994Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-04T11:06:41.709971Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-04T11:06:41.710994Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"86e523b598166645","local-member-id":"3a0c9d65f167b31b","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T11:06:41.711207Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T11:06:41.711251Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-04T11:06:41.714341Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.9:2379"} {"level":"info","ts":"2026-03-04T11:06:41.720286Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-04T11:06:50.054121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.218804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-04T11:06:50.054309Z","caller":"traceutil/trace.go:171","msg":"trace[1839396993] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:296; }","duration":"132.440339ms","start":"2026-03-04T11:06:49.921821Z","end":"2026-03-04T11:06:50.054261Z","steps":["trace[1839396993] 'range keys from in-memory index tree' (duration: 132.128742ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T11:06:53.972603Z","caller":"traceutil/trace.go:171","msg":"trace[257811249] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:371; }","duration":"114.473251ms","start":"2026-03-04T11:06:53.858098Z","end":"2026-03-04T11:06:53.972572Z","steps":["trace[257811249] 'read index received' (duration: 114.293317ms)","trace[257811249] 'applied index is now lower than readState.Index' (duration: 174.834µs)"],"step_count":2} {"level":"warn","ts":"2026-03-04T11:06:53.972792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.664815ms","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-03-04T11:06:53.972714Z","caller":"traceutil/trace.go:171","msg":"trace[705646138] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"256.424758ms","start":"2026-03-04T11:06:53.716236Z","end":"2026-03-04T11:06:53.972661Z","steps":["trace[705646138] 'process raft request' (duration: 256.130941ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T11:06:53.972835Z","caller":"traceutil/trace.go:171","msg":"trace[1412332085] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:361; }","duration":"114.790988ms","start":"2026-03-04T11:06:53.858032Z","end":"2026-03-04T11:06:53.972823Z","steps":["trace[1412332085] 'agreement among raft nodes before linearized reading' (duration: 114.670545ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:06:59.764948Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12906081473518210973,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-04T11:06:59.830041Z","caller":"traceutil/trace.go:171","msg":"trace[111958840] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:420; }","duration":"565.650245ms","start":"2026-03-04T11:06:59.264362Z","end":"2026-03-04T11:06:59.830012Z","steps":["trace[111958840] 'read index received' (duration: 565.43625ms)","trace[111958840] 'applied index is now lower than readState.Index' (duration: 212.745µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T11:06:59.830143Z","caller":"traceutil/trace.go:171","msg":"trace[1648743877] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"572.661041ms","start":"2026-03-04T11:06:59.257468Z","end":"2026-03-04T11:06:59.830129Z","steps":["trace[1648743877] 'process raft request' (duration: 572.406266ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:06:59.830211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.824008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-04T11:06:59.830246Z","caller":"traceutil/trace.go:171","msg":"trace[332508508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:407; }","duration":"565.914071ms","start":"2026-03-04T11:06:59.26432Z","end":"2026-03-04T11:06:59.830234Z","steps":["trace[332508508] 'agreement among raft nodes before linearized reading' (duration: 565.822049ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:06:59.830291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T11:06:59.264301Z","time spent":"565.975892ms","remote":"127.0.0.1:55040","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-03-04T11:06:59.830372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.108431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T11:06:59.830434Z","caller":"traceutil/trace.go:171","msg":"trace[458050350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:407; }","duration":"233.228833ms","start":"2026-03-04T11:06:59.597187Z","end":"2026-03-04T11:06:59.830416Z","steps":["trace[458050350] 'agreement among raft nodes before linearized reading' (duration: 233.126411ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:06:59.830402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.580188ms","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-03-04T11:06:59.830529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T11:06:59.257455Z","time spent":"572.719493ms","remote":"127.0.0.1:55040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T11:06:59.830529Z","caller":"traceutil/trace.go:171","msg":"trace[503067590] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:407; }","duration":"359.785303ms","start":"2026-03-04T11:06:59.470708Z","end":"2026-03-04T11:06:59.830494Z","steps":["trace[503067590] 'agreement among raft nodes before linearized reading' (duration: 359.509716ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:06:59.830597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T11:06:59.470659Z","time spent":"359.926576ms","remote":"127.0.0.1:55040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-04T11:07:00.038517Z","caller":"traceutil/trace.go:171","msg":"trace[426401930] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"203.776252ms","start":"2026-03-04T11:06:59.834712Z","end":"2026-03-04T11:07:00.038488Z","steps":["trace[426401930] 'process raft request' (duration: 199.889229ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T11:07:00.038811Z","caller":"traceutil/trace.go:171","msg":"trace[1675949853] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"204.007147ms","start":"2026-03-04T11:06:59.834759Z","end":"2026-03-04T11:07:00.038766Z","steps":["trace[1675949853] 'process raft request' (duration: 203.113666ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T11:07:00.312744Z","caller":"traceutil/trace.go:171","msg":"trace[554939943] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"243.52141ms","start":"2026-03-04T11:07:00.069195Z","end":"2026-03-04T11:07:00.312716Z","steps":["trace[554939943] 'process raft request' (duration: 243.358966ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:07:12.61558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.061325ms","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-03-04T11:07:12.615755Z","caller":"traceutil/trace.go:171","msg":"trace[735671514] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:470; }","duration":"242.29001ms","start":"2026-03-04T11:07:12.373441Z","end":"2026-03-04T11:07:12.615731Z","steps":["trace[735671514] 'range keys from in-memory index tree' (duration: 241.793389ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T11:07:12.782783Z","caller":"traceutil/trace.go:171","msg":"trace[1279302486] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"162.453435ms","start":"2026-03-04T11:07:12.620308Z","end":"2026-03-04T11:07:12.782762Z","steps":["trace[1279302486] 'process raft request' (duration: 162.309902ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T11:07:16.36339Z","caller":"traceutil/trace.go:171","msg":"trace[337912210] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:496; }","duration":"188.418576ms","start":"2026-03-04T11:07:16.174947Z","end":"2026-03-04T11:07:16.363366Z","steps":["trace[337912210] 'read index received' (duration: 188.230612ms)","trace[337912210] 'applied index is now lower than readState.Index' (duration: 187.044µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T11:07:16.363506Z","caller":"traceutil/trace.go:171","msg":"trace[2102642243] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"358.731909ms","start":"2026-03-04T11:07:16.004763Z","end":"2026-03-04T11:07:16.363495Z","steps":["trace[2102642243] 'process raft request' (duration: 358.445593ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:07:16.363599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T11:07:16.004714Z","time spent":"358.811991ms","remote":"127.0.0.1:55040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T11:07:16.36375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.941144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-04T11:07:16.363795Z","caller":"traceutil/trace.go:171","msg":"trace[501389076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:479; }","duration":"187.998605ms","start":"2026-03-04T11:07:16.175787Z","end":"2026-03-04T11:07:16.363785Z","steps":["trace[501389076] 'agreement among raft nodes before linearized reading' (duration: 187.876613ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:07:16.363887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.940349ms","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-03-04T11:07:16.363902Z","caller":"traceutil/trace.go:171","msg":"trace[1316121073] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:479; }","duration":"188.99352ms","start":"2026-03-04T11:07:16.174903Z","end":"2026-03-04T11:07:16.363897Z","steps":["trace[1316121073] 'agreement among raft nodes before linearized reading' (duration: 188.944129ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:07:19.32621Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12906081473518211161,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-04T11:07:19.389749Z","caller":"traceutil/trace.go:171","msg":"trace[870301954] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:507; }","duration":"564.489199ms","start":"2026-03-04T11:07:18.825229Z","end":"2026-03-04T11:07:19.389718Z","steps":["trace[870301954] 'read index received' (duration: 561.668931ms)","trace[870301954] 'applied index is now lower than readState.Index' (duration: 2.819598ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T11:07:19.389819Z","caller":"traceutil/trace.go:171","msg":"trace[912472926] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"570.818399ms","start":"2026-03-04T11:07:18.818959Z","end":"2026-03-04T11:07:19.389777Z","steps":["trace[912472926] 'process raft request' (duration: 568.887092ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:07:19.390018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T11:07:18.818933Z","time spent":"570.981002ms","remote":"127.0.0.1:55040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T11:07:19.390708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.457151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nb55m\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-03-04T11:07:19.390792Z","caller":"traceutil/trace.go:171","msg":"trace[256889522] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nb55m; range_end:; response_count:1; response_revision:490; }","duration":"565.602324ms","start":"2026-03-04T11:07:18.82517Z","end":"2026-03-04T11:07:19.390772Z","steps":["trace[256889522] 'agreement among raft nodes before linearized reading' (duration: 565.325178ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:07:19.390825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T11:07:18.825146Z","time spent":"565.672186ms","remote":"127.0.0.1:54946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16980,"request content":"key:\"/registry/pods/kube-system/cilium-nb55m\" "} {"level":"info","ts":"2026-03-04T11:07:19.539849Z","caller":"traceutil/trace.go:171","msg":"trace[1393998845] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"135.733788ms","start":"2026-03-04T11:07:19.40408Z","end":"2026-03-04T11:07:19.539814Z","steps":["trace[1393998845] 'process raft request' (duration: 112.153136ms)","trace[1393998845] 'compare' (duration: 23.314296ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T11:07:20.588068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.98232ms","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-03-04T11:07:20.58823Z","caller":"traceutil/trace.go:171","msg":"trace[1918624610] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:491; }","duration":"464.159784ms","start":"2026-03-04T11:07:20.124043Z","end":"2026-03-04T11:07:20.588202Z","steps":["trace[1918624610] 'range keys from in-memory index tree' (duration: 463.95653ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T11:07:20.67776Z","caller":"traceutil/trace.go:171","msg":"trace[325712376] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:509; }","duration":"301.184766ms","start":"2026-03-04T11:07:20.376542Z","end":"2026-03-04T11:07:20.677727Z","steps":["trace[325712376] 'read index received' (duration: 204.571121ms)","trace[325712376] 'applied index is now lower than readState.Index' (duration: 96.611785ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T11:07:20.678098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.896885ms","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-03-04T11:07:20.678164Z","caller":"traceutil/trace.go:171","msg":"trace[1324318942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:491; }","duration":"294.986338ms","start":"2026-03-04T11:07:20.383146Z","end":"2026-03-04T11:07:20.678132Z","steps":["trace[1324318942] 'agreement among raft nodes before linearized reading' (duration: 294.759323ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:07:20.678137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.565395ms","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-03-04T11:07:20.6782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.700418ms","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-03-04T11:07:20.678262Z","caller":"traceutil/trace.go:171","msg":"trace[204334005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:491; }","duration":"301.7488ms","start":"2026-03-04T11:07:20.376486Z","end":"2026-03-04T11:07:20.678235Z","steps":["trace[204334005] 'agreement among raft nodes before linearized reading' (duration: 301.389401ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T11:07:20.678292Z","caller":"traceutil/trace.go:171","msg":"trace[1938427611] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:491; }","duration":"293.854481ms","start":"2026-03-04T11:07:20.384416Z","end":"2026-03-04T11:07:20.67827Z","steps":["trace[1938427611] 'agreement among raft nodes before linearized reading' (duration: 293.578045ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:07:20.678324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T11:07:20.376462Z","time spent":"301.851873ms","remote":"127.0.0.1:55040","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-03-04T11:07:40.018163Z","caller":"traceutil/trace.go:171","msg":"trace[807520002] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"521.396441ms","start":"2026-03-04T11:07:39.496739Z","end":"2026-03-04T11:07:40.018135Z","steps":["trace[807520002] 'process raft request' (duration: 521.246167ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:07:40.018335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T11:07:39.49672Z","time spent":"521.530585ms","remote":"127.0.0.1:55040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T11:07:40.157551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.289456ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-04T11:07:40.157685Z","caller":"traceutil/trace.go:171","msg":"trace[501234543] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:705; }","duration":"117.737238ms","start":"2026-03-04T11:07:40.039918Z","end":"2026-03-04T11:07:40.157655Z","steps":["trace[501234543] 'read index received' (duration: 43.161µs)","trace[501234543] 'applied index is now lower than readState.Index' (duration: 117.692517ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T11:07:40.157902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.963583ms","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-03-04T11:07:40.15797Z","caller":"traceutil/trace.go:171","msg":"trace[506931134] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:681; }","duration":"118.094816ms","start":"2026-03-04T11:07:40.03986Z","end":"2026-03-04T11:07:40.157955Z","steps":["trace[506931134] 'agreement among raft nodes before linearized reading' (duration: 117.85538ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:07:41.199092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.654156ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T11:07:41.199283Z","caller":"traceutil/trace.go:171","msg":"trace[880491494] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"341.745063ms","start":"2026-03-04T11:07:40.85752Z","end":"2026-03-04T11:07:41.199265Z","steps":["trace[880491494] 'process raft request' (duration: 341.686351ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T11:07:41.199287Z","caller":"traceutil/trace.go:171","msg":"trace[1639383027] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"352.685615ms","start":"2026-03-04T11:07:40.846575Z","end":"2026-03-04T11:07:41.199261Z","steps":["trace[1639383027] 'process raft request' (duration: 32.767873ms)","trace[1639383027] 'compare' (duration: 319.481042ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T11:07:41.199364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T11:07:40.846549Z","time spent":"352.780846ms","remote":"127.0.0.1:55040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T11:07:41.199367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T11:07:40.857498Z","time spent":"341.832834ms","remote":"127.0.0.1:55040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T11:08:05.441018Z","caller":"traceutil/trace.go:171","msg":"trace[1115841870] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:787; }","duration":"126.11588ms","start":"2026-03-04T11:08:05.314872Z","end":"2026-03-04T11:08:05.440988Z","steps":["trace[1115841870] 'read index received' (duration: 95.776059ms)","trace[1115841870] 'applied index is now lower than readState.Index' (duration: 30.338351ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T11:08:05.441153Z","caller":"traceutil/trace.go:171","msg":"trace[1276063719] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"359.112561ms","start":"2026-03-04T11:08:05.082027Z","end":"2026-03-04T11:08:05.44114Z","steps":["trace[1276063719] 'process raft request' (duration: 328.697808ms)","trace[1276063719] 'compare' (duration: 29.966372ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T11:08:05.441287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T11:08:05.082001Z","time spent":"359.180272ms","remote":"127.0.0.1:55040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T11:08:05.441329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.583604ms","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-03-04T11:08:05.441418Z","caller":"traceutil/trace.go:171","msg":"trace[1797366244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:757; }","duration":"122.716897ms","start":"2026-03-04T11:08:05.318675Z","end":"2026-03-04T11:08:05.441392Z","steps":["trace[1797366244] 'agreement among raft nodes before linearized reading' (duration: 122.503992ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:08:05.44161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.719075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-04T11:08:05.441741Z","caller":"traceutil/trace.go:171","msg":"trace[17660048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:757; }","duration":"126.90015ms","start":"2026-03-04T11:08:05.314808Z","end":"2026-03-04T11:08:05.441708Z","steps":["trace[17660048] 'agreement among raft nodes before linearized reading' (duration: 126.529091ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T11:08:06.472184Z","caller":"traceutil/trace.go:171","msg":"trace[1854848674] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:790; }","duration":"187.482609ms","start":"2026-03-04T11:08:06.284672Z","end":"2026-03-04T11:08:06.472155Z","steps":["trace[1854848674] 'read index received' (duration: 187.387887ms)","trace[1854848674] 'applied index is now lower than readState.Index' (duration: 93.352µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T11:08:06.472261Z","caller":"traceutil/trace.go:171","msg":"trace[587202369] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"339.330328ms","start":"2026-03-04T11:08:06.132893Z","end":"2026-03-04T11:08:06.472224Z","steps":["trace[587202369] 'process raft request' (duration: 339.082041ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:08:06.472439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T11:08:06.132837Z","time spent":"339.500111ms","remote":"127.0.0.1:55040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T11:08:06.472439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.744935ms","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-03-04T11:08:06.472507Z","caller":"traceutil/trace.go:171","msg":"trace[1603091388] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:760; }","duration":"187.887919ms","start":"2026-03-04T11:08:06.284602Z","end":"2026-03-04T11:08:06.47249Z","steps":["trace[1603091388] 'agreement among raft nodes before linearized reading' (duration: 187.660244ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:08:06.823046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.536624ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T11:08:06.823163Z","caller":"traceutil/trace.go:171","msg":"trace[2092928131] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:791; }","duration":"226.101192ms","start":"2026-03-04T11:08:06.597047Z","end":"2026-03-04T11:08:06.823148Z","steps":["trace[2092928131] 'read index received' (duration: 53.328392ms)","trace[2092928131] 'applied index is now lower than readState.Index' (duration: 172.76488ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T11:08:06.823214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.181915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T11:08:06.823235Z","caller":"traceutil/trace.go:171","msg":"trace[1656205194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"226.251426ms","start":"2026-03-04T11:08:06.596978Z","end":"2026-03-04T11:08:06.823229Z","steps":["trace[1656205194] 'agreement among raft nodes before linearized reading' (duration: 226.212675ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T11:08:06.823391Z","caller":"traceutil/trace.go:171","msg":"trace[1021755691] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"345.550258ms","start":"2026-03-04T11:08:06.477831Z","end":"2026-03-04T11:08:06.823381Z","steps":["trace[1021755691] 'process raft request' (duration: 172.601434ms)","trace[1021755691] 'compare' (duration: 172.406731ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T11:08:06.823436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T11:08:06.477802Z","time spent":"345.61215ms","remote":"127.0.0.1:55040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T11:08:07.788758Z","caller":"traceutil/trace.go:171","msg":"trace[1197763917] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"260.38299ms","start":"2026-03-04T11:08:07.528348Z","end":"2026-03-04T11:08:07.788731Z","steps":["trace[1197763917] 'process raft request' (duration: 260.221896ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T11:08:07.795366Z","caller":"traceutil/trace.go:171","msg":"trace[1564914284] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"264.162801ms","start":"2026-03-04T11:08:07.531167Z","end":"2026-03-04T11:08:07.79533Z","steps":["trace[1564914284] 'process raft request' (duration: 263.830243ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:08:20.965782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.247492ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-04T11:08:20.968185Z","caller":"traceutil/trace.go:171","msg":"trace[169932512] linearizableReadLoop","detail":"{readStateIndex:885; appliedIndex:884; }","duration":"816.368936ms","start":"2026-03-04T11:08:20.151779Z","end":"2026-03-04T11:08:20.968148Z","steps":["trace[169932512] 'read index received' (duration: 408.69261ms)","trace[169932512] 'applied index is now lower than readState.Index' (duration: 407.672856ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T11:08:20.968417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.577173ms","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-03-04T11:08:20.968551Z","caller":"traceutil/trace.go:171","msg":"trace[1104233845] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:849; }","duration":"816.771947ms","start":"2026-03-04T11:08:20.151752Z","end":"2026-03-04T11:08:20.968524Z","steps":["trace[1104233845] 'agreement among raft nodes before linearized reading' (duration: 816.4826ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:08:20.968611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T11:08:20.151741Z","time spent":"816.858519ms","remote":"127.0.0.1:55208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-03-04T11:08:20.968596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.602354ms","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-03-04T11:08:20.968694Z","caller":"traceutil/trace.go:171","msg":"trace[407200324] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:849; }","duration":"389.769279ms","start":"2026-03-04T11:08:20.578903Z","end":"2026-03-04T11:08:20.968673Z","steps":["trace[407200324] 'agreement among raft nodes before linearized reading' (duration: 389.488163ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:08:20.968745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T11:08:20.578841Z","time spent":"389.895392ms","remote":"127.0.0.1:55040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-04T11:08:20.969092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.313537ms","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":"info","ts":"2026-03-04T11:08:20.969142Z","caller":"traceutil/trace.go:171","msg":"trace[396598213] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:849; }","duration":"375.375608ms","start":"2026-03-04T11:08:20.593752Z","end":"2026-03-04T11:08:20.969128Z","steps":["trace[396598213] 'agreement among raft nodes before linearized reading' (duration: 375.280035ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:08:20.969183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T11:08:20.593746Z","time spent":"375.426819ms","remote":"127.0.0.1:55328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-03-04T11:08:31.184378Z","caller":"traceutil/trace.go:171","msg":"trace[1555871945] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"166.674241ms","start":"2026-03-04T11:08:31.017669Z","end":"2026-03-04T11:08:31.184344Z","steps":["trace[1555871945] 'process raft request' (duration: 166.434175ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T11:08:31.185084Z","caller":"traceutil/trace.go:171","msg":"trace[1136581773] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"165.080952ms","start":"2026-03-04T11:08:31.019984Z","end":"2026-03-04T11:08:31.185065Z","steps":["trace[1136581773] 'process raft request' (duration: 164.917438ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T11:08:34.11848Z","caller":"traceutil/trace.go:171","msg":"trace[109770516] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"191.253301ms","start":"2026-03-04T11:08:33.927201Z","end":"2026-03-04T11:08:34.118455Z","steps":["trace[109770516] 'process raft request' (duration: 191.107748ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T11:08:34.119634Z","caller":"traceutil/trace.go:171","msg":"trace[83493710] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"191.743463ms","start":"2026-03-04T11:08:33.927868Z","end":"2026-03-04T11:08:34.119611Z","steps":["trace[83493710] 'process raft request' (duration: 191.61609ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T11:08:37.463324Z","caller":"traceutil/trace.go:171","msg":"trace[246500703] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:953; }","duration":"253.8829ms","start":"2026-03-04T11:08:37.209417Z","end":"2026-03-04T11:08:37.4633Z","steps":["trace[246500703] 'read index received' (duration: 253.643744ms)","trace[246500703] 'applied index is now lower than readState.Index' (duration: 238.006µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T11:08:37.4634Z","caller":"traceutil/trace.go:171","msg":"trace[1996763327] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"253.998233ms","start":"2026-03-04T11:08:37.209378Z","end":"2026-03-04T11:08:37.463377Z","steps":["trace[1996763327] 'process raft request' (duration: 253.797538ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:08:37.463557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.118426ms","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-03-04T11:08:37.463601Z","caller":"traceutil/trace.go:171","msg":"trace[549215402] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:915; }","duration":"254.205769ms","start":"2026-03-04T11:08:37.209384Z","end":"2026-03-04T11:08:37.46359Z","steps":["trace[549215402] 'agreement among raft nodes before linearized reading' (duration: 254.053715ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:08:37.463707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.902482ms","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-03-04T11:08:37.463805Z","caller":"traceutil/trace.go:171","msg":"trace[1256692667] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:915; }","duration":"254.026945ms","start":"2026-03-04T11:08:37.209736Z","end":"2026-03-04T11:08:37.463763Z","steps":["trace[1256692667] 'agreement among raft nodes before linearized reading' (duration: 253.765448ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:08:37.719691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.491412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-04T11:08:37.720542Z","caller":"traceutil/trace.go:171","msg":"trace[1162927959] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"247.077984ms","start":"2026-03-04T11:08:37.472817Z","end":"2026-03-04T11:08:37.719895Z","steps":["trace[1162927959] 'process raft request' (duration: 115.929021ms)","trace[1162927959] 'compare' (duration: 120.353489ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T11:08:37.720682Z","caller":"traceutil/trace.go:171","msg":"trace[176697481] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"245.862075ms","start":"2026-03-04T11:08:37.474711Z","end":"2026-03-04T11:08:37.720573Z","steps":["trace[176697481] 'process raft request' (duration: 245.066675ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T11:08:38.25756Z","caller":"traceutil/trace.go:171","msg":"trace[1672739073] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"119.619021ms","start":"2026-03-04T11:08:38.137921Z","end":"2026-03-04T11:08:38.25754Z","steps":["trace[1672739073] 'process raft request' (duration: 119.542839ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:08:38.580712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.410842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T11:08:38.580832Z","caller":"traceutil/trace.go:171","msg":"trace[815652248] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:921; }","duration":"197.566395ms","start":"2026-03-04T11:08:38.383228Z","end":"2026-03-04T11:08:38.580794Z","steps":["trace[815652248] 'range keys from in-memory index tree' (duration: 197.338309ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T11:08:38.883586Z","caller":"traceutil/trace.go:171","msg":"trace[1669631950] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:960; }","duration":"289.969122ms","start":"2026-03-04T11:08:38.593594Z","end":"2026-03-04T11:08:38.883563Z","steps":["trace[1669631950] 'read index received' (duration: 289.87845ms)","trace[1669631950] 'applied index is now lower than readState.Index' (duration: 89.312µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T11:08:38.883647Z","caller":"traceutil/trace.go:171","msg":"trace[48008996] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"298.116501ms","start":"2026-03-04T11:08:38.585507Z","end":"2026-03-04T11:08:38.883624Z","steps":["trace[48008996] 'process raft request' (duration: 297.938037ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:08:38.883738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.131946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T11:08:38.883774Z","caller":"traceutil/trace.go:171","msg":"trace[69615095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:922; }","duration":"290.209598ms","start":"2026-03-04T11:08:38.593554Z","end":"2026-03-04T11:08:38.883764Z","steps":["trace[69615095] 'agreement among raft nodes before linearized reading' (duration: 290.112226ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T11:08:38.981658Z","caller":"traceutil/trace.go:171","msg":"trace[1974072595] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"167.596114ms","start":"2026-03-04T11:08:38.814004Z","end":"2026-03-04T11:08:38.9816Z","steps":["trace[1974072595] 'process raft request' (duration: 167.377108ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T11:08:39.413809Z","caller":"traceutil/trace.go:171","msg":"trace[1672720403] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"523.71954ms","start":"2026-03-04T11:08:38.890061Z","end":"2026-03-04T11:08:39.413781Z","steps":["trace[1672720403] 'process raft request' (duration: 523.502965ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:08:39.413998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T11:08:38.890054Z","time spent":"523.842124ms","remote":"127.0.0.1:54872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-04T11:08:39.444762Z","caller":"traceutil/trace.go:171","msg":"trace[517023668] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"460.068686ms","start":"2026-03-04T11:08:38.984681Z","end":"2026-03-04T11:08:39.444749Z","steps":["trace[517023668] 'process raft request' (duration: 459.871322ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:08:39.444824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T11:08:38.984656Z","time spent":"460.137028ms","remote":"127.0.0.1:54872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":835,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-04T11:08:39.444806Z","caller":"traceutil/trace.go:171","msg":"trace[1721941442] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"346.761628ms","start":"2026-03-04T11:08:39.098021Z","end":"2026-03-04T11:08:39.444783Z","steps":["trace[1721941442] 'process raft request' (duration: 346.719487ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:08:39.444944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T11:08:39.097996Z","time spent":"346.873971ms","remote":"127.0.0.1:55040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T11:08:39.44509Z","caller":"traceutil/trace.go:171","msg":"trace[612053228] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"459.475152ms","start":"2026-03-04T11:08:38.985605Z","end":"2026-03-04T11:08:39.445081Z","steps":["trace[612053228] 'process raft request' (duration: 459.075392ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:08:39.445147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T11:08:38.985591Z","time spent":"459.527313ms","remote":"127.0.0.1:55040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T11:08:39.58141Z","caller":"traceutil/trace.go:171","msg":"trace[2053111068] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"111.066692ms","start":"2026-03-04T11:08:39.470313Z","end":"2026-03-04T11:08:39.58138Z","steps":["trace[2053111068] 'process raft request' (duration: 47.226433ms)","trace[2053111068] 'compare' (duration: 63.716436ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T11:08:40.308748Z","caller":"traceutil/trace.go:171","msg":"trace[171504607] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:969; }","duration":"112.19119ms","start":"2026-03-04T11:08:40.196535Z","end":"2026-03-04T11:08:40.308726Z","steps":["trace[171504607] 'read index received' (duration: 61.165544ms)","trace[171504607] 'applied index is now lower than readState.Index' (duration: 51.024556ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T11:08:40.308954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.402424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-04T11:08:40.30899Z","caller":"traceutil/trace.go:171","msg":"trace[700756858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:930; }","duration":"112.477686ms","start":"2026-03-04T11:08:40.196499Z","end":"2026-03-04T11:08:40.308977Z","steps":["trace[700756858] 'agreement among raft nodes before linearized reading' (duration: 112.343663ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T11:08:50.013283Z","caller":"traceutil/trace.go:171","msg":"trace[243189336] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"145.072013ms","start":"2026-03-04T11:08:49.868181Z","end":"2026-03-04T11:08:50.013253Z","steps":["trace[243189336] 'read index received' (duration: 144.853778ms)","trace[243189336] 'applied index is now lower than readState.Index' (duration: 216.815µs)"],"step_count":2} {"level":"info","ts":"2026-03-04T11:08:50.013397Z","caller":"traceutil/trace.go:171","msg":"trace[1681005208] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"374.696191ms","start":"2026-03-04T11:08:49.638667Z","end":"2026-03-04T11:08:50.013363Z","steps":["trace[1681005208] 'process raft request' (duration: 374.398794ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:08:50.013632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.431372ms","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-03-04T11:08:50.013674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.265165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-04T11:08:50.013711Z","caller":"traceutil/trace.go:171","msg":"trace[1021340025] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:997; }","duration":"145.585236ms","start":"2026-03-04T11:08:49.868109Z","end":"2026-03-04T11:08:50.013695Z","steps":["trace[1021340025] 'agreement among raft nodes before linearized reading' (duration: 145.277979ms)"],"step_count":1} {"level":"info","ts":"2026-03-04T11:08:50.01372Z","caller":"traceutil/trace.go:171","msg":"trace[79724047] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:997; }","duration":"115.330428ms","start":"2026-03-04T11:08:49.898377Z","end":"2026-03-04T11:08:50.013708Z","steps":["trace[79724047] 'agreement among raft nodes before linearized reading' (duration: 115.03605ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:08:50.01386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T11:08:49.638654Z","time spent":"374.965047ms","remote":"127.0.0.1:55040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-04T11:08:50.12889Z","caller":"traceutil/trace.go:171","msg":"trace[211563317] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"110.255413ms","start":"2026-03-04T11:08:50.018574Z","end":"2026-03-04T11:08:50.128829Z","steps":["trace[211563317] 'process raft request' (duration: 96.247841ms)","trace[211563317] 'compare' (duration: 13.735265ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T11:08:50.129132Z","caller":"traceutil/trace.go:171","msg":"trace[1092447827] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"110.203071ms","start":"2026-03-04T11:08:50.018911Z","end":"2026-03-04T11:08:50.129114Z","steps":["trace[1092447827] 'process raft request' (duration: 109.75061ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:08:50.278088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.132832ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-04T11:08:50.881296Z","caller":"traceutil/trace.go:171","msg":"trace[887847368] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1043; }","duration":"285.354809ms","start":"2026-03-04T11:08:50.595911Z","end":"2026-03-04T11:08:50.881266Z","steps":["trace[887847368] 'read index received' (duration: 246.929981ms)","trace[887847368] 'applied index is now lower than readState.Index' (duration: 38.423398ms)"],"step_count":2} {"level":"info","ts":"2026-03-04T11:08:50.881439Z","caller":"traceutil/trace.go:171","msg":"trace[479435648] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"519.585569ms","start":"2026-03-04T11:08:50.361839Z","end":"2026-03-04T11:08:50.881425Z","steps":["trace[479435648] 'process raft request' (duration: 480.960396ms)","trace[479435648] 'compare' (duration: 38.198022ms)"],"step_count":2} {"level":"warn","ts":"2026-03-04T11:08:50.881533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.622126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-04T11:08:50.881561Z","caller":"traceutil/trace.go:171","msg":"trace[1088551967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1002; }","duration":"285.708368ms","start":"2026-03-04T11:08:50.595846Z","end":"2026-03-04T11:08:50.881554Z","steps":["trace[1088551967] 'agreement among raft nodes before linearized reading' (duration: 285.647826ms)"],"step_count":1} {"level":"warn","ts":"2026-03-04T11:08:50.881607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-04T11:08:50.361821Z","time spent":"519.717172ms","remote":"127.0.0.1:55040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-04T11:08:51.402003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.5555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-03-04T11:08:51.402088Z","caller":"traceutil/trace.go:171","msg":"trace[37417172] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1002; }","duration":"112.702403ms","start":"2026-03-04T11:08:51.289365Z","end":"2026-03-04T11:08:51.402068Z","steps":["trace[37417172] 'range keys from in-memory index tree' (duration: 112.363904ms)"],"step_count":1}