{"level":"warn","ts":"2026-05-07T13:19:25.618079Z","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-07T13:19:25.61817Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.212: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.212:2380","--initial-cluster=instance=https://199.19.213.212:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.212:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.212: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-07T13:19:25.618264Z","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-07T13:19:25.618284Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.212:2380"]} {"level":"info","ts":"2026-05-07T13:19:25.618307Z","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-07T13:19:25.619164Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.212:2379"]} {"level":"info","ts":"2026-05-07T13:19:25.619266Z","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.212:2380"],"listen-peer-urls":["https://199.19.213.212:2380"],"advertise-client-urls":["https://199.19.213.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.212: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-07T13:19:25.623413Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.914095ms"} {"level":"info","ts":"2026-05-07T13:19:25.630183Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e1ab0380b9290bdc","cluster-id":"a3ed131e36ad6843"} {"level":"info","ts":"2026-05-07T13:19:25.630253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1ab0380b9290bdc switched to configuration voters=()"} {"level":"info","ts":"2026-05-07T13:19:25.630281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1ab0380b9290bdc became follower at term 0"} {"level":"info","ts":"2026-05-07T13:19:25.630293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e1ab0380b9290bdc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-07T13:19:25.630302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1ab0380b9290bdc became follower at term 1"} {"level":"info","ts":"2026-05-07T13:19:25.630335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1ab0380b9290bdc switched to configuration voters=(16261094730948479964)"} {"level":"warn","ts":"2026-05-07T13:19:25.636656Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-07T13:19:25.637946Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-07T13:19:25.639208Z","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-07T13:19:25.641937Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e1ab0380b9290bdc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-07T13:19:25.642132Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e1ab0380b9290bdc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-07T13:19:25.64228Z","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-07T13:19:25.642367Z","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-07T13:19:25.642381Z","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-07T13:19:25.643022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1ab0380b9290bdc switched to configuration voters=(16261094730948479964)"} {"level":"info","ts":"2026-05-07T13:19:25.643456Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a3ed131e36ad6843","local-member-id":"e1ab0380b9290bdc","added-peer-id":"e1ab0380b9290bdc","added-peer-peer-urls":["https://199.19.213.212:2380"]} {"level":"info","ts":"2026-05-07T13:19:25.646183Z","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-07T13:19:25.646241Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.212:2380"} {"level":"info","ts":"2026-05-07T13:19:25.646287Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.212:2380"} {"level":"info","ts":"2026-05-07T13:19:25.646489Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e1ab0380b9290bdc","initial-advertise-peer-urls":["https://199.19.213.212:2380"],"listen-peer-urls":["https://199.19.213.212:2380"],"advertise-client-urls":["https://199.19.213.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-07T13:19:25.646549Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-07T13:19:25.830683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1ab0380b9290bdc is starting a new election at term 1"} {"level":"info","ts":"2026-05-07T13:19:25.83075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1ab0380b9290bdc became pre-candidate at term 1"} {"level":"info","ts":"2026-05-07T13:19:25.830812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1ab0380b9290bdc received MsgPreVoteResp from e1ab0380b9290bdc at term 1"} {"level":"info","ts":"2026-05-07T13:19:25.830833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1ab0380b9290bdc became candidate at term 2"} {"level":"info","ts":"2026-05-07T13:19:25.830844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1ab0380b9290bdc received MsgVoteResp from e1ab0380b9290bdc at term 2"} {"level":"info","ts":"2026-05-07T13:19:25.830859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1ab0380b9290bdc became leader at term 2"} {"level":"info","ts":"2026-05-07T13:19:25.830886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e1ab0380b9290bdc elected leader e1ab0380b9290bdc at term 2"} {"level":"info","ts":"2026-05-07T13:19:25.832105Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T13:19:25.8332Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e1ab0380b9290bdc","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.212:2379]}","request-path":"/0/members/e1ab0380b9290bdc/attributes","cluster-id":"a3ed131e36ad6843","publish-timeout":"7s"} {"level":"info","ts":"2026-05-07T13:19:25.833521Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T13:19:25.833572Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T13:19:25.833745Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a3ed131e36ad6843","local-member-id":"e1ab0380b9290bdc","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T13:19:25.833993Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-07T13:19:25.83406Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-07T13:19:25.833999Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T13:19:25.83414Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T13:19:25.836455Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.212:2379"} {"level":"info","ts":"2026-05-07T13:19:25.837391Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-07T13:19:58.680782Z","caller":"traceutil/trace.go:171","msg":"trace[1742983154] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"107.454674ms","start":"2026-05-07T13:19:58.5733Z","end":"2026-05-07T13:19:58.680755Z","steps":["trace[1742983154] 'process raft request' (duration: 107.248449ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:19:58.891455Z","caller":"traceutil/trace.go:171","msg":"trace[1801721674] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:619; }","duration":"238.804491ms","start":"2026-05-07T13:19:58.652571Z","end":"2026-05-07T13:19:58.891375Z","steps":["trace[1801721674] 'read index received' (duration: 28.069918ms)","trace[1801721674] 'applied index is now lower than readState.Index' (duration: 210.732623ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:19:58.891812Z","caller":"traceutil/trace.go:171","msg":"trace[962540821] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"263.289431ms","start":"2026-05-07T13:19:58.628506Z","end":"2026-05-07T13:19:58.891795Z","steps":["trace[962540821] 'process raft request' (duration: 259.312251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:58.891868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.273543ms","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-07T13:19:58.892067Z","caller":"traceutil/trace.go:171","msg":"trace[2140907709] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:600; }","duration":"239.483428ms","start":"2026-05-07T13:19:58.652505Z","end":"2026-05-07T13:19:58.891989Z","steps":["trace[2140907709] 'agreement among raft nodes before linearized reading' (duration: 239.194451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:19:58.892053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.216103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:19:58.892149Z","caller":"traceutil/trace.go:171","msg":"trace[1697784605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"106.363596ms","start":"2026-05-07T13:19:58.785759Z","end":"2026-05-07T13:19:58.892122Z","steps":["trace[1697784605] 'agreement among raft nodes before linearized reading' (duration: 106.179432ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:20:00.469904Z","caller":"traceutil/trace.go:171","msg":"trace[902208698] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"148.731081ms","start":"2026-05-07T13:20:00.321134Z","end":"2026-05-07T13:20:00.469865Z","steps":["trace[902208698] 'process raft request' (duration: 147.805287ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:20:01.1296Z","caller":"traceutil/trace.go:171","msg":"trace[385294832] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"459.169314ms","start":"2026-05-07T13:20:00.670405Z","end":"2026-05-07T13:20:01.129575Z","steps":["trace[385294832] 'read index received' (duration: 448.769515ms)","trace[385294832] 'applied index is now lower than readState.Index' (duration: 10.398969ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:20:01.129786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.365959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hx45b\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-07T13:20:01.129819Z","caller":"traceutil/trace.go:171","msg":"trace[550986680] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hx45b; range_end:; response_count:1; response_revision:614; }","duration":"459.462101ms","start":"2026-05-07T13:20:00.670349Z","end":"2026-05-07T13:20:01.129811Z","steps":["trace[550986680] 'agreement among raft nodes before linearized reading' (duration: 459.349959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:20:01.129848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:20:00.670328Z","time spent":"459.514062ms","remote":"127.0.0.1:39672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-hx45b\" "} {"level":"info","ts":"2026-05-07T13:20:01.129706Z","caller":"traceutil/trace.go:171","msg":"trace[2127039806] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"596.421318ms","start":"2026-05-07T13:20:00.533252Z","end":"2026-05-07T13:20:01.129673Z","steps":["trace[2127039806] 'process raft request' (duration: 585.987378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:20:01.130243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.188084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:20:01.130351Z","caller":"traceutil/trace.go:171","msg":"trace[306073233] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"344.360298ms","start":"2026-05-07T13:20:00.785963Z","end":"2026-05-07T13:20:01.130323Z","steps":["trace[306073233] 'agreement among raft nodes before linearized reading' (duration: 344.183154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:20:01.130414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:20:00.785938Z","time spent":"344.46305ms","remote":"127.0.0.1:39512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T13:20:01.130368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.27853ms","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-07T13:20:01.13059Z","caller":"traceutil/trace.go:171","msg":"trace[480040629] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:614; }","duration":"230.594727ms","start":"2026-05-07T13:20:00.899973Z","end":"2026-05-07T13:20:01.130568Z","steps":["trace[480040629] 'agreement among raft nodes before linearized reading' (duration: 230.158027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:20:01.130999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:20:00.533225Z","time spent":"596.791427ms","remote":"127.0.0.1:39966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T13:20:01.131177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.483994ms","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-07T13:20:01.131282Z","caller":"traceutil/trace.go:171","msg":"trace[406745986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"445.604627ms","start":"2026-05-07T13:20:00.685663Z","end":"2026-05-07T13:20:01.131267Z","steps":["trace[406745986] 'agreement among raft nodes before linearized reading' (duration: 444.423648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:20:01.131365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:20:00.685653Z","time spent":"445.700909ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-07T13:20:57.983522Z","caller":"traceutil/trace.go:171","msg":"trace[1376697466] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"135.93953ms","start":"2026-05-07T13:20:57.847518Z","end":"2026-05-07T13:20:57.983457Z","steps":["trace[1376697466] 'process raft request' (duration: 135.695194ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:20:58.16018Z","caller":"traceutil/trace.go:171","msg":"trace[170594057] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1048; }","duration":"146.468888ms","start":"2026-05-07T13:20:58.013687Z","end":"2026-05-07T13:20:58.160156Z","steps":["trace[170594057] 'read index received' (duration: 143.57841ms)","trace[170594057] 'applied index is now lower than readState.Index' (duration: 2.889188ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:20:58.160237Z","caller":"traceutil/trace.go:171","msg":"trace[130658261] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"160.843287ms","start":"2026-05-07T13:20:57.999367Z","end":"2026-05-07T13:20:58.16021Z","steps":["trace[130658261] 'process raft request' (duration: 157.936109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:20:58.160397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.698724ms","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-07T13:20:58.160434Z","caller":"traceutil/trace.go:171","msg":"trace[1699986471] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1011; }","duration":"146.779276ms","start":"2026-05-07T13:20:58.013642Z","end":"2026-05-07T13:20:58.160422Z","steps":["trace[1699986471] 'agreement among raft nodes before linearized reading' (duration: 146.602552ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:21:19.660222Z","caller":"traceutil/trace.go:171","msg":"trace[514746557] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"134.175769ms","start":"2026-05-07T13:21:19.526004Z","end":"2026-05-07T13:21:19.66018Z","steps":["trace[514746557] 'process raft request' (duration: 133.982254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:21:19.961095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.320314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:21:19.961396Z","caller":"traceutil/trace.go:171","msg":"trace[348870577] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"297.419353ms","start":"2026-05-07T13:21:19.663772Z","end":"2026-05-07T13:21:19.961192Z","steps":["trace[348870577] 'process raft request' (duration: 167.611307ms)","trace[348870577] 'compare' (duration: 129.214412ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:21:20.018647Z","caller":"traceutil/trace.go:171","msg":"trace[352504133] linearizableReadLoop","detail":"{readStateIndex:1126; appliedIndex:1124; }","duration":"234.130682ms","start":"2026-05-07T13:21:19.784498Z","end":"2026-05-07T13:21:20.018628Z","steps":["trace[352504133] 'read index received' (duration: 46.878084ms)","trace[352504133] 'applied index is now lower than readState.Index' (duration: 187.251598ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:21:20.018734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.219794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:21:20.018761Z","caller":"traceutil/trace.go:171","msg":"trace[1925196626] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1083; }","duration":"234.301906ms","start":"2026-05-07T13:21:19.784451Z","end":"2026-05-07T13:21:20.018753Z","steps":["trace[1925196626] 'agreement among raft nodes before linearized reading' (duration: 234.245164ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:21:20.018918Z","caller":"traceutil/trace.go:171","msg":"trace[1054557498] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"336.366599ms","start":"2026-05-07T13:21:19.682544Z","end":"2026-05-07T13:21:20.018911Z","steps":["trace[1054557498] 'process raft request' (duration: 335.98029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:21:20.019013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:21:19.682512Z","time spent":"336.426861ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:21:21.780547Z","caller":"traceutil/trace.go:171","msg":"trace[1455917568] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"111.26541ms","start":"2026-05-07T13:21:21.669174Z","end":"2026-05-07T13:21:21.78044Z","steps":["trace[1455917568] 'process raft request' (duration: 111.033694ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:21:52.165549Z","caller":"traceutil/trace.go:171","msg":"trace[380584744] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"172.567799ms","start":"2026-05-07T13:21:51.992945Z","end":"2026-05-07T13:21:52.165513Z","steps":["trace[380584744] 'process raft request' (duration: 172.378445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:21:52.412902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.14629ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:21:52.412988Z","caller":"traceutil/trace.go:171","msg":"trace[844755190] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1293; }","duration":"197.622485ms","start":"2026-05-07T13:21:52.215352Z","end":"2026-05-07T13:21:52.412975Z","steps":["trace[844755190] 'read index received' (duration: 31.246371ms)","trace[844755190] 'applied index is now lower than readState.Index' (duration: 166.375104ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:21:52.413096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.750508ms","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-07T13:21:52.413118Z","caller":"traceutil/trace.go:171","msg":"trace[452323155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1243; }","duration":"197.797679ms","start":"2026-05-07T13:21:52.215314Z","end":"2026-05-07T13:21:52.413112Z","steps":["trace[452323155] 'agreement among raft nodes before linearized reading' (duration: 197.694877ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:21:52.413125Z","caller":"traceutil/trace.go:171","msg":"trace[1935887034] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"243.648994ms","start":"2026-05-07T13:21:52.169461Z","end":"2026-05-07T13:21:52.41311Z","steps":["trace[1935887034] 'process raft request' (duration: 77.218378ms)","trace[1935887034] 'compare' (duration: 166.059658ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:22:03.754693Z","caller":"traceutil/trace.go:171","msg":"trace[1149428959] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"225.321107ms","start":"2026-05-07T13:22:03.529346Z","end":"2026-05-07T13:22:03.754668Z","steps":["trace[1149428959] 'process raft request' (duration: 225.152593ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:22:04.135063Z","caller":"traceutil/trace.go:171","msg":"trace[1663901501] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"502.332155ms","start":"2026-05-07T13:22:03.632703Z","end":"2026-05-07T13:22:04.135035Z","steps":["trace[1663901501] 'process raft request' (duration: 502.100539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:22:04.135216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:22:03.632694Z","time spent":"502.441287ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:22:04.135363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.760109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:22:04.135446Z","caller":"traceutil/trace.go:171","msg":"trace[1861578011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1343; }","duration":"350.896893ms","start":"2026-05-07T13:22:03.784528Z","end":"2026-05-07T13:22:04.135425Z","steps":["trace[1861578011] 'agreement among raft nodes before linearized reading' (duration: 350.758518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:22:04.135493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:22:03.784502Z","time spent":"350.981095ms","remote":"127.0.0.1:39500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T13:22:04.135021Z","caller":"traceutil/trace.go:171","msg":"trace[1331605882] linearizableReadLoop","detail":"{readStateIndex:1396; appliedIndex:1394; }","duration":"350.39682ms","start":"2026-05-07T13:22:03.784587Z","end":"2026-05-07T13:22:04.134984Z","steps":["trace[1331605882] 'read index received' (duration: 277.171782ms)","trace[1331605882] 'applied index is now lower than readState.Index' (duration: 73.223938ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:22:04.136898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.933079ms","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-07T13:22:04.136883Z","caller":"traceutil/trace.go:171","msg":"trace[517605090] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"507.965425ms","start":"2026-05-07T13:22:03.626915Z","end":"2026-05-07T13:22:04.13488Z","steps":["trace[517605090] 'process raft request' (duration: 434.823629ms)","trace[517605090] 'compare' (duration: 72.764526ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:22:04.136946Z","caller":"traceutil/trace.go:171","msg":"trace[1513676002] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1343; }","duration":"173.037691ms","start":"2026-05-07T13:22:03.963893Z","end":"2026-05-07T13:22:04.136931Z","steps":["trace[1513676002] 'agreement among raft nodes before linearized reading' (duration: 172.869036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:22:04.137009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:22:03.626895Z","time spent":"510.056668ms","remote":"127.0.0.1:39666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6758,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:26.930337Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":854731763268046797,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T13:24:27.279436Z","caller":"traceutil/trace.go:171","msg":"trace[277848017] linearizableReadLoop","detail":"{readStateIndex:2078; appliedIndex:2077; }","duration":"849.756949ms","start":"2026-05-07T13:24:26.429654Z","end":"2026-05-07T13:24:27.279411Z","steps":["trace[277848017] 'read index received' (duration: 829.469642ms)","trace[277848017] 'applied index is now lower than readState.Index' (duration: 20.285977ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:24:27.27956Z","caller":"traceutil/trace.go:171","msg":"trace[2110368769] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"901.539554ms","start":"2026-05-07T13:24:26.378008Z","end":"2026-05-07T13:24:27.279548Z","steps":["trace[2110368769] 'process raft request' (duration: 881.098874ms)","trace[2110368769] 'compare' (duration: 20.181214ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:24:27.279663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:26.377994Z","time spent":"901.592755ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:27.279773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.111197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/eg\" ","response":"range_response_count:1 size:1086"} {"level":"info","ts":"2026-05-07T13:24:27.279853Z","caller":"traceutil/trace.go:171","msg":"trace[1702743464] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/eg; range_end:; response_count:1; response_revision:1996; }","duration":"850.23838ms","start":"2026-05-07T13:24:26.429597Z","end":"2026-05-07T13:24:27.279835Z","steps":["trace[1702743464] 'agreement among raft nodes before linearized reading' (duration: 850.050166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:27.279899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:26.429575Z","time spent":"850.315491ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":1110,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/eg\" "} {"level":"info","ts":"2026-05-07T13:24:27.284886Z","caller":"traceutil/trace.go:171","msg":"trace[1789662675] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"137.851068ms","start":"2026-05-07T13:24:27.14701Z","end":"2026-05-07T13:24:27.284861Z","steps":["trace[1789662675] 'process raft request' (duration: 137.805746ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:27.285123Z","caller":"traceutil/trace.go:171","msg":"trace[1490595371] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"337.182471ms","start":"2026-05-07T13:24:26.947929Z","end":"2026-05-07T13:24:27.285111Z","steps":["trace[1490595371] 'process raft request' (duration: 336.761171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:27.285274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:26.947909Z","time spent":"337.287314ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:27.285482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.29979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-07T13:24:27.285543Z","caller":"traceutil/trace.go:171","msg":"trace[1641594830] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1998; }","duration":"522.396382ms","start":"2026-05-07T13:24:26.763129Z","end":"2026-05-07T13:24:27.285526Z","steps":["trace[1641594830] 'agreement among raft nodes before linearized reading' (duration: 522.223008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:27.285553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.523163ms","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-07T13:24:27.285585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:26.763108Z","time spent":"522.459045ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-07T13:24:27.285596Z","caller":"traceutil/trace.go:171","msg":"trace[1913911590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1998; }","duration":"128.613427ms","start":"2026-05-07T13:24:27.156974Z","end":"2026-05-07T13:24:27.285588Z","steps":["trace[1913911590] 'agreement among raft nodes before linearized reading' (duration: 128.480343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:27.285696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.450114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T13:24:27.28573Z","caller":"traceutil/trace.go:171","msg":"trace[1848310748] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1998; }","duration":"486.508426ms","start":"2026-05-07T13:24:26.799214Z","end":"2026-05-07T13:24:27.285722Z","steps":["trace[1848310748] 'agreement among raft nodes before linearized reading' (duration: 486.443484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:27.285677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.259298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T13:24:27.285767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.431277ms","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-07T13:24:27.285765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:26.799203Z","time spent":"486.553867ms","remote":"127.0.0.1:39920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-05-07T13:24:27.285787Z","caller":"traceutil/trace.go:171","msg":"trace[807574335] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1998; }","duration":"125.511708ms","start":"2026-05-07T13:24:27.160269Z","end":"2026-05-07T13:24:27.285781Z","steps":["trace[807574335] 'agreement among raft nodes before linearized reading' (duration: 125.460827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:27.285768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.522464ms","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-07T13:24:27.285844Z","caller":"traceutil/trace.go:171","msg":"trace[279583146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1998; }","duration":"190.638866ms","start":"2026-05-07T13:24:27.095185Z","end":"2026-05-07T13:24:27.285824Z","steps":["trace[279583146] 'agreement among raft nodes before linearized reading' (duration: 190.3906ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:27.285789Z","caller":"traceutil/trace.go:171","msg":"trace[305656412] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1998; }","duration":"358.530206ms","start":"2026-05-07T13:24:26.927239Z","end":"2026-05-07T13:24:27.285769Z","steps":["trace[305656412] 'agreement among raft nodes before linearized reading' (duration: 358.230058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:27.286013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:26.927217Z","time spent":"358.69106ms","remote":"127.0.0.1:53296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-05-07T13:24:28.053011Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":854731763268046808,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:24:28.554049Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":854731763268046808,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:24:29.054798Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":854731763268046808,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:24:29.345712Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.046615068s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T13:24:29.36417Z","caller":"traceutil/trace.go:171","msg":"trace[74899731] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"2.073654763s","start":"2026-05-07T13:24:27.290464Z","end":"2026-05-07T13:24:29.364119Z","steps":["trace[74899731] 'process raft request' (duration: 2.055496269s)","trace[74899731] 'compare' (duration: 16.710258ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:24:29.364551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:27.290444Z","time spent":"2.073942661s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:29.36647Z","caller":"traceutil/trace.go:171","msg":"trace[1935326110] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"2.075959851s","start":"2026-05-07T13:24:27.290487Z","end":"2026-05-07T13:24:29.366446Z","steps":["trace[1935326110] 'process raft request' (duration: 2.072365971s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:29.36744Z","caller":"traceutil/trace.go:171","msg":"trace[1089906758] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"2.076874585s","start":"2026-05-07T13:24:27.290541Z","end":"2026-05-07T13:24:29.367416Z","steps":["trace[1089906758] 'process raft request' (duration: 2.075709145s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:29.367456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:27.290477Z","time spent":"2.076876954s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:29.36753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:27.290534Z","time spent":"2.076947776s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:29.367795Z","caller":"traceutil/trace.go:171","msg":"trace[1055763018] linearizableReadLoop","detail":"{readStateIndex:2085; appliedIndex:2081; }","duration":"1.815154349s","start":"2026-05-07T13:24:27.552589Z","end":"2026-05-07T13:24:29.367744Z","steps":["trace[1055763018] 'read index received' (duration: 1.793383685s)","trace[1055763018] 'applied index is now lower than readState.Index' (duration: 21.709833ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:24:29.367924Z","caller":"traceutil/trace.go:171","msg":"trace[1113988894] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"2.077033808s","start":"2026-05-07T13:24:27.290845Z","end":"2026-05-07T13:24:29.367879Z","steps":["trace[1113988894] 'process raft request' (duration: 2.07633706s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:29.368071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:27.290835Z","time spent":"2.077156661s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:29.368193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.815597851s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-05-07T13:24:29.368249Z","caller":"traceutil/trace.go:171","msg":"trace[1896530615] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2003; }","duration":"1.815670073s","start":"2026-05-07T13:24:27.552555Z","end":"2026-05-07T13:24:29.368225Z","steps":["trace[1896530615] 'agreement among raft nodes before linearized reading' (duration: 1.815443537s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:29.36837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:27.552539Z","time spent":"1.815740934s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-07T13:24:29.372699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.672559074s","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":"warn","ts":"2026-05-07T13:24:29.372705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102872708s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T13:24:29.372734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.587833485s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:24:29.372745Z","caller":"traceutil/trace.go:171","msg":"trace[841745630] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2004; }","duration":"1.672638156s","start":"2026-05-07T13:24:27.700097Z","end":"2026-05-07T13:24:29.372735Z","steps":["trace[841745630] 'agreement among raft nodes before linearized reading' (duration: 1.672487063s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:29.372712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.728054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.212\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-07T13:24:29.372792Z","caller":"traceutil/trace.go:171","msg":"trace[963698392] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2004; }","duration":"1.587903507s","start":"2026-05-07T13:24:27.784856Z","end":"2026-05-07T13:24:29.372759Z","steps":["trace[963698392] 'agreement among raft nodes before linearized reading' (duration: 1.587843665s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:29.372814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:27.700082Z","time spent":"1.672687427s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-07T13:24:29.372823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:27.78483Z","time spent":"1.587986209s","remote":"127.0.0.1:39500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T13:24:29.372805Z","caller":"traceutil/trace.go:171","msg":"trace[180063400] range","detail":"{range_begin:/registry/masterleases/199.19.213.212; range_end:; response_count:1; response_revision:2004; }","duration":"325.862908ms","start":"2026-05-07T13:24:29.046932Z","end":"2026-05-07T13:24:29.372795Z","steps":["trace[180063400] 'agreement among raft nodes before linearized reading' (duration: 325.675483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:29.372852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.92768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-05-07T13:24:29.372895Z","caller":"traceutil/trace.go:171","msg":"trace[658508010] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2004; }","duration":"333.991722ms","start":"2026-05-07T13:24:29.038891Z","end":"2026-05-07T13:24:29.372883Z","steps":["trace[658508010] 'agreement among raft nodes before linearized reading' (duration: 333.892449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:29.372902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:29.046909Z","time spent":"325.96584ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.212\" "} {"level":"warn","ts":"2026-05-07T13:24:29.373018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.398702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:6772"} {"level":"info","ts":"2026-05-07T13:24:29.373048Z","caller":"traceutil/trace.go:171","msg":"trace[1730896148] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:2004; }","duration":"304.448743ms","start":"2026-05-07T13:24:29.068592Z","end":"2026-05-07T13:24:29.37304Z","steps":["trace[1730896148] 'agreement among raft nodes before linearized reading' (duration: 304.366921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:29.372929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:29.038876Z","time spent":"334.046814ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-07T13:24:29.373072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:29.06858Z","time spent":"304.486364ms","remote":"127.0.0.1:39666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":6796,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-07T13:24:29.372762Z","caller":"traceutil/trace.go:171","msg":"trace[64813696] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:2004; }","duration":"1.10298048s","start":"2026-05-07T13:24:28.269768Z","end":"2026-05-07T13:24:29.372749Z","steps":["trace[64813696] 'agreement among raft nodes before linearized reading' (duration: 1.102877818s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:29.373324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:28.269742Z","time spent":"1.103559095s","remote":"127.0.0.1:39572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-05-07T13:24:29.874362Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":854731763268046811,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:24:31.126498Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.161277068s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-07T13:24:31.138202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.172854988s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:24:31.138515Z","caller":"traceutil/trace.go:171","msg":"trace[1737088200] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"1.764909644s","start":"2026-05-07T13:24:29.373569Z","end":"2026-05-07T13:24:31.138479Z","steps":["trace[1737088200] 'process raft request' (duration: 591.697996ms)","trace[1737088200] 'compare' (duration: 1.172733955s)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:24:31.13891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:29.373552Z","time spent":"1.765267403s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:31.139044Z","caller":"traceutil/trace.go:171","msg":"trace[78308375] transaction","detail":"{read_only:false; response_revision:2007; number_of_response:1; }","duration":"1.762604166s","start":"2026-05-07T13:24:29.376422Z","end":"2026-05-07T13:24:31.139026Z","steps":["trace[78308375] 'process raft request' (duration: 1.762071172s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:31.138566Z","caller":"traceutil/trace.go:171","msg":"trace[1802288138] transaction","detail":"{read_only:false; response_revision:2006; number_of_response:1; }","duration":"1.763205071s","start":"2026-05-07T13:24:29.375338Z","end":"2026-05-07T13:24:31.138543Z","steps":["trace[1802288138] 'process raft request' (duration: 1.763070498s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:31.139124Z","caller":"traceutil/trace.go:171","msg":"trace[6530557] transaction","detail":"{read_only:false; response_revision:2008; number_of_response:1; }","duration":"1.762661827s","start":"2026-05-07T13:24:29.376448Z","end":"2026-05-07T13:24:31.139109Z","steps":["trace[6530557] 'process raft request' (duration: 1.762187854s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:31.139146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:29.375324Z","time spent":"1.763788966s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:31.139162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:29.376416Z","time spent":"1.762683917s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:31.139197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:29.376433Z","time spent":"1.762725349s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:31.13861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:29.374225Z","time spent":"1.764382861s","remote":"127.0.0.1:39526","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-07T13:24:31.138743Z","caller":"traceutil/trace.go:171","msg":"trace[1753950391] linearizableReadLoop","detail":"{readStateIndex:2091; appliedIndex:2086; }","duration":"1.765058356s","start":"2026-05-07T13:24:29.373659Z","end":"2026-05-07T13:24:31.138717Z","steps":["trace[1753950391] 'read index received' (duration: 591.622203ms)","trace[1753950391] 'applied index is now lower than readState.Index' (duration: 1.173435293s)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:24:31.139428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.76038277s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:6772"} {"level":"warn","ts":"2026-05-07T13:24:31.139483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.261563937s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:24:31.139497Z","caller":"traceutil/trace.go:171","msg":"trace[733081952] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:2008; }","duration":"1.760461392s","start":"2026-05-07T13:24:29.379021Z","end":"2026-05-07T13:24:31.139483Z","steps":["trace[733081952] 'agreement among raft nodes before linearized reading' (duration: 1.760303789s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:31.139435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.765180379s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T13:24:31.139539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:29.379015Z","time spent":"1.760515984s","remote":"127.0.0.1:39666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":6796,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-07T13:24:31.139536Z","caller":"traceutil/trace.go:171","msg":"trace[878293491] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:2008; }","duration":"1.261690709s","start":"2026-05-07T13:24:29.87783Z","end":"2026-05-07T13:24:31.139521Z","steps":["trace[878293491] 'agreement among raft nodes before linearized reading' (duration: 1.261604977s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:31.139581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:29.877807Z","time spent":"1.261764281s","remote":"127.0.0.1:39788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-07T13:24:31.139558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013801541s","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-07T13:24:31.139594Z","caller":"traceutil/trace.go:171","msg":"trace[854918053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2008; }","duration":"1.765931878s","start":"2026-05-07T13:24:29.373622Z","end":"2026-05-07T13:24:31.139554Z","steps":["trace[854918053] 'agreement among raft nodes before linearized reading' (duration: 1.765158899s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:31.139638Z","caller":"traceutil/trace.go:171","msg":"trace[167750051] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2008; }","duration":"1.013927924s","start":"2026-05-07T13:24:30.125689Z","end":"2026-05-07T13:24:31.139617Z","steps":["trace[167750051] 'agreement among raft nodes before linearized reading' (duration: 1.013709788s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:31.13971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:30.125658Z","time spent":"1.014042628s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-07T13:24:31.139711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:29.373609Z","time spent":"1.76604291s","remote":"127.0.0.1:39512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T13:24:31.281898Z","caller":"traceutil/trace.go:171","msg":"trace[1703837153] linearizableReadLoop","detail":"{readStateIndex:2092; appliedIndex:2091; }","duration":"134.018881ms","start":"2026-05-07T13:24:31.147853Z","end":"2026-05-07T13:24:31.281872Z","steps":["trace[1703837153] 'read index received' (duration: 127.284173ms)","trace[1703837153] 'applied index is now lower than readState.Index' (duration: 6.733678ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:24:31.281959Z","caller":"traceutil/trace.go:171","msg":"trace[388023052] transaction","detail":"{read_only:false; response_revision:2009; number_of_response:1; }","duration":"139.882907ms","start":"2026-05-07T13:24:31.142008Z","end":"2026-05-07T13:24:31.281891Z","steps":["trace[388023052] 'process raft request' (duration: 133.16332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:31.28215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.283359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:24:31.282225Z","caller":"traceutil/trace.go:171","msg":"trace[603115953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2009; }","duration":"134.395631ms","start":"2026-05-07T13:24:31.147813Z","end":"2026-05-07T13:24:31.282208Z","steps":["trace[603115953] 'agreement among raft nodes before linearized reading' (duration: 134.154385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:32.347131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.004238ms","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-07T13:24:32.347217Z","caller":"traceutil/trace.go:171","msg":"trace[699787849] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2009; }","duration":"563.103691ms","start":"2026-05-07T13:24:31.784102Z","end":"2026-05-07T13:24:32.347206Z","steps":["trace[699787849] 'range keys from in-memory index tree' (duration: 562.992057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:32.347219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.416099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:24:32.347364Z","caller":"traceutil/trace.go:171","msg":"trace[695718264] linearizableReadLoop","detail":"{readStateIndex:2093; appliedIndex:2092; }","duration":"1.06538927s","start":"2026-05-07T13:24:31.281967Z","end":"2026-05-07T13:24:32.347357Z","steps":["trace[695718264] 'read index received' (duration: 435.769936ms)","trace[695718264] 'applied index is now lower than readState.Index' (duration: 629.618424ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:24:32.347372Z","caller":"traceutil/trace.go:171","msg":"trace[979457667] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"971.044851ms","start":"2026-05-07T13:24:31.376311Z","end":"2026-05-07T13:24:32.347355Z","steps":["trace[979457667] 'process raft request' (duration: 970.99344ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:32.347415Z","caller":"traceutil/trace.go:171","msg":"trace[1331920876] transaction","detail":"{read_only:false; response_revision:2010; number_of_response:1; }","duration":"1.199210427s","start":"2026-05-07T13:24:31.148198Z","end":"2026-05-07T13:24:32.347409Z","steps":["trace[1331920876] 'process raft request' (duration: 569.525381ms)","trace[1331920876] 'compare' (duration: 629.312576ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:24:32.347434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:31.376272Z","time spent":"971.131073ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:32.347479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:31.14817Z","time spent":"1.199260478s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:32.347566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064338235s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T13:24:32.347618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.264577ms","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-07T13:24:32.347634Z","caller":"traceutil/trace.go:171","msg":"trace[748820382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2011; }","duration":"973.322139ms","start":"2026-05-07T13:24:31.374307Z","end":"2026-05-07T13:24:32.347629Z","steps":["trace[748820382] 'agreement among raft nodes before linearized reading' (duration: 973.241497ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:32.347632Z","caller":"traceutil/trace.go:171","msg":"trace[952713436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2011; }","duration":"1.064449917s","start":"2026-05-07T13:24:31.283165Z","end":"2026-05-07T13:24:32.347615Z","steps":["trace[952713436] 'agreement among raft nodes before linearized reading' (duration: 1.064343745s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:32.347677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075506863s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-07T13:24:32.347675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:31.283144Z","time spent":"1.064524329s","remote":"127.0.0.1:39512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T13:24:32.347691Z","caller":"traceutil/trace.go:171","msg":"trace[148215053] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:2011; }","duration":"1.075561065s","start":"2026-05-07T13:24:31.272127Z","end":"2026-05-07T13:24:32.347688Z","steps":["trace[148215053] 'agreement among raft nodes before linearized reading' (duration: 1.075530204s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:32.347704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:31.272103Z","time spent":"1.075598166s","remote":"127.0.0.1:39584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":11,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-05-07T13:24:32.347893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.063931235s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-05-07T13:24:32.347925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.597336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-05-07T13:24:32.34794Z","caller":"traceutil/trace.go:171","msg":"trace[1919725929] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2011; }","duration":"1.064005227s","start":"2026-05-07T13:24:31.283924Z","end":"2026-05-07T13:24:32.347929Z","steps":["trace[1919725929] 'agreement among raft nodes before linearized reading' (duration: 1.063925375s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:32.347956Z","caller":"traceutil/trace.go:171","msg":"trace[612696935] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2011; }","duration":"973.661527ms","start":"2026-05-07T13:24:31.374285Z","end":"2026-05-07T13:24:32.347947Z","steps":["trace[612696935] 'agreement among raft nodes before linearized reading' (duration: 973.550035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:32.347976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:31.283905Z","time spent":"1.064064548s","remote":"127.0.0.1:39656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-05-07T13:24:32.347987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:31.374266Z","time spent":"973.712859ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-07T13:24:32.347648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:31.374287Z","time spent":"973.358739ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T13:24:32.348199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.953784ms","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-07T13:24:32.348206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.986815ms","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-07T13:24:32.348235Z","caller":"traceutil/trace.go:171","msg":"trace[686683428] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2011; }","duration":"974.046086ms","start":"2026-05-07T13:24:31.374183Z","end":"2026-05-07T13:24:32.348229Z","steps":["trace[686683428] 'agreement among raft nodes before linearized reading' (duration: 973.983334ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:32.348235Z","caller":"traceutil/trace.go:171","msg":"trace[563579803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2011; }","duration":"973.995345ms","start":"2026-05-07T13:24:31.374231Z","end":"2026-05-07T13:24:32.348226Z","steps":["trace[563579803] 'agreement among raft nodes before linearized reading' (duration: 973.887532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:32.347962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.284703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-07T13:24:32.348255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:31.374166Z","time spent":"974.084897ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T13:24:32.348262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:31.374228Z","time spent":"974.028866ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-07T13:24:32.348273Z","caller":"traceutil/trace.go:171","msg":"trace[1303165654] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2011; }","duration":"159.634182ms","start":"2026-05-07T13:24:32.188627Z","end":"2026-05-07T13:24:32.348261Z","steps":["trace[1303165654] 'agreement among raft nodes before linearized reading' (duration: 159.316454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:33.450336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.042409ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:24:33.4507Z","caller":"traceutil/trace.go:171","msg":"trace[1439702222] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"1.095451603s","start":"2026-05-07T13:24:32.355196Z","end":"2026-05-07T13:24:33.450647Z","steps":["trace[1439702222] 'process raft request' (duration: 363.989073ms)","trace[1439702222] 'compare' (duration: 730.850205ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:24:33.450767Z","caller":"traceutil/trace.go:171","msg":"trace[261655883] linearizableReadLoop","detail":"{readStateIndex:2097; appliedIndex:2094; }","duration":"1.094231242s","start":"2026-05-07T13:24:32.356515Z","end":"2026-05-07T13:24:33.450746Z","steps":["trace[261655883] 'read index received' (duration: 362.6903ms)","trace[261655883] 'applied index is now lower than readState.Index' (duration: 731.539752ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:24:33.450823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:32.355186Z","time spent":"1.095580976s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:33.450892Z","caller":"traceutil/trace.go:171","msg":"trace[1900003432] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"1.095647627s","start":"2026-05-07T13:24:32.355224Z","end":"2026-05-07T13:24:33.450871Z","steps":["trace[1900003432] 'process raft request' (duration: 1.095237418s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:33.450989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:32.355212Z","time spent":"1.095722969s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:33.451167Z","caller":"traceutil/trace.go:171","msg":"trace[1415156004] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"1.093541426s","start":"2026-05-07T13:24:32.357577Z","end":"2026-05-07T13:24:33.451118Z","steps":["trace[1415156004] 'process raft request' (duration: 1.093120905s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:33.451211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.094683714s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:24:33.45127Z","caller":"traceutil/trace.go:171","msg":"trace[1906583116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2016; }","duration":"1.094741495s","start":"2026-05-07T13:24:32.356513Z","end":"2026-05-07T13:24:33.451254Z","steps":["trace[1906583116] 'agreement among raft nodes before linearized reading' (duration: 1.094652853s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:33.451263Z","caller":"traceutil/trace.go:171","msg":"trace[1614366232] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"1.093930305s","start":"2026-05-07T13:24:32.357312Z","end":"2026-05-07T13:24:33.451243Z","steps":["trace[1614366232] 'process raft request' (duration: 1.09332548s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:33.451324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:32.356497Z","time spent":"1.094816527s","remote":"127.0.0.1:39512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T13:24:33.451343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.180337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-07T13:24:33.451372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:32.357307Z","time spent":"1.094012507s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:33.451403Z","caller":"traceutil/trace.go:171","msg":"trace[1552848788] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2016; }","duration":"306.260389ms","start":"2026-05-07T13:24:33.145133Z","end":"2026-05-07T13:24:33.451394Z","steps":["trace[1552848788] 'agreement among raft nodes before linearized reading' (duration: 306.102795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:33.45137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:32.357559Z","time spent":"1.09370256s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:33.451476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:33.145123Z","time spent":"306.32588ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-07T13:24:33.451202Z","caller":"traceutil/trace.go:171","msg":"trace[777486231] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"1.095523674s","start":"2026-05-07T13:24:32.355636Z","end":"2026-05-07T13:24:33.45116Z","steps":["trace[777486231] 'process raft request' (duration: 1.09494585s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:33.451573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:32.355613Z","time spent":"1.095908964s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:33.451216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.846783ms","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-07T13:24:33.451718Z","caller":"traceutil/trace.go:171","msg":"trace[1891697326] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2016; }","duration":"307.425937ms","start":"2026-05-07T13:24:33.144274Z","end":"2026-05-07T13:24:33.4517Z","steps":["trace[1891697326] 'agreement among raft nodes before linearized reading' (duration: 306.850843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:33.451775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:33.14425Z","time spent":"307.510719ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-07T13:24:33.959974Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":854731763268046834,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:24:34.55633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.631553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:24:34.556458Z","caller":"traceutil/trace.go:171","msg":"trace[364970418] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"1.099687539s","start":"2026-05-07T13:24:33.456745Z","end":"2026-05-07T13:24:34.556433Z","steps":["trace[364970418] 'process raft request' (duration: 633.578703ms)","trace[364970418] 'compare' (duration: 464.990367ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:24:34.556528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:33.456712Z","time spent":"1.099782291s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:34.579075Z","caller":"traceutil/trace.go:171","msg":"trace[1287350972] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"1.120442628s","start":"2026-05-07T13:24:33.458607Z","end":"2026-05-07T13:24:34.57905Z","steps":["trace[1287350972] 'process raft request' (duration: 1.120250983s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:34.579224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:33.458595Z","time spent":"1.120550211s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:34.57925Z","caller":"traceutil/trace.go:171","msg":"trace[1971703878] linearizableReadLoop","detail":"{readStateIndex:2102; appliedIndex:2099; }","duration":"1.12016018s","start":"2026-05-07T13:24:33.459062Z","end":"2026-05-07T13:24:34.579222Z","steps":["trace[1971703878] 'read index received' (duration: 631.279025ms)","trace[1971703878] 'applied index is now lower than readState.Index' (duration: 488.879525ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:24:34.579361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.120286994s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:24:34.579413Z","caller":"traceutil/trace.go:171","msg":"trace[16108104] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"628.543476ms","start":"2026-05-07T13:24:33.950787Z","end":"2026-05-07T13:24:34.57933Z","steps":["trace[16108104] 'process raft request' (duration: 628.206389ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:34.579479Z","caller":"traceutil/trace.go:171","msg":"trace[101604164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2019; }","duration":"1.120374786s","start":"2026-05-07T13:24:33.459014Z","end":"2026-05-07T13:24:34.579389Z","steps":["trace[101604164] 'agreement among raft nodes before linearized reading' (duration: 1.120294304s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:34.579651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:33.950768Z","time spent":"628.781323ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:34.580107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:33.458997Z","time spent":"1.121081803s","remote":"127.0.0.1:39500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T13:24:35.096722Z","caller":"traceutil/trace.go:171","msg":"trace[674674450] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"743.333528ms","start":"2026-05-07T13:24:34.353353Z","end":"2026-05-07T13:24:35.096686Z","steps":["trace[674674450] 'process raft request' (duration: 720.491447ms)","trace[674674450] 'compare' (duration: 22.477162ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:24:35.097074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:34.353326Z","time spent":"743.598625ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:35.121309Z","caller":"traceutil/trace.go:171","msg":"trace[1495652067] linearizableReadLoop","detail":"{readStateIndex:2104; appliedIndex:2102; }","duration":"538.952217ms","start":"2026-05-07T13:24:34.58233Z","end":"2026-05-07T13:24:35.121283Z","steps":["trace[1495652067] 'read index received' (duration: 491.363686ms)","trace[1495652067] 'applied index is now lower than readState.Index' (duration: 47.586981ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:24:35.121653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.296205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:24:35.121722Z","caller":"traceutil/trace.go:171","msg":"trace[777298302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2020; }","duration":"539.422229ms","start":"2026-05-07T13:24:34.582282Z","end":"2026-05-07T13:24:35.121704Z","steps":["trace[777298302] 'agreement among raft nodes before linearized reading' (duration: 539.116281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:35.121987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:34.582257Z","time spent":"539.600373ms","remote":"127.0.0.1:39512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T13:24:36.017719Z","caller":"traceutil/trace.go:171","msg":"trace[481621307] transaction","detail":"{read_only:false; response_revision:2021; number_of_response:1; }","duration":"557.629984ms","start":"2026-05-07T13:24:35.460063Z","end":"2026-05-07T13:24:36.017693Z","steps":["trace[481621307] 'process raft request' (duration: 557.45204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:36.017879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:35.460036Z","time spent":"557.758407ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:36.813362Z","caller":"traceutil/trace.go:171","msg":"trace[1178685862] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"1.35326097s","start":"2026-05-07T13:24:35.460079Z","end":"2026-05-07T13:24:36.81334Z","steps":["trace[1178685862] 'process raft request' (duration: 1.345871824s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:36.813714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:35.460054Z","time spent":"1.353579248s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:36.814024Z","caller":"traceutil/trace.go:171","msg":"trace[1000177095] transaction","detail":"{read_only:false; response_revision:2023; number_of_response:1; }","duration":"1.353028374s","start":"2026-05-07T13:24:35.460973Z","end":"2026-05-07T13:24:36.814001Z","steps":["trace[1000177095] 'process raft request' (duration: 1.352311855s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:36.814143Z","caller":"traceutil/trace.go:171","msg":"trace[1556444971] linearizableReadLoop","detail":"{readStateIndex:2108; appliedIndex:2104; }","duration":"1.028337765s","start":"2026-05-07T13:24:35.785789Z","end":"2026-05-07T13:24:36.814126Z","steps":["trace[1556444971] 'read index received' (duration: 231.768936ms)","trace[1556444971] 'applied index is now lower than readState.Index' (duration: 796.567829ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:24:36.814166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:35.460959Z","time spent":"1.353129007s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:36.814262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028465858s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:24:36.814259Z","caller":"traceutil/trace.go:171","msg":"trace[581295075] transaction","detail":"{read_only:false; response_revision:2024; number_of_response:1; }","duration":"1.352817608s","start":"2026-05-07T13:24:35.461344Z","end":"2026-05-07T13:24:36.814162Z","steps":["trace[581295075] 'process raft request' (duration: 1.352585982s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:36.814296Z","caller":"traceutil/trace.go:171","msg":"trace[1793686508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2024; }","duration":"1.028567921s","start":"2026-05-07T13:24:35.785716Z","end":"2026-05-07T13:24:36.814284Z","steps":["trace[1793686508] 'agreement among raft nodes before linearized reading' (duration: 1.02851058s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:36.814339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:35.785698Z","time spent":"1.028634852s","remote":"127.0.0.1:39512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T13:24:36.814325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.104751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:24:36.814404Z","caller":"traceutil/trace.go:171","msg":"trace[1017794080] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2024; }","duration":"785.218575ms","start":"2026-05-07T13:24:36.029157Z","end":"2026-05-07T13:24:36.814375Z","steps":["trace[1017794080] 'agreement among raft nodes before linearized reading' (duration: 785.114983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:36.814421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:35.461209Z","time spent":"1.353123306s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:36.814451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:36.029133Z","time spent":"785.307148ms","remote":"127.0.0.1:53328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-07T13:24:36.814471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.702453ms","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-07T13:24:36.814469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.362748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-05-07T13:24:36.814516Z","caller":"traceutil/trace.go:171","msg":"trace[1588037561] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2024; }","duration":"229.781175ms","start":"2026-05-07T13:24:36.584723Z","end":"2026-05-07T13:24:36.814504Z","steps":["trace[1588037561] 'agreement among raft nodes before linearized reading' (duration: 229.646202ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:36.814549Z","caller":"traceutil/trace.go:171","msg":"trace[947071042] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2024; }","duration":"667.492541ms","start":"2026-05-07T13:24:36.147034Z","end":"2026-05-07T13:24:36.814526Z","steps":["trace[947071042] 'agreement among raft nodes before linearized reading' (duration: 667.255855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:36.814339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.127743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:24:36.81461Z","caller":"traceutil/trace.go:171","msg":"trace[1790771646] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:2024; }","duration":"647.43608ms","start":"2026-05-07T13:24:36.167157Z","end":"2026-05-07T13:24:36.814593Z","steps":["trace[1790771646] 'agreement among raft nodes before linearized reading' (duration: 647.117923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:36.814611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:36.14701Z","time spent":"667.589103ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-07T13:24:36.814658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:36.167139Z","time spent":"647.505351ms","remote":"127.0.0.1:39888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-05-07T13:24:36.814667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.882559ms","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-07T13:24:36.814705Z","caller":"traceutil/trace.go:171","msg":"trace[2134256653] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2024; }","duration":"253.955871ms","start":"2026-05-07T13:24:36.560741Z","end":"2026-05-07T13:24:36.814697Z","steps":["trace[2134256653] 'agreement among raft nodes before linearized reading' (duration: 253.866538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:36.814787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.124377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-05-07T13:24:36.81487Z","caller":"traceutil/trace.go:171","msg":"trace[2051243731] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2024; }","duration":"670.23827ms","start":"2026-05-07T13:24:36.144613Z","end":"2026-05-07T13:24:36.814851Z","steps":["trace[2051243731] 'agreement among raft nodes before linearized reading' (duration: 669.742218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:36.815046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:36.14459Z","time spent":"670.442706ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-07T13:24:42.365406Z","caller":"traceutil/trace.go:171","msg":"trace[612708836] transaction","detail":"{read_only:false; response_revision:2048; number_of_response:1; }","duration":"533.733347ms","start":"2026-05-07T13:24:41.83163Z","end":"2026-05-07T13:24:42.365363Z","steps":["trace[612708836] 'process raft request' (duration: 533.405819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:42.365829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:41.831602Z","time spent":"533.917831ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:24:42.36861Z","caller":"traceutil/trace.go:171","msg":"trace[285488121] linearizableReadLoop","detail":"{readStateIndex:2134; appliedIndex:2132; }","duration":"324.843514ms","start":"2026-05-07T13:24:42.043743Z","end":"2026-05-07T13:24:42.368586Z","steps":["trace[285488121] 'read index received' (duration: 321.246224ms)","trace[285488121] 'applied index is now lower than readState.Index' (duration: 3.59601ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:24:42.368773Z","caller":"traceutil/trace.go:171","msg":"trace[514986350] transaction","detail":"{read_only:false; response_revision:2049; number_of_response:1; }","duration":"534.035685ms","start":"2026-05-07T13:24:41.834705Z","end":"2026-05-07T13:24:42.368741Z","steps":["trace[514986350] 'process raft request' (duration: 533.718176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:42.368964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:41.834672Z","time spent":"534.175848ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:43.970518Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":854731763268046918,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:24:44.226564Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.103599467s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-07T13:24:44.228755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.184998929s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-07T13:24:44.228796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.443437934s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:24:44.22882Z","caller":"traceutil/trace.go:171","msg":"trace[67138787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2049; }","duration":"1.443491776s","start":"2026-05-07T13:24:42.78532Z","end":"2026-05-07T13:24:44.228812Z","steps":["trace[67138787] 'range keys from in-memory index tree' (duration: 1.443377673s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:44.22882Z","caller":"traceutil/trace.go:171","msg":"trace[437770904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2049; }","duration":"2.185120132s","start":"2026-05-07T13:24:42.043681Z","end":"2026-05-07T13:24:44.228802Z","steps":["trace[437770904] 'agreement among raft nodes before linearized reading' (duration: 325.12111ms)","trace[437770904] 'range keys from in-memory index tree' (duration: 1.859724495s)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:24:44.228844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:42.785304Z","time spent":"1.443536206s","remote":"127.0.0.1:39500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T13:24:44.228856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:42.043657Z","time spent":"2.185191414s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T13:24:44.229137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.387233248s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-07T13:24:44.229162Z","caller":"traceutil/trace.go:171","msg":"trace[1691152513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2049; }","duration":"1.387297581s","start":"2026-05-07T13:24:42.841858Z","end":"2026-05-07T13:24:44.229156Z","steps":["trace[1691152513] 'range keys from in-memory index tree' (duration: 1.387104505s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:44.229178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:42.841838Z","time spent":"1.387335722s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T13:24:44.229421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.382668115s","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-07T13:24:44.229462Z","caller":"traceutil/trace.go:171","msg":"trace[677619636] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2049; }","duration":"1.382721797s","start":"2026-05-07T13:24:42.846728Z","end":"2026-05-07T13:24:44.229449Z","steps":["trace[677619636] 'range keys from in-memory index tree' (duration: 1.382555332s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:44.229475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.383786893s","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-05-07T13:24:44.229494Z","caller":"traceutil/trace.go:171","msg":"trace[1412019451] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2049; }","duration":"1.383822155s","start":"2026-05-07T13:24:42.845664Z","end":"2026-05-07T13:24:44.229486Z","steps":["trace[1412019451] 'range keys from in-memory index tree' (duration: 1.383373343s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:44.229497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:42.84672Z","time spent":"1.382767268s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-07T13:24:44.229489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.388480291s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-07T13:24:44.229554Z","caller":"traceutil/trace.go:171","msg":"trace[376180522] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2049; }","duration":"1.388605824s","start":"2026-05-07T13:24:42.84093Z","end":"2026-05-07T13:24:44.229536Z","steps":["trace[376180522] 'range keys from in-memory index tree' (duration: 1.387504786s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:44.229592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:42.840915Z","time spent":"1.388668656s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T13:24:44.229645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.86595553s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-05-07T13:24:44.22951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:42.845655Z","time spent":"1.383850385s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-05-07T13:24:44.229687Z","caller":"traceutil/trace.go:171","msg":"trace[703772239] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2049; }","duration":"1.866038342s","start":"2026-05-07T13:24:42.363636Z","end":"2026-05-07T13:24:44.229675Z","steps":["trace[703772239] 'range keys from in-memory index tree' (duration: 1.859695354s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:44.229717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:42.363615Z","time spent":"1.866094473s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-07T13:24:44.229775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.382664125s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-07T13:24:44.229785Z","caller":"traceutil/trace.go:171","msg":"trace[1080436990] linearizableReadLoop","detail":"{readStateIndex:2135; appliedIndex:2134; }","duration":"759.707028ms","start":"2026-05-07T13:24:43.47007Z","end":"2026-05-07T13:24:44.229777Z","steps":["trace[1080436990] 'read index received' (duration: 756.59678ms)","trace[1080436990] 'applied index is now lower than readState.Index' (duration: 3.109648ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:24:44.229807Z","caller":"traceutil/trace.go:171","msg":"trace[1182359416] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2049; }","duration":"1.382705486s","start":"2026-05-07T13:24:42.847087Z","end":"2026-05-07T13:24:44.229792Z","steps":["trace[1182359416] 'range keys from in-memory index tree' (duration: 1.382202445s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:44.229829Z","caller":"traceutil/trace.go:171","msg":"trace[233205193] transaction","detail":"{read_only:false; response_revision:2050; number_of_response:1; }","duration":"1.10694097s","start":"2026-05-07T13:24:43.122882Z","end":"2026-05-07T13:24:44.229823Z","steps":["trace[233205193] 'process raft request' (duration: 1.103835092s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:44.229834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:42.847075Z","time spent":"1.382752367s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T13:24:44.229881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:43.122859Z","time spent":"1.106980942s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:24:44.229881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.239291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:24:44.229912Z","caller":"traceutil/trace.go:171","msg":"trace[660835600] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2050; }","duration":"592.328212ms","start":"2026-05-07T13:24:43.637572Z","end":"2026-05-07T13:24:44.2299Z","steps":["trace[660835600] 'agreement among raft nodes before linearized reading' (duration: 592.282071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:44.22994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:43.637542Z","time spent":"592.389954ms","remote":"127.0.0.1:39714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-05-07T13:24:44.22995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.57983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:24:44.229966Z","caller":"traceutil/trace.go:171","msg":"trace[1507109932] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2050; }","duration":"111.651873ms","start":"2026-05-07T13:24:44.118309Z","end":"2026-05-07T13:24:44.229961Z","steps":["trace[1507109932] 'agreement among raft nodes before linearized reading' (duration: 111.621132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:44.230066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.999965ms","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":"warn","ts":"2026-05-07T13:24:44.230089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.042204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:24:44.230112Z","caller":"traceutil/trace.go:171","msg":"trace[1314159678] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2050; }","duration":"760.083087ms","start":"2026-05-07T13:24:43.470015Z","end":"2026-05-07T13:24:44.230099Z","steps":["trace[1314159678] 'agreement among raft nodes before linearized reading' (duration: 759.961444ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:24:44.230119Z","caller":"traceutil/trace.go:171","msg":"trace[1799160311] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:2050; }","duration":"430.137506ms","start":"2026-05-07T13:24:43.79997Z","end":"2026-05-07T13:24:44.230108Z","steps":["trace[1799160311] 'agreement among raft nodes before linearized reading' (duration: 430.094475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:24:44.230153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:43.799936Z","time spent":"430.210097ms","remote":"127.0.0.1:53406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-07T13:24:44.230154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:24:43.469992Z","time spent":"760.149378ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-07T13:25:09.549252Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":854731763268047244,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:25:10.049408Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":854731763268047244,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:25:10.549929Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":854731763268047244,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:25:11.050084Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":854731763268047244,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:25:11.550882Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":854731763268047244,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:25:12.051216Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":854731763268047244,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:25:12.551607Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":854731763268047244,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:25:12.785628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000510182s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-07T13:25:12.786285Z","caller":"traceutil/trace.go:171","msg":"trace[305726968] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00121036s","start":"2026-05-07T13:25:10.785032Z","end":"2026-05-07T13:25:12.786242Z","steps":["trace[305726968] 'agreement among raft nodes before linearized reading' (duration: 2.000534113s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:12.786381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:10.785006Z","time spent":"2.001357325s","remote":"127.0.0.1:39500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T13:25:13.052357Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":854731763268047244,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:25:13.207249Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.213140406s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-07T13:25:13.212235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.428753977s","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-07T13:25:13.212407Z","caller":"traceutil/trace.go:171","msg":"trace[221419864] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2189; }","duration":"1.428937971s","start":"2026-05-07T13:25:11.783443Z","end":"2026-05-07T13:25:13.212381Z","steps":["trace[221419864] 'range keys from in-memory index tree' (duration: 1.428734727s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:25:13.213161Z","caller":"traceutil/trace.go:171","msg":"trace[1728244104] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"4.219113357s","start":"2026-05-07T13:25:08.994029Z","end":"2026-05-07T13:25:13.213142Z","steps":["trace[1728244104] 'process raft request' (duration: 4.213433353s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.213272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:08.994009Z","time spent":"4.219199079s","remote":"127.0.0.1:53542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1856,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T13:25:13.214125Z","caller":"traceutil/trace.go:171","msg":"trace[1176115191] linearizableReadLoop","detail":"{readStateIndex:2282; appliedIndex:2280; }","duration":"4.165251119s","start":"2026-05-07T13:25:09.048851Z","end":"2026-05-07T13:25:13.214102Z","steps":["trace[1176115191] 'read index received' (duration: 4.158581611s)","trace[1176115191] 'applied index is now lower than readState.Index' (duration: 6.668468ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:25:13.214912Z","caller":"traceutil/trace.go:171","msg":"trace[1058436656] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"2.876892573s","start":"2026-05-07T13:25:10.338004Z","end":"2026-05-07T13:25:13.214897Z","steps":["trace[1058436656] 'process raft request' (duration: 2.87597245s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.215012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:10.337981Z","time spent":"2.876964595s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:25:13.215972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.016453764s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2723"} {"level":"info","ts":"2026-05-07T13:25:13.216034Z","caller":"traceutil/trace.go:171","msg":"trace[996373760] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2191; }","duration":"4.017008147s","start":"2026-05-07T13:25:09.199008Z","end":"2026-05-07T13:25:13.216016Z","steps":["trace[996373760] 'agreement among raft nodes before linearized reading' (duration: 4.016369291s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.21608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:09.198986Z","time spent":"4.01707865s","remote":"127.0.0.1:39672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2747,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-05-07T13:25:13.216336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.840649909s","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-07T13:25:13.216383Z","caller":"traceutil/trace.go:171","msg":"trace[1034584316] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2191; }","duration":"2.84071282s","start":"2026-05-07T13:25:10.375657Z","end":"2026-05-07T13:25:13.21637Z","steps":["trace[1034584316] 'agreement among raft nodes before linearized reading' (duration: 2.840541896s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.216377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.851408181s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-07T13:25:13.216419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:10.375651Z","time spent":"2.840759931s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-07T13:25:13.216428Z","caller":"traceutil/trace.go:171","msg":"trace[226421692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2191; }","duration":"2.851487683s","start":"2026-05-07T13:25:10.364929Z","end":"2026-05-07T13:25:13.216416Z","steps":["trace[226421692] 'agreement among raft nodes before linearized reading' (duration: 2.851324549s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.216457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:10.364917Z","time spent":"2.851532984s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T13:25:13.21658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.851619156s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-07T13:25:13.216578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.744172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-05-07T13:25:13.216605Z","caller":"traceutil/trace.go:171","msg":"trace[902020672] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2191; }","duration":"2.851642997s","start":"2026-05-07T13:25:10.364954Z","end":"2026-05-07T13:25:13.216597Z","steps":["trace[902020672] 'agreement among raft nodes before linearized reading' (duration: 2.851564345s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:25:13.216622Z","caller":"traceutil/trace.go:171","msg":"trace[232931336] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2191; }","duration":"800.824344ms","start":"2026-05-07T13:25:12.415783Z","end":"2026-05-07T13:25:13.216608Z","steps":["trace[232931336] 'agreement among raft nodes before linearized reading' (duration: 800.710151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.216631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:10.364913Z","time spent":"2.851712208s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T13:25:13.216655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:12.415766Z","time spent":"800.882046ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-07T13:25:13.216662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.836048193s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-07T13:25:13.216592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.885653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:25:13.216694Z","caller":"traceutil/trace.go:171","msg":"trace[1375202814] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2191; }","duration":"1.836107154s","start":"2026-05-07T13:25:11.380578Z","end":"2026-05-07T13:25:13.216685Z","steps":["trace[1375202814] 'agreement among raft nodes before linearized reading' (duration: 1.836063513s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.216735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:11.38056Z","time spent":"1.836165866s","remote":"127.0.0.1:39666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-05-07T13:25:13.216619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.585355378s","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-07T13:25:13.216719Z","caller":"traceutil/trace.go:171","msg":"trace[1957721146] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:2191; }","duration":"986.060547ms","start":"2026-05-07T13:25:12.230636Z","end":"2026-05-07T13:25:13.216697Z","steps":["trace[1957721146] 'agreement among raft nodes before linearized reading' (duration: 985.841802ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:25:13.216801Z","caller":"traceutil/trace.go:171","msg":"trace[2049600380] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2191; }","duration":"1.585579054s","start":"2026-05-07T13:25:11.63121Z","end":"2026-05-07T13:25:13.216789Z","steps":["trace[2049600380] 'agreement among raft nodes before linearized reading' (duration: 1.585377589s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.216833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:12.230589Z","time spent":"986.228353ms","remote":"127.0.0.1:53290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-05-07T13:25:13.216862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:11.631184Z","time spent":"1.585640855s","remote":"127.0.0.1:53418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-07T13:25:13.216578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.110449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-07T13:25:13.216963Z","caller":"traceutil/trace.go:171","msg":"trace[238841994] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2191; }","duration":"789.52466ms","start":"2026-05-07T13:25:12.427422Z","end":"2026-05-07T13:25:13.216946Z","steps":["trace[238841994] 'agreement among raft nodes before linearized reading' (duration: 789.054828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.217013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.806938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:25:13.217053Z","caller":"traceutil/trace.go:171","msg":"trace[1036407915] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2191; }","duration":"250.91206ms","start":"2026-05-07T13:25:12.966128Z","end":"2026-05-07T13:25:13.21704Z","steps":["trace[1036407915] 'agreement among raft nodes before linearized reading' (duration: 250.836298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.217059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:12.427405Z","time spent":"789.640302ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-07T13:25:13.217174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.821094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T13:25:13.217184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.168336626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.212\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-07T13:25:13.217207Z","caller":"traceutil/trace.go:171","msg":"trace[434864606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2191; }","duration":"429.892796ms","start":"2026-05-07T13:25:12.787305Z","end":"2026-05-07T13:25:13.217197Z","steps":["trace[434864606] 'agreement among raft nodes before linearized reading' (duration: 429.844555ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:25:13.217233Z","caller":"traceutil/trace.go:171","msg":"trace[47505634] range","detail":"{range_begin:/registry/masterleases/199.19.213.212; range_end:; response_count:1; response_revision:2191; }","duration":"4.168364937s","start":"2026-05-07T13:25:09.048838Z","end":"2026-05-07T13:25:13.217203Z","steps":["trace[47505634] 'agreement among raft nodes before linearized reading' (duration: 4.168263395s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.217239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:12.787282Z","time spent":"429.951418ms","remote":"127.0.0.1:39512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T13:25:13.217272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:09.048784Z","time spent":"4.16848005s","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.212\" "} {"level":"warn","ts":"2026-05-07T13:25:13.217314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.680101317s","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-05-07T13:25:13.217356Z","caller":"traceutil/trace.go:171","msg":"trace[290534230] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2191; }","duration":"3.680172529s","start":"2026-05-07T13:25:09.537173Z","end":"2026-05-07T13:25:13.217345Z","steps":["trace[290534230] 'agreement among raft nodes before linearized reading' (duration: 3.680097007s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.217391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:09.537158Z","time spent":"3.680224822s","remote":"127.0.0.1:39996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-05-07T13:25:13.21739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.938573229s","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-07T13:25:13.217433Z","caller":"traceutil/trace.go:171","msg":"trace[1582845072] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2191; }","duration":"3.938655991s","start":"2026-05-07T13:25:09.278764Z","end":"2026-05-07T13:25:13.21742Z","steps":["trace[1582845072] 'agreement among raft nodes before linearized reading' (duration: 3.938526517s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.217473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:09.278745Z","time spent":"3.938721113s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-07T13:25:13.217567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.859342791s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-07T13:25:13.217588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.874052572s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-07T13:25:13.217609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.938434196s","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:507"} {"level":"info","ts":"2026-05-07T13:25:13.217627Z","caller":"traceutil/trace.go:171","msg":"trace[1980905213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2191; }","duration":"2.859431463s","start":"2026-05-07T13:25:10.358186Z","end":"2026-05-07T13:25:13.217617Z","steps":["trace[1980905213] 'agreement among raft nodes before linearized reading' (duration: 2.85931436s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:25:13.217634Z","caller":"traceutil/trace.go:171","msg":"trace[1668705604] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2191; }","duration":"2.874138734s","start":"2026-05-07T13:25:10.343486Z","end":"2026-05-07T13:25:13.217624Z","steps":["trace[1668705604] 'agreement among raft nodes before linearized reading' (duration: 2.874026151s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:25:13.217641Z","caller":"traceutil/trace.go:171","msg":"trace[1935205701] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2191; }","duration":"3.938498598s","start":"2026-05-07T13:25:09.279131Z","end":"2026-05-07T13:25:13.21763Z","steps":["trace[1935205701] 'agreement among raft nodes before linearized reading' (duration: 3.938416736s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.217657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:10.358174Z","time spent":"2.859476094s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T13:25:13.217664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:10.343465Z","time spent":"2.874192835s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T13:25:13.217669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:09.279112Z","time spent":"3.938551379s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-07T13:25:13.217671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.859579517s","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-05-07T13:25:13.217714Z","caller":"traceutil/trace.go:171","msg":"trace[1367873124] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2191; }","duration":"2.859665749s","start":"2026-05-07T13:25:10.358035Z","end":"2026-05-07T13:25:13.217701Z","steps":["trace[1367873124] 'agreement among raft nodes before linearized reading' (duration: 2.859517675s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:25:13.217744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:25:10.358015Z","time spent":"2.85972263s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "}