{"level":"warn","ts":"2026-05-08T18:19:38.998083Z","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-08T18:19:38.998248Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.153:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.153:2380","--initial-cluster=instance=https://199.204.45.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.153: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-08T18:19:38.998338Z","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-08T18:19:38.998352Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-05-08T18:19:38.998376Z","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-08T18:19:38.999928Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"]} {"level":"info","ts":"2026-05-08T18:19:39.000134Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.153: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-08T18:19:39.310755Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"171.517816ms"} {"level":"info","ts":"2026-05-08T18:19:39.467204Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"355195d5aae85944","cluster-id":"e424441517bc0b32"} {"level":"info","ts":"2026-05-08T18:19:39.467481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=()"} {"level":"info","ts":"2026-05-08T18:19:39.467518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 0"} {"level":"info","ts":"2026-05-08T18:19:39.467533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 355195d5aae85944 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-08T18:19:39.467542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 1"} {"level":"info","ts":"2026-05-08T18:19:39.467584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"warn","ts":"2026-05-08T18:19:39.560649Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-08T18:19:39.572565Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-08T18:19:39.573545Z","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-08T18:19:39.575886Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"355195d5aae85944","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-08T18:19:39.576516Z","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-08T18:19:39.576583Z","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-08T18:19:39.576038Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"355195d5aae85944","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-08T18:19:39.576632Z","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-08T18:19:39.577942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"info","ts":"2026-05-08T18:19:39.57811Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","added-peer-id":"355195d5aae85944","added-peer-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-05-08T18:19:39.57982Z","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-08T18:19:39.579909Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-05-08T18:19:39.579942Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-05-08T18:19:39.580421Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"355195d5aae85944","initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-08T18:19:39.580492Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-08T18:19:40.071833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 is starting a new election at term 1"} {"level":"info","ts":"2026-05-08T18:19:40.071899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-08T18:19:40.071943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgPreVoteResp from 355195d5aae85944 at term 1"} {"level":"info","ts":"2026-05-08T18:19:40.071971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became candidate at term 2"} {"level":"info","ts":"2026-05-08T18:19:40.071984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgVoteResp from 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-05-08T18:19:40.072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became leader at term 2"} {"level":"info","ts":"2026-05-08T18:19:40.072021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 355195d5aae85944 elected leader 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-05-08T18:19:40.073696Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T18:19:40.073719Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"355195d5aae85944","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.153:2379]}","request-path":"/0/members/355195d5aae85944/attributes","cluster-id":"e424441517bc0b32","publish-timeout":"7s"} {"level":"info","ts":"2026-05-08T18:19:40.073803Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T18:19:40.073797Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T18:19:40.074035Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-08T18:19:40.074149Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-08T18:19:40.07497Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T18:19:40.075175Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T18:19:40.075222Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T18:19:40.078635Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-08T18:19:40.079629Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.153:2379"} {"level":"warn","ts":"2026-05-08T18:19:45.040696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.620324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:19:45.040846Z","caller":"traceutil/trace.go:171","msg":"trace[104018980] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:222; }","duration":"159.884292ms","start":"2026-05-08T18:19:44.880937Z","end":"2026-05-08T18:19:45.040821Z","steps":["trace[104018980] 'range keys from in-memory index tree' (duration: 159.524831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:19:45.343997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.201801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T18:19:45.344134Z","caller":"traceutil/trace.go:171","msg":"trace[424548982] linearizableReadLoop","detail":"{readStateIndex:229; appliedIndex:228; }","duration":"300.269691ms","start":"2026-05-08T18:19:45.043843Z","end":"2026-05-08T18:19:45.344113Z","steps":["trace[424548982] 'read index received' (duration: 140.39305ms)","trace[424548982] 'applied index is now lower than readState.Index' (duration: 159.875371ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:19:45.344182Z","caller":"traceutil/trace.go:171","msg":"trace[1883487693] transaction","detail":"{read_only:false; response_revision:223; number_of_response:1; }","duration":"301.844017ms","start":"2026-05-08T18:19:45.042304Z","end":"2026-05-08T18:19:45.344148Z","steps":["trace[1883487693] 'process raft request' (duration: 141.910475ms)","trace[1883487693] 'compare' (duration: 159.013466ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:19:45.344299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.438967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-05-08T18:19:45.344358Z","caller":"traceutil/trace.go:171","msg":"trace[1673720653] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:223; }","duration":"300.57275ms","start":"2026-05-08T18:19:45.043771Z","end":"2026-05-08T18:19:45.344343Z","steps":["trace[1673720653] 'agreement among raft nodes before linearized reading' (duration: 300.406735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:19:45.344403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:19:45.043749Z","time spent":"300.643372ms","remote":"127.0.0.1:38892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4040,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-08T18:19:45.344444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:19:45.042283Z","time spent":"301.972421ms","remote":"127.0.0.1:38794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T18:19:45.346605Z","caller":"traceutil/trace.go:171","msg":"trace[190980449] transaction","detail":"{read_only:false; number_of_response:0; response_revision:223; }","duration":"286.630651ms","start":"2026-05-08T18:19:45.05995Z","end":"2026-05-08T18:19:45.346581Z","steps":["trace[190980449] 'process raft request' (duration: 286.483376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:19:45.346789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.665421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-08T18:19:45.346854Z","caller":"traceutil/trace.go:171","msg":"trace[364513581] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:227; }","duration":"302.825886ms","start":"2026-05-08T18:19:45.044014Z","end":"2026-05-08T18:19:45.346839Z","steps":["trace[364513581] 'agreement among raft nodes before linearized reading' (duration: 302.660851ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:45.346822Z","caller":"traceutil/trace.go:171","msg":"trace[1756047305] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"266.958483ms","start":"2026-05-08T18:19:45.079836Z","end":"2026-05-08T18:19:45.346794Z","steps":["trace[1756047305] 'process raft request' (duration: 266.666344ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:45.346921Z","caller":"traceutil/trace.go:171","msg":"trace[2051391097] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"267.012335ms","start":"2026-05-08T18:19:45.079897Z","end":"2026-05-08T18:19:45.34691Z","steps":["trace[2051391097] 'process raft request' (duration: 266.664255ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:45.347009Z","caller":"traceutil/trace.go:171","msg":"trace[1974796327] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"266.783708ms","start":"2026-05-08T18:19:45.080214Z","end":"2026-05-08T18:19:45.346998Z","steps":["trace[1974796327] 'process raft request' (duration: 266.421398ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:45.347083Z","caller":"traceutil/trace.go:171","msg":"trace[2060432198] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"267.005665ms","start":"2026-05-08T18:19:45.080071Z","end":"2026-05-08T18:19:45.347076Z","steps":["trace[2060432198] 'process raft request' (duration: 266.515691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:19:45.346885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:19:45.043991Z","time spent":"302.886767ms","remote":"127.0.0.1:39076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-05-08T18:19:45.347137Z","caller":"traceutil/trace.go:171","msg":"trace[477789744] transaction","detail":"{read_only:false; number_of_response:0; response_revision:226; }","duration":"267.006774ms","start":"2026-05-08T18:19:45.080124Z","end":"2026-05-08T18:19:45.347131Z","steps":["trace[477789744] 'process raft request' (duration: 266.4904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:19:45.347219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.518465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:19:45.347241Z","caller":"traceutil/trace.go:171","msg":"trace[1527887912] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:227; }","duration":"147.573297ms","start":"2026-05-08T18:19:45.19966Z","end":"2026-05-08T18:19:45.347234Z","steps":["trace[1527887912] 'agreement among raft nodes before linearized reading' (duration: 147.522726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:19:45.348542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.011787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:19:45.348774Z","caller":"traceutil/trace.go:171","msg":"trace[483318895] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:227; }","duration":"299.270814ms","start":"2026-05-08T18:19:45.049457Z","end":"2026-05-08T18:19:45.348728Z","steps":["trace[483318895] 'agreement among raft nodes before linearized reading' (duration: 298.843391ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:20:10.289549Z","caller":"traceutil/trace.go:171","msg":"trace[618488091] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"129.235089ms","start":"2026-05-08T18:20:10.16028Z","end":"2026-05-08T18:20:10.289515Z","steps":["trace[618488091] 'process raft request' (duration: 123.433251ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:20:10.289676Z","caller":"traceutil/trace.go:171","msg":"trace[1538516858] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"117.856879ms","start":"2026-05-08T18:20:10.171778Z","end":"2026-05-08T18:20:10.289635Z","steps":["trace[1538516858] 'process raft request' (duration: 117.729326ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:20:10.289665Z","caller":"traceutil/trace.go:171","msg":"trace[1079678474] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"122.819923ms","start":"2026-05-08T18:20:10.166767Z","end":"2026-05-08T18:20:10.289587Z","steps":["trace[1079678474] 'process raft request' (duration: 122.688389ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:20:10.289736Z","caller":"traceutil/trace.go:171","msg":"trace[2134466163] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"106.47711ms","start":"2026-05-08T18:20:10.183227Z","end":"2026-05-08T18:20:10.289704Z","steps":["trace[2134466163] 'process raft request' (duration: 106.337606ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:20:10.289576Z","caller":"traceutil/trace.go:171","msg":"trace[1947881860] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"129.228839ms","start":"2026-05-08T18:20:10.160318Z","end":"2026-05-08T18:20:10.289546Z","steps":["trace[1947881860] 'process raft request' (duration: 129.050024ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:20:10.289621Z","caller":"traceutil/trace.go:171","msg":"trace[1129099921] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"106.509951ms","start":"2026-05-08T18:20:10.183102Z","end":"2026-05-08T18:20:10.289612Z","steps":["trace[1129099921] 'process raft request' (duration: 106.435419ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:20:10.289988Z","caller":"traceutil/trace.go:171","msg":"trace[677709259] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"106.373007ms","start":"2026-05-08T18:20:10.183601Z","end":"2026-05-08T18:20:10.289974Z","steps":["trace[677709259] 'process raft request' (duration: 106.043808ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:20:15.629284Z","caller":"traceutil/trace.go:171","msg":"trace[1163944895] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:585; }","duration":"245.852422ms","start":"2026-05-08T18:20:15.383409Z","end":"2026-05-08T18:20:15.629261Z","steps":["trace[1163944895] 'read index received' (duration: 245.677407ms)","trace[1163944895] 'applied index is now lower than readState.Index' (duration: 173.945µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:20:15.629767Z","caller":"traceutil/trace.go:171","msg":"trace[1676903728] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"283.710656ms","start":"2026-05-08T18:20:15.346038Z","end":"2026-05-08T18:20:15.629749Z","steps":["trace[1676903728] 'process raft request' (duration: 283.113019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:20:15.629847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.430898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:20:15.630217Z","caller":"traceutil/trace.go:171","msg":"trace[185253872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"246.85848ms","start":"2026-05-08T18:20:15.383341Z","end":"2026-05-08T18:20:15.6302Z","steps":["trace[185253872] 'agreement among raft nodes before linearized reading' (duration: 246.467679ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:20:16.87382Z","caller":"traceutil/trace.go:171","msg":"trace[438440525] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"219.372525ms","start":"2026-05-08T18:20:16.654416Z","end":"2026-05-08T18:20:16.873789Z","steps":["trace[438440525] 'read index received' (duration: 218.521051ms)","trace[438440525] 'applied index is now lower than readState.Index' (duration: 850.194µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:20:16.874012Z","caller":"traceutil/trace.go:171","msg":"trace[2109868175] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"321.277164ms","start":"2026-05-08T18:20:16.552704Z","end":"2026-05-08T18:20:16.873981Z","steps":["trace[2109868175] 'process raft request' (duration: 320.44595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:20:16.874086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.657424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T18:20:16.874155Z","caller":"traceutil/trace.go:171","msg":"trace[756660726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:576; }","duration":"219.759897ms","start":"2026-05-08T18:20:16.654369Z","end":"2026-05-08T18:20:16.874129Z","steps":["trace[756660726] 'agreement among raft nodes before linearized reading' (duration: 219.537471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:20:16.874321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:20:16.552681Z","time spent":"321.392258ms","remote":"127.0.0.1:38806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:20:17.351951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.204393ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T18:20:17.352065Z","caller":"traceutil/trace.go:171","msg":"trace[630085753] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"277.9002ms","start":"2026-05-08T18:20:17.074148Z","end":"2026-05-08T18:20:17.352048Z","steps":["trace[630085753] 'read index received' (duration: 45.372µs)","trace[630085753] 'applied index is now lower than readState.Index' (duration: 277.853588ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:20:17.352294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.132066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9k8hg\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-08T18:20:17.352355Z","caller":"traceutil/trace.go:171","msg":"trace[1549213013] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9k8hg; range_end:; response_count:1; response_revision:578; }","duration":"278.228229ms","start":"2026-05-08T18:20:17.07411Z","end":"2026-05-08T18:20:17.352338Z","steps":["trace[1549213013] 'agreement among raft nodes before linearized reading' (duration: 277.985242ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:20:17.35241Z","caller":"traceutil/trace.go:171","msg":"trace[598627153] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"408.434054ms","start":"2026-05-08T18:20:16.94394Z","end":"2026-05-08T18:20:17.352374Z","steps":["trace[598627153] 'process raft request' (duration: 50.735847ms)","trace[598627153] 'compare' (duration: 357.046679ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:20:17.352566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:20:16.943916Z","time spent":"408.570519ms","remote":"127.0.0.1:39198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T18:20:17.580202Z","caller":"traceutil/trace.go:171","msg":"trace[529567179] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"193.312451ms","start":"2026-05-08T18:20:17.386858Z","end":"2026-05-08T18:20:17.58017Z","steps":["trace[529567179] 'read index received' (duration: 190.749657ms)","trace[529567179] 'applied index is now lower than readState.Index' (duration: 2.561554ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:20:17.580372Z","caller":"traceutil/trace.go:171","msg":"trace[1609575767] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"211.60354ms","start":"2026-05-08T18:20:17.368753Z","end":"2026-05-08T18:20:17.580356Z","steps":["trace[1609575767] 'process raft request' (duration: 208.945993ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:20:17.580579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.698553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:20:17.580628Z","caller":"traceutil/trace.go:171","msg":"trace[17943254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"193.784625ms","start":"2026-05-08T18:20:17.386827Z","end":"2026-05-08T18:20:17.580612Z","steps":["trace[17943254] 'agreement among raft nodes before linearized reading' (duration: 193.694203ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:20:33.05657Z","caller":"traceutil/trace.go:171","msg":"trace[1330945795] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"105.238805ms","start":"2026-05-08T18:20:32.951297Z","end":"2026-05-08T18:20:33.056536Z","steps":["trace[1330945795] 'process raft request' (duration: 105.083631ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:21:07.247921Z","caller":"traceutil/trace.go:171","msg":"trace[1998942625] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"101.873918ms","start":"2026-05-08T18:21:07.146028Z","end":"2026-05-08T18:21:07.247902Z","steps":["trace[1998942625] 'process raft request' (duration: 101.744444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:21:07.744465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.356523ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T18:21:07.744547Z","caller":"traceutil/trace.go:171","msg":"trace[1514705704] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"444.991611ms","start":"2026-05-08T18:21:07.299543Z","end":"2026-05-08T18:21:07.744534Z","steps":["trace[1514705704] 'read index received' (duration: 56.464263ms)","trace[1514705704] 'applied index is now lower than readState.Index' (duration: 388.526428ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:21:07.744634Z","caller":"traceutil/trace.go:171","msg":"trace[118542555] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"469.558581ms","start":"2026-05-08T18:21:07.275035Z","end":"2026-05-08T18:21:07.744594Z","steps":["trace[118542555] 'process raft request' (duration: 81.000603ms)","trace[118542555] 'compare' (duration: 387.91613ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:21:07.744691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.155086ms","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-08T18:21:07.744715Z","caller":"traceutil/trace.go:171","msg":"trace[907026222] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:957; }","duration":"445.206566ms","start":"2026-05-08T18:21:07.299501Z","end":"2026-05-08T18:21:07.744707Z","steps":["trace[907026222] 'agreement among raft nodes before linearized reading' (duration: 445.102024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:21:07.744739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:21:07.299482Z","time spent":"445.250288ms","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-08T18:21:07.744817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.192464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T18:21:07.744885Z","caller":"traceutil/trace.go:171","msg":"trace[805286242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:957; }","duration":"419.299858ms","start":"2026-05-08T18:21:07.325568Z","end":"2026-05-08T18:21:07.744867Z","steps":["trace[805286242] 'agreement among raft nodes before linearized reading' (duration: 419.077432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:21:07.74489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.764139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T18:21:07.744931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:21:07.325551Z","time spent":"419.36822ms","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-08T18:21:07.744949Z","caller":"traceutil/trace.go:171","msg":"trace[1310823696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:957; }","duration":"254.875602ms","start":"2026-05-08T18:21:07.490059Z","end":"2026-05-08T18:21:07.744935Z","steps":["trace[1310823696] 'agreement among raft nodes before linearized reading' (duration: 254.784829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:21:07.744985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:21:07.274995Z","time spent":"469.699855ms","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:21:30.570984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.728045ms","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-08T18:21:30.571077Z","caller":"traceutil/trace.go:171","msg":"trace[348139970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1033; }","duration":"256.87845ms","start":"2026-05-08T18:21:30.314177Z","end":"2026-05-08T18:21:30.571056Z","steps":["trace[348139970] 'range keys from in-memory index tree' (duration: 256.504349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:21:30.571003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.292897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:21:30.571283Z","caller":"traceutil/trace.go:171","msg":"trace[596691606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1033; }","duration":"187.642657ms","start":"2026-05-08T18:21:30.383608Z","end":"2026-05-08T18:21:30.57125Z","steps":["trace[596691606] 'range keys from in-memory index tree' (duration: 187.159304ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:21:30.689377Z","caller":"traceutil/trace.go:171","msg":"trace[2040750918] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"114.006187ms","start":"2026-05-08T18:21:30.57533Z","end":"2026-05-08T18:21:30.689336Z","steps":["trace[2040750918] 'process raft request' (duration: 113.851053ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:21:32.125169Z","caller":"traceutil/trace.go:171","msg":"trace[1970360795] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"254.011698ms","start":"2026-05-08T18:21:31.871131Z","end":"2026-05-08T18:21:32.125143Z","steps":["trace[1970360795] 'process raft request' (duration: 253.883854ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:21:32.143791Z","caller":"traceutil/trace.go:171","msg":"trace[1018817051] linearizableReadLoop","detail":"{readStateIndex:1076; appliedIndex:1074; }","duration":"142.414089ms","start":"2026-05-08T18:21:32.001347Z","end":"2026-05-08T18:21:32.143761Z","steps":["trace[1018817051] 'read index received' (duration: 123.694688ms)","trace[1018817051] 'applied index is now lower than readState.Index' (duration: 18.718041ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:21:32.143867Z","caller":"traceutil/trace.go:171","msg":"trace[2059707249] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"272.104671ms","start":"2026-05-08T18:21:31.87173Z","end":"2026-05-08T18:21:32.143835Z","steps":["trace[2059707249] 'process raft request' (duration: 271.854043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:21:32.144122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.746008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-08T18:21:32.144228Z","caller":"traceutil/trace.go:171","msg":"trace[1739824331] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1037; }","duration":"142.888363ms","start":"2026-05-08T18:21:32.001286Z","end":"2026-05-08T18:21:32.144174Z","steps":["trace[1739824331] 'agreement among raft nodes before linearized reading' (duration: 142.581774ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:02.478719Z","caller":"traceutil/trace.go:171","msg":"trace[1595018333] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"174.749234ms","start":"2026-05-08T18:22:02.303952Z","end":"2026-05-08T18:22:02.478702Z","steps":["trace[1595018333] 'process raft request' (duration: 174.516267ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:02.478682Z","caller":"traceutil/trace.go:171","msg":"trace[1426382073] linearizableReadLoop","detail":"{readStateIndex:1253; appliedIndex:1252; }","duration":"160.477023ms","start":"2026-05-08T18:22:02.318159Z","end":"2026-05-08T18:22:02.478636Z","steps":["trace[1426382073] 'read index received' (duration: 160.293218ms)","trace[1426382073] 'applied index is now lower than readState.Index' (duration: 182.365µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:22:02.478916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.74262ms","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-08T18:22:02.478993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.235796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-08T18:22:02.479001Z","caller":"traceutil/trace.go:171","msg":"trace[1238374737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1206; }","duration":"160.865634ms","start":"2026-05-08T18:22:02.31812Z","end":"2026-05-08T18:22:02.478985Z","steps":["trace[1238374737] 'agreement among raft nodes before linearized reading' (duration: 160.632497ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:02.479044Z","caller":"traceutil/trace.go:171","msg":"trace[378545445] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1206; }","duration":"118.327929ms","start":"2026-05-08T18:22:02.3607Z","end":"2026-05-08T18:22:02.479028Z","steps":["trace[378545445] 'agreement among raft nodes before linearized reading' (duration: 118.195055ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:02.675849Z","caller":"traceutil/trace.go:171","msg":"trace[318148301] linearizableReadLoop","detail":"{readStateIndex:1255; appliedIndex:1253; }","duration":"182.303413ms","start":"2026-05-08T18:22:02.493529Z","end":"2026-05-08T18:22:02.675833Z","steps":["trace[318148301] 'read index received' (duration: 111.690218ms)","trace[318148301] 'applied index is now lower than readState.Index' (duration: 70.612365ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:22:02.675948Z","caller":"traceutil/trace.go:171","msg":"trace[859201283] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"192.131456ms","start":"2026-05-08T18:22:02.483798Z","end":"2026-05-08T18:22:02.67593Z","steps":["trace[859201283] 'process raft request' (duration: 192.000313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:02.676001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.463477ms","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-08T18:22:02.676032Z","caller":"traceutil/trace.go:171","msg":"trace[1191512707] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1208; }","duration":"182.505368ms","start":"2026-05-08T18:22:02.493517Z","end":"2026-05-08T18:22:02.676022Z","steps":["trace[1191512707] 'agreement among raft nodes before linearized reading' (duration: 182.386735ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:02.676198Z","caller":"traceutil/trace.go:171","msg":"trace[147110038] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"193.120704ms","start":"2026-05-08T18:22:02.482802Z","end":"2026-05-08T18:22:02.675923Z","steps":["trace[147110038] 'process raft request' (duration: 122.291153ms)","trace[147110038] 'compare' (duration: 70.604515ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:22:03.635322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.950318ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T18:22:03.635407Z","caller":"traceutil/trace.go:171","msg":"trace[1516328071] linearizableReadLoop","detail":"{readStateIndex:1260; appliedIndex:1259; }","duration":"308.816926ms","start":"2026-05-08T18:22:03.326578Z","end":"2026-05-08T18:22:03.635395Z","steps":["trace[1516328071] 'read index received' (duration: 106.706544ms)","trace[1516328071] 'applied index is now lower than readState.Index' (duration: 202.109332ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:22:03.635481Z","caller":"traceutil/trace.go:171","msg":"trace[1486946869] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"351.015861ms","start":"2026-05-08T18:22:03.284431Z","end":"2026-05-08T18:22:03.635446Z","steps":["trace[1486946869] 'process raft request' (duration: 148.877138ms)","trace[1486946869] 'compare' (duration: 201.421123ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:22:03.635525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.761831ms","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-08T18:22:03.635564Z","caller":"traceutil/trace.go:171","msg":"trace[907910793] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1212; }","duration":"277.839883ms","start":"2026-05-08T18:22:03.357713Z","end":"2026-05-08T18:22:03.635553Z","steps":["trace[907910793] 'agreement among raft nodes before linearized reading' (duration: 277.754651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:03.635563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.322029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T18:22:03.635583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.992061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T18:22:03.635631Z","caller":"traceutil/trace.go:171","msg":"trace[1027647688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1212; }","duration":"252.436922ms","start":"2026-05-08T18:22:03.383172Z","end":"2026-05-08T18:22:03.635609Z","steps":["trace[1027647688] 'agreement among raft nodes before linearized reading' (duration: 252.329019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:03.635624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:03.284406Z","time spent":"351.147095ms","remote":"127.0.0.1:38768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:22:03.635667Z","caller":"traceutil/trace.go:171","msg":"trace[1744555664] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1212; }","duration":"309.128755ms","start":"2026-05-08T18:22:03.326517Z","end":"2026-05-08T18:22:03.635646Z","steps":["trace[1744555664] 'agreement among raft nodes before linearized reading' (duration: 308.920109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:03.635989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:03.326489Z","time spent":"309.484445ms","remote":"127.0.0.1:53956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-05-08T18:22:04.062638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.594453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T18:22:04.062772Z","caller":"traceutil/trace.go:171","msg":"trace[216160904] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"270.40131ms","start":"2026-05-08T18:22:03.792335Z","end":"2026-05-08T18:22:04.062736Z","steps":["trace[216160904] 'process raft request' (duration: 131.645712ms)","trace[216160904] 'compare' (duration: 138.272734ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:22:04.236443Z","caller":"traceutil/trace.go:171","msg":"trace[1284989908] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"435.583629ms","start":"2026-05-08T18:22:03.800825Z","end":"2026-05-08T18:22:04.236409Z","steps":["trace[1284989908] 'process raft request' (duration: 435.339383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:04.236668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:03.800799Z","time spent":"435.769164ms","remote":"127.0.0.1:38794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T18:22:04.241791Z","caller":"traceutil/trace.go:171","msg":"trace[43454497] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1263; }","duration":"174.317103ms","start":"2026-05-08T18:22:04.067442Z","end":"2026-05-08T18:22:04.241759Z","steps":["trace[43454497] 'read index received' (duration: 168.762053ms)","trace[43454497] 'applied index is now lower than readState.Index' (duration: 5.55343ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:22:04.242095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.619251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-08T18:22:04.242138Z","caller":"traceutil/trace.go:171","msg":"trace[1533293527] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1218; }","duration":"174.726374ms","start":"2026-05-08T18:22:04.067401Z","end":"2026-05-08T18:22:04.242128Z","steps":["trace[1533293527] 'agreement among raft nodes before linearized reading' (duration: 174.486528ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:04.24252Z","caller":"traceutil/trace.go:171","msg":"trace[1961238501] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"242.795185ms","start":"2026-05-08T18:22:03.999714Z","end":"2026-05-08T18:22:04.242509Z","steps":["trace[1961238501] 'process raft request' (duration: 241.772356ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:04.242664Z","caller":"traceutil/trace.go:171","msg":"trace[1306535936] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"175.543538ms","start":"2026-05-08T18:22:04.067113Z","end":"2026-05-08T18:22:04.242656Z","steps":["trace[1306535936] 'process raft request' (duration: 174.588111ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:16.878096Z","caller":"traceutil/trace.go:171","msg":"trace[602613280] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1353; }","duration":"138.171909ms","start":"2026-05-08T18:22:16.739878Z","end":"2026-05-08T18:22:16.87805Z","steps":["trace[602613280] 'read index received' (duration: 137.889991ms)","trace[602613280] 'applied index is now lower than readState.Index' (duration: 280.478µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:22:16.878129Z","caller":"traceutil/trace.go:171","msg":"trace[190437335] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"143.489053ms","start":"2026-05-08T18:22:16.734613Z","end":"2026-05-08T18:22:16.878102Z","steps":["trace[190437335] 'process raft request' (duration: 143.211765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:16.878392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.486428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T18:22:16.878456Z","caller":"traceutil/trace.go:171","msg":"trace[114960460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1304; }","duration":"138.584592ms","start":"2026-05-08T18:22:16.739856Z","end":"2026-05-08T18:22:16.87844Z","steps":["trace[114960460] 'agreement among raft nodes before linearized reading' (duration: 138.376646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:16.87848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.379474ms","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-08T18:22:16.878567Z","caller":"traceutil/trace.go:171","msg":"trace[654465989] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1304; }","duration":"131.515149ms","start":"2026-05-08T18:22:16.747035Z","end":"2026-05-08T18:22:16.87855Z","steps":["trace[654465989] 'agreement among raft nodes before linearized reading' (duration: 131.281161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:16.87866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.517929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-08T18:22:16.879367Z","caller":"traceutil/trace.go:171","msg":"trace[716528950] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1304; }","duration":"124.311021ms","start":"2026-05-08T18:22:16.755041Z","end":"2026-05-08T18:22:16.879352Z","steps":["trace[716528950] 'agreement among raft nodes before linearized reading' (duration: 123.321692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:17.129516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.23165ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T18:22:17.129641Z","caller":"traceutil/trace.go:171","msg":"trace[177810787] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1354; }","duration":"193.259207ms","start":"2026-05-08T18:22:16.936372Z","end":"2026-05-08T18:22:17.129632Z","steps":["trace[177810787] 'read index received' (duration: 20.850121ms)","trace[177810787] 'applied index is now lower than readState.Index' (duration: 172.408036ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:22:17.12973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.36287ms","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-08T18:22:17.129746Z","caller":"traceutil/trace.go:171","msg":"trace[798600885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1307; }","duration":"193.408321ms","start":"2026-05-08T18:22:16.936333Z","end":"2026-05-08T18:22:17.129742Z","steps":["trace[798600885] 'agreement among raft nodes before linearized reading' (duration: 193.328379ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:17.129753Z","caller":"traceutil/trace.go:171","msg":"trace[1600870443] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"246.569123ms","start":"2026-05-08T18:22:16.88312Z","end":"2026-05-08T18:22:17.129689Z","steps":["trace[1600870443] 'process raft request' (duration: 74.093105ms)","trace[1600870443] 'compare' (duration: 172.163629ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:22:17.129854Z","caller":"traceutil/trace.go:171","msg":"trace[1897333085] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"246.052438ms","start":"2026-05-08T18:22:16.883797Z","end":"2026-05-08T18:22:17.129849Z","steps":["trace[1897333085] 'process raft request' (duration: 245.790241ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:17.129913Z","caller":"traceutil/trace.go:171","msg":"trace[1077353061] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"245.687707ms","start":"2026-05-08T18:22:16.88422Z","end":"2026-05-08T18:22:17.129908Z","steps":["trace[1077353061] 'process raft request' (duration: 245.391248ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:42.818529Z","caller":"traceutil/trace.go:171","msg":"trace[634206722] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"124.006962ms","start":"2026-05-08T18:22:42.694499Z","end":"2026-05-08T18:22:42.818506Z","steps":["trace[634206722] 'process raft request' (duration: 123.870668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:44.370107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.719162ms","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-08T18:22:44.370208Z","caller":"traceutil/trace.go:171","msg":"trace[377899235] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1426; }","duration":"980.854837ms","start":"2026-05-08T18:22:43.389328Z","end":"2026-05-08T18:22:44.370183Z","steps":["trace[377899235] 'range keys from in-memory index tree' (duration: 980.556418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:44.370267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:43.389312Z","time spent":"980.940739ms","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T18:22:44.370264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024434412s","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-08T18:22:44.370287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.037082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:22:44.370322Z","caller":"traceutil/trace.go:171","msg":"trace[824487590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1426; }","duration":"1.024528344s","start":"2026-05-08T18:22:43.34578Z","end":"2026-05-08T18:22:44.370309Z","steps":["trace[824487590] 'range keys from in-memory index tree' (duration: 1.024322478s)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:44.37035Z","caller":"traceutil/trace.go:171","msg":"trace[250380943] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1426; }","duration":"989.136715ms","start":"2026-05-08T18:22:43.381196Z","end":"2026-05-08T18:22:44.370333Z","steps":["trace[250380943] 'range keys from in-memory index tree' (duration: 988.96273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:44.37037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:43.345761Z","time spent":"1.024600877s","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T18:22:44.37038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:43.381179Z","time spent":"989.194596ms","remote":"127.0.0.1:38712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T18:22:44.370458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.115795112s","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-08T18:22:44.37051Z","caller":"traceutil/trace.go:171","msg":"trace[2019340354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1426; }","duration":"1.116386469s","start":"2026-05-08T18:22:43.254108Z","end":"2026-05-08T18:22:44.370494Z","steps":["trace[2019340354] 'range keys from in-memory index tree' (duration: 1.115587107s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:44.370552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:43.254083Z","time spent":"1.116459712s","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-08T18:22:44.767387Z","caller":"traceutil/trace.go:171","msg":"trace[1224142605] transaction","detail":"{read_only:false; response_revision:1430; number_of_response:1; }","duration":"384.430765ms","start":"2026-05-08T18:22:44.382934Z","end":"2026-05-08T18:22:44.767364Z","steps":["trace[1224142605] 'process raft request' (duration: 384.240089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:44.767561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:44.382926Z","time spent":"384.557758ms","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:22:44.767602Z","caller":"traceutil/trace.go:171","msg":"trace[23368504] transaction","detail":"{read_only:false; response_revision:1431; number_of_response:1; }","duration":"384.602659ms","start":"2026-05-08T18:22:44.382984Z","end":"2026-05-08T18:22:44.767587Z","steps":["trace[23368504] 'process raft request' (duration: 384.341802ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:44.767562Z","caller":"traceutil/trace.go:171","msg":"trace[1797696019] linearizableReadLoop","detail":"{readStateIndex:1487; appliedIndex:1485; }","duration":"382.949212ms","start":"2026-05-08T18:22:44.38458Z","end":"2026-05-08T18:22:44.767529Z","steps":["trace[1797696019] 'read index received' (duration: 382.627953ms)","trace[1797696019] 'applied index is now lower than readState.Index' (duration: 318.109µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:22:44.767654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:44.382979Z","time spent":"384.6496ms","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:22:44.767905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.311953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:22:44.768062Z","caller":"traceutil/trace.go:171","msg":"trace[1478197558] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1431; }","duration":"383.477087ms","start":"2026-05-08T18:22:44.384559Z","end":"2026-05-08T18:22:44.768036Z","steps":["trace[1478197558] 'agreement among raft nodes before linearized reading' (duration: 383.130777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:44.768379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:44.384553Z","time spent":"383.812207ms","remote":"127.0.0.1:38712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T18:22:44.769245Z","caller":"traceutil/trace.go:171","msg":"trace[656722262] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"349.350374ms","start":"2026-05-08T18:22:44.419864Z","end":"2026-05-08T18:22:44.769214Z","steps":["trace[656722262] 'process raft request' (duration: 349.282762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:44.769327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:44.419837Z","time spent":"349.452497ms","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:22:47.830603Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439928496348029,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T18:22:48.331851Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439928496348029,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T18:22:48.832021Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439928496348029,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T18:22:49.332366Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439928496348029,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T18:22:49.381137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000696105s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-08T18:22:49.381215Z","caller":"traceutil/trace.go:171","msg":"trace[1459524269] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000820379s","start":"2026-05-08T18:22:47.380377Z","end":"2026-05-08T18:22:49.381197Z","steps":["trace[1459524269] 'agreement among raft nodes before linearized reading' (duration: 2.000719666s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:49.381258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:47.380362Z","time spent":"2.000888021s","remote":"127.0.0.1:38728","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-08T18:22:49.487763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.0005298s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-08T18:22:49.487834Z","caller":"traceutil/trace.go:171","msg":"trace[1354347418] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000642194s","start":"2026-05-08T18:22:47.487178Z","end":"2026-05-08T18:22:49.487821Z","steps":["trace[1354347418] 'agreement among raft nodes before linearized reading' (duration: 2.000555831s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:49.487868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:47.487154Z","time spent":"2.000707396s","remote":"127.0.0.1:38712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/08 18:22:49 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-08T18:22:49.832878Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439928496348029,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T18:22:50.333499Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439928496348029,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T18:22:50.833682Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439928496348029,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T18:22:50.881715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.008882141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-08T18:22:50.881784Z","caller":"traceutil/trace.go:171","msg":"trace[2040364848] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.009020647s","start":"2026-05-08T18:22:48.872747Z","end":"2026-05-08T18:22:50.881768Z","steps":["trace[2040364848] 'agreement among raft nodes before linearized reading' (duration: 2.008933103s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:50.881814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:48.872734Z","time spent":"2.009074588s","remote":"127.0.0.1:38712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/08 18:22:50 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-08T18:22:51.21844Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.501898342s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-08T18:22:51.223722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:49.986597Z","time spent":"1.237119839s","remote":"127.0.0.1:35696","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-08T18:22:51.224163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.896902541s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-08T18:22:51.22423Z","caller":"traceutil/trace.go:171","msg":"trace[1416543066] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1439; }","duration":"3.897006263s","start":"2026-05-08T18:22:47.327211Z","end":"2026-05-08T18:22:51.224217Z","steps":["trace[1416543066] 'range keys from in-memory index tree' (duration: 3.896778277s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:51.224272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:47.327196Z","time spent":"3.897064556s","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-08T18:22:51.224325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.896727366s","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-08T18:22:51.224381Z","caller":"traceutil/trace.go:171","msg":"trace[1116923475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1439; }","duration":"3.896827699s","start":"2026-05-08T18:22:47.327542Z","end":"2026-05-08T18:22:51.22437Z","steps":["trace[1116923475] 'range keys from in-memory index tree' (duration: 3.896573332s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:51.224404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:47.327526Z","time spent":"3.89687331s","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-08T18:22:51.22473Z","caller":"traceutil/trace.go:171","msg":"trace[704438259] transaction","detail":"{read_only:false; response_revision:1440; number_of_response:1; }","duration":"3.896748876s","start":"2026-05-08T18:22:47.327952Z","end":"2026-05-08T18:22:51.2247Z","steps":["trace[704438259] 'process raft request' (duration: 3.89064232s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:51.224859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:47.32794Z","time spent":"3.896843019s","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:22:51.233345Z","caller":"traceutil/trace.go:171","msg":"trace[2020710497] transaction","detail":"{read_only:false; response_revision:1441; number_of_response:1; }","duration":"1.610699311s","start":"2026-05-08T18:22:49.622624Z","end":"2026-05-08T18:22:51.233323Z","steps":["trace[2020710497] 'process raft request' (duration: 1.610550556s)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:51.233375Z","caller":"traceutil/trace.go:171","msg":"trace[1757852453] linearizableReadLoop","detail":"{readStateIndex:1499; appliedIndex:1495; }","duration":"3.90378886s","start":"2026-05-08T18:22:47.329569Z","end":"2026-05-08T18:22:51.233357Z","steps":["trace[1757852453] 'read index received' (duration: 3.889013904s)","trace[1757852453] 'applied index is now lower than readState.Index' (duration: 14.774196ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:22:51.233509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.903932184s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-08T18:22:51.23352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:49.622605Z","time spent":"1.610793444s","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:22:51.233537Z","caller":"traceutil/trace.go:171","msg":"trace[2071386963] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1442; }","duration":"3.903985135s","start":"2026-05-08T18:22:47.329543Z","end":"2026-05-08T18:22:51.233528Z","steps":["trace[2071386963] 'agreement among raft nodes before linearized reading' (duration: 3.903871562s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:51.233578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:47.329531Z","time spent":"3.904030516s","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-08T18:22:51.233394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:49.391051Z","time spent":"1.842338464s","remote":"127.0.0.1:38794","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-08T18:22:51.234769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.900389611s","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":"warn","ts":"2026-05-08T18:22:51.235227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.084653701s","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-08T18:22:51.235255Z","caller":"traceutil/trace.go:171","msg":"trace[1236453567] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1443; }","duration":"3.900885636s","start":"2026-05-08T18:22:47.334359Z","end":"2026-05-08T18:22:51.235244Z","steps":["trace[1236453567] 'agreement among raft nodes before linearized reading' (duration: 3.90033796s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:51.235274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.642601296s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T18:22:51.235273Z","caller":"traceutil/trace.go:171","msg":"trace[1545762536] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1443; }","duration":"3.084719824s","start":"2026-05-08T18:22:48.150541Z","end":"2026-05-08T18:22:51.235261Z","steps":["trace[1545762536] 'agreement among raft nodes before linearized reading' (duration: 3.084636831s)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:51.235296Z","caller":"traceutil/trace.go:171","msg":"trace[804107907] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1443; }","duration":"3.642633476s","start":"2026-05-08T18:22:47.592656Z","end":"2026-05-08T18:22:51.23529Z","steps":["trace[804107907] 'agreement among raft nodes before linearized reading' (duration: 3.642564914s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:51.235275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:47.334353Z","time spent":"3.900917147s","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-08T18:22:51.235304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:48.150527Z","time spent":"3.084769085s","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-08T18:22:51.235321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:47.592651Z","time spent":"3.642664737s","remote":"127.0.0.1:39040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":70,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-05-08T18:22:51.234882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.36254097s","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-08T18:22:51.235387Z","caller":"traceutil/trace.go:171","msg":"trace[686848841] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1443; }","duration":"2.363068835s","start":"2026-05-08T18:22:48.872309Z","end":"2026-05-08T18:22:51.235378Z","steps":["trace[686848841] 'agreement among raft nodes before linearized reading' (duration: 2.3625301s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:51.235497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:48.872294Z","time spent":"2.363193948s","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-08T18:22:51.235018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.842882477s","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-08T18:22:51.235583Z","caller":"traceutil/trace.go:171","msg":"trace[66137373] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1443; }","duration":"2.843506515s","start":"2026-05-08T18:22:48.392069Z","end":"2026-05-08T18:22:51.235576Z","steps":["trace[66137373] 'agreement among raft nodes before linearized reading' (duration: 2.842855966s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:51.235628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:48.392018Z","time spent":"2.843581937s","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T18:22:51.235067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.781232661s","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-08T18:22:51.235686Z","caller":"traceutil/trace.go:171","msg":"trace[1149278913] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1443; }","duration":"2.781877589s","start":"2026-05-08T18:22:48.453801Z","end":"2026-05-08T18:22:51.235678Z","steps":["trace[1149278913] 'agreement among raft nodes before linearized reading' (duration: 2.781239311s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:51.235728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:48.453782Z","time spent":"2.781936981s","remote":"127.0.0.1:39132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-05-08T18:22:51.235097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.608818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-08T18:22:51.235147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.156397011s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T18:22:51.235945Z","caller":"traceutil/trace.go:171","msg":"trace[1884485644] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1443; }","duration":"2.157228445s","start":"2026-05-08T18:22:49.078704Z","end":"2026-05-08T18:22:51.235933Z","steps":["trace[1884485644] 'agreement among raft nodes before linearized reading' (duration: 2.156402481s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:51.236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:49.078679Z","time spent":"2.157308587s","remote":"127.0.0.1:39028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":17,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-08T18:22:51.235145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.838092548s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T18:22:51.236096Z","caller":"traceutil/trace.go:171","msg":"trace[873485434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1443; }","duration":"2.839068647s","start":"2026-05-08T18:22:48.397002Z","end":"2026-05-08T18:22:51.236071Z","steps":["trace[873485434] 'agreement among raft nodes before linearized reading' (duration: 2.838082499s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:51.236185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:48.396974Z","time spent":"2.83917393s","remote":"127.0.0.1:38970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T18:22:51.235194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.60736336s","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-08T18:22:51.23631Z","caller":"traceutil/trace.go:171","msg":"trace[1003568419] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1443; }","duration":"3.608534623s","start":"2026-05-08T18:22:47.627761Z","end":"2026-05-08T18:22:51.236296Z","steps":["trace[1003568419] 'agreement among raft nodes before linearized reading' (duration: 3.607411851s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:51.235205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.462228033s","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-08T18:22:51.236355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:47.627726Z","time spent":"3.608617685s","remote":"127.0.0.1:39014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-05-08T18:22:51.236354Z","caller":"traceutil/trace.go:171","msg":"trace[1614614631] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1443; }","duration":"1.463412166s","start":"2026-05-08T18:22:49.772933Z","end":"2026-05-08T18:22:51.236346Z","steps":["trace[1614614631] 'agreement among raft nodes before linearized reading' (duration: 1.462231803s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:51.2364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:49.772911Z","time spent":"1.463480199s","remote":"127.0.0.1:38970","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-08T18:22:51.235223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.843079585s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:22:51.236527Z","caller":"traceutil/trace.go:171","msg":"trace[1804710537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1443; }","duration":"1.844407272s","start":"2026-05-08T18:22:49.392109Z","end":"2026-05-08T18:22:51.236516Z","steps":["trace[1804710537] 'agreement among raft nodes before linearized reading' (duration: 1.843102985s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:51.236558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:49.392089Z","time spent":"1.844459964s","remote":"127.0.0.1:38728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T18:22:51.235814Z","caller":"traceutil/trace.go:171","msg":"trace[1538766000] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1443; }","duration":"655.313638ms","start":"2026-05-08T18:22:50.580452Z","end":"2026-05-08T18:22:51.235766Z","steps":["trace[1538766000] 'agreement among raft nodes before linearized reading' (duration: 654.604428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:22:51.236855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:22:50.580436Z","time spent":"656.406738ms","remote":"127.0.0.1:38970","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\" "}