{"level":"warn","ts":"2026-04-20T14:29:49.549823Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T14:29:49.549933Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38: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.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38: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-04-20T14:29:49.550007Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T14:29:49.550022Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-04-20T14:29:49.550038Z","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-04-20T14:29:49.550725Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-04-20T14:29:49.550812Z","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":16,"max-cpu-available":16,"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.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38: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-04-20T14:29:49.556312Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.252197ms"} {"level":"info","ts":"2026-04-20T14:29:49.561804Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-04-20T14:29:49.561885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T14:29:49.561962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-04-20T14:29:49.561978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T14:29:49.561995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-04-20T14:29:49.562037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-04-20T14:29:49.569048Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T14:29:49.571472Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T14:29:49.574245Z","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-04-20T14:29:49.576624Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T14:29:49.576841Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T14:29:49.57695Z","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-04-20T14:29:49.577106Z","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-04-20T14:29:49.577125Z","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-04-20T14:29:49.578667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-04-20T14:29:49.579169Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-04-20T14:29:49.580936Z","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-04-20T14:29:49.581151Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T14:29:49.581184Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T14:29:49.581216Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-04-20T14:29:49.581242Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-04-20T14:29:50.062865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T14:29:50.062926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T14:29:50.062969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-04-20T14:29:50.062989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-04-20T14:29:50.062999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-04-20T14:29:50.063015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-04-20T14:29:50.063034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-04-20T14:29:50.064059Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:29:50.06488Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T14:29:50.065048Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T14:29:50.065115Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T14:29:50.065519Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:29:50.065718Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:29:50.065795Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T14:29:50.068422Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T14:29:50.06968Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"info","ts":"2026-04-20T14:29:50.074302Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T14:29:50.074327Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T14:30:16.919472Z","caller":"traceutil/trace.go:171","msg":"trace[1803687014] linearizableReadLoop","detail":"{readStateIndex:483; appliedIndex:482; }","duration":"171.11785ms","start":"2026-04-20T14:30:16.748327Z","end":"2026-04-20T14:30:16.919445Z","steps":["trace[1803687014] 'read index received' (duration: 170.855391ms)","trace[1803687014] 'applied index is now lower than readState.Index' (duration: 261.219µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:30:16.919574Z","caller":"traceutil/trace.go:171","msg":"trace[50890125] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"310.403446ms","start":"2026-04-20T14:30:16.609154Z","end":"2026-04-20T14:30:16.919558Z","steps":["trace[50890125] 'process raft request' (duration: 310.107986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:30:16.91964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.295857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:30:16.919726Z","caller":"traceutil/trace.go:171","msg":"trace[455969799] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:465; }","duration":"171.437882ms","start":"2026-04-20T14:30:16.748273Z","end":"2026-04-20T14:30:16.919711Z","steps":["trace[455969799] 'agreement among raft nodes before linearized reading' (duration: 171.308187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:30:16.920068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:30:16.609141Z","time spent":"310.477028ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:30:23.981448Z","caller":"traceutil/trace.go:171","msg":"trace[1624858646] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:596; }","duration":"307.922093ms","start":"2026-04-20T14:30:23.673508Z","end":"2026-04-20T14:30:23.98143Z","steps":["trace[1624858646] 'read index received' (duration: 307.306609ms)","trace[1624858646] 'applied index is now lower than readState.Index' (duration: 614.924µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:30:23.98157Z","caller":"traceutil/trace.go:171","msg":"trace[1475030591] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"472.303588ms","start":"2026-04-20T14:30:23.509231Z","end":"2026-04-20T14:30:23.981534Z","steps":["trace[1475030591] 'process raft request' (duration: 471.763888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:30:23.98163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.117499ms","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-04-20T14:30:23.98166Z","caller":"traceutil/trace.go:171","msg":"trace[1808745303] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:574; }","duration":"308.203603ms","start":"2026-04-20T14:30:23.673448Z","end":"2026-04-20T14:30:23.981652Z","steps":["trace[1808745303] 'agreement among raft nodes before linearized reading' (duration: 308.087679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:30:23.981684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:30:23.673424Z","time spent":"308.254395ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T14:30:23.98168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.716501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:30:23.981731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:30:23.509208Z","time spent":"472.446773ms","remote":"127.0.0.1:35058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:30:23.981751Z","caller":"traceutil/trace.go:171","msg":"trace[292124066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"204.804724ms","start":"2026-04-20T14:30:23.776928Z","end":"2026-04-20T14:30:23.981733Z","steps":["trace[292124066] 'agreement among raft nodes before linearized reading' (duration: 204.668289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:30:40.641324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.341899ms","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-04-20T14:30:40.641442Z","caller":"traceutil/trace.go:171","msg":"trace[1717584406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:737; }","duration":"204.503666ms","start":"2026-04-20T14:30:40.436915Z","end":"2026-04-20T14:30:40.641419Z","steps":["trace[1717584406] 'range keys from in-memory index tree' (duration: 204.163873ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:30:57.253574Z","caller":"traceutil/trace.go:171","msg":"trace[708571821] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"135.526133ms","start":"2026-04-20T14:30:57.11801Z","end":"2026-04-20T14:30:57.253536Z","steps":["trace[708571821] 'process raft request' (duration: 135.309974ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:32:02.925424Z","caller":"traceutil/trace.go:171","msg":"trace[1993223032] linearizableReadLoop","detail":"{readStateIndex:1156; appliedIndex:1155; }","duration":"150.285542ms","start":"2026-04-20T14:32:02.775113Z","end":"2026-04-20T14:32:02.925398Z","steps":["trace[1993223032] 'read index received' (duration: 150.033342ms)","trace[1993223032] 'applied index is now lower than readState.Index' (duration: 251.31µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:32:02.925514Z","caller":"traceutil/trace.go:171","msg":"trace[385403136] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"221.275276ms","start":"2026-04-20T14:32:02.704201Z","end":"2026-04-20T14:32:02.925476Z","steps":["trace[385403136] 'process raft request' (duration: 221.046497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:32:02.925563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.429027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:32:02.925599Z","caller":"traceutil/trace.go:171","msg":"trace[758339961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1112; }","duration":"150.514631ms","start":"2026-04-20T14:32:02.775074Z","end":"2026-04-20T14:32:02.925589Z","steps":["trace[758339961] 'agreement among raft nodes before linearized reading' (duration: 150.435538ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:32:09.944906Z","caller":"traceutil/trace.go:171","msg":"trace[1316264245] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"144.185599ms","start":"2026-04-20T14:32:09.800703Z","end":"2026-04-20T14:32:09.944889Z","steps":["trace[1316264245] 'process raft request' (duration: 144.087355ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:32:11.23864Z","caller":"traceutil/trace.go:171","msg":"trace[1979812872] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"107.235927ms","start":"2026-04-20T14:32:11.131374Z","end":"2026-04-20T14:32:11.23861Z","steps":["trace[1979812872] 'process raft request' (duration: 107.06708ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:32:18.115009Z","caller":"traceutil/trace.go:171","msg":"trace[1427070121] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"138.65019ms","start":"2026-04-20T14:32:17.976313Z","end":"2026-04-20T14:32:18.114963Z","steps":["trace[1427070121] 'process raft request' (duration: 138.473252ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:32:20.295207Z","caller":"traceutil/trace.go:171","msg":"trace[1330271066] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"172.005663ms","start":"2026-04-20T14:32:20.123181Z","end":"2026-04-20T14:32:20.295187Z","steps":["trace[1330271066] 'process raft request' (duration: 171.776414ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:32:21.69279Z","caller":"traceutil/trace.go:171","msg":"trace[702319786] linearizableReadLoop","detail":"{readStateIndex:1220; appliedIndex:1219; }","duration":"353.501121ms","start":"2026-04-20T14:32:21.339269Z","end":"2026-04-20T14:32:21.69277Z","steps":["trace[702319786] 'read index received' (duration: 353.322933ms)","trace[702319786] 'applied index is now lower than readState.Index' (duration: 177.408µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:32:21.693005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.73089ms","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-04-20T14:32:21.69304Z","caller":"traceutil/trace.go:171","msg":"trace[2098691453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1172; }","duration":"353.819733ms","start":"2026-04-20T14:32:21.33921Z","end":"2026-04-20T14:32:21.69303Z","steps":["trace[2098691453] 'agreement among raft nodes before linearized reading' (duration: 353.642726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:32:21.693078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:32:21.339187Z","time spent":"353.883916ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T14:32:21.693124Z","caller":"traceutil/trace.go:171","msg":"trace[392928848] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"555.757089ms","start":"2026-04-20T14:32:21.137351Z","end":"2026-04-20T14:32:21.693108Z","steps":["trace[392928848] 'process raft request' (duration: 555.30167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:32:21.69322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:32:21.137326Z","time spent":"555.836182ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:32:22.414377Z","caller":"traceutil/trace.go:171","msg":"trace[1091789708] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"112.86214ms","start":"2026-04-20T14:32:22.301493Z","end":"2026-04-20T14:32:22.414356Z","steps":["trace[1091789708] 'process raft request' (duration: 112.730195ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:32:27.350671Z","caller":"traceutil/trace.go:171","msg":"trace[1987974839] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"125.934142ms","start":"2026-04-20T14:32:27.224714Z","end":"2026-04-20T14:32:27.350649Z","steps":["trace[1987974839] 'process raft request' (duration: 125.795727ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:32:27.929095Z","caller":"traceutil/trace.go:171","msg":"trace[114884459] linearizableReadLoop","detail":"{readStateIndex:1243; appliedIndex:1242; }","duration":"153.402092ms","start":"2026-04-20T14:32:27.775663Z","end":"2026-04-20T14:32:27.929065Z","steps":["trace[114884459] 'read index received' (duration: 153.162602ms)","trace[114884459] 'applied index is now lower than readState.Index' (duration: 237.95µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:32:27.929121Z","caller":"traceutil/trace.go:171","msg":"trace[1053174262] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"157.142517ms","start":"2026-04-20T14:32:27.771955Z","end":"2026-04-20T14:32:27.929097Z","steps":["trace[1053174262] 'process raft request' (duration: 156.903017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:32:27.929298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.60628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:32:27.929378Z","caller":"traceutil/trace.go:171","msg":"trace[657609757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1194; }","duration":"153.727835ms","start":"2026-04-20T14:32:27.775632Z","end":"2026-04-20T14:32:27.92936Z","steps":["trace[657609757] 'agreement among raft nodes before linearized reading' (duration: 153.6009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:32:27.929421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.153838ms","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-04-20T14:32:27.929458Z","caller":"traceutil/trace.go:171","msg":"trace[2111174008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1194; }","duration":"123.237111ms","start":"2026-04-20T14:32:27.806213Z","end":"2026-04-20T14:32:27.92945Z","steps":["trace[2111174008] 'agreement among raft nodes before linearized reading' (duration: 123.110945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:32:27.929506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.723764ms","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-04-20T14:32:27.92962Z","caller":"traceutil/trace.go:171","msg":"trace[2069535422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1194; }","duration":"139.881081ms","start":"2026-04-20T14:32:27.789705Z","end":"2026-04-20T14:32:27.929586Z","steps":["trace[2069535422] 'agreement among raft nodes before linearized reading' (duration: 139.61489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:32:38.468959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.98861ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-20T14:32:42.147374Z","caller":"traceutil/trace.go:171","msg":"trace[368095976] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"112.36232ms","start":"2026-04-20T14:32:42.034993Z","end":"2026-04-20T14:32:42.147355Z","steps":["trace[368095976] 'process raft request' (duration: 112.240715ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:32:55.024509Z","caller":"traceutil/trace.go:171","msg":"trace[142749362] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1335; }","duration":"250.183436ms","start":"2026-04-20T14:32:54.774296Z","end":"2026-04-20T14:32:55.024479Z","steps":["trace[142749362] 'read index received' (duration: 249.939916ms)","trace[142749362] 'applied index is now lower than readState.Index' (duration: 241.09µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:32:55.024584Z","caller":"traceutil/trace.go:171","msg":"trace[1705580329] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"552.718782ms","start":"2026-04-20T14:32:54.471836Z","end":"2026-04-20T14:32:55.024555Z","steps":["trace[1705580329] 'process raft request' (duration: 552.363267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:32:55.024726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.408376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:32:55.024761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.19477ms","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":"warn","ts":"2026-04-20T14:32:55.024765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:32:54.471803Z","time spent":"552.869489ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:32:55.02479Z","caller":"traceutil/trace.go:171","msg":"trace[1322815298] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1281; }","duration":"164.262202ms","start":"2026-04-20T14:32:54.86052Z","end":"2026-04-20T14:32:55.024783Z","steps":["trace[1322815298] 'agreement among raft nodes before linearized reading' (duration: 164.154868ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:32:55.024797Z","caller":"traceutil/trace.go:171","msg":"trace[1210469772] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1281; }","duration":"250.5189ms","start":"2026-04-20T14:32:54.774261Z","end":"2026-04-20T14:32:55.02478Z","steps":["trace[1210469772] 'agreement among raft nodes before linearized reading' (duration: 250.376894ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:32:57.550447Z","caller":"traceutil/trace.go:171","msg":"trace[1229522142] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"127.952826ms","start":"2026-04-20T14:32:57.422458Z","end":"2026-04-20T14:32:57.550411Z","steps":["trace[1229522142] 'process raft request' (duration: 127.78929ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:32:57.876243Z","caller":"traceutil/trace.go:171","msg":"trace[1661784715] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1346; }","duration":"101.820793ms","start":"2026-04-20T14:32:57.774405Z","end":"2026-04-20T14:32:57.876225Z","steps":["trace[1661784715] 'read index received' (duration: 101.692475ms)","trace[1661784715] 'applied index is now lower than readState.Index' (duration: 127.598µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:32:57.876301Z","caller":"traceutil/trace.go:171","msg":"trace[1871178608] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"213.922372ms","start":"2026-04-20T14:32:57.662366Z","end":"2026-04-20T14:32:57.876288Z","steps":["trace[1871178608] 'process raft request' (duration: 213.751266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:32:57.876347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.935899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:32:57.876371Z","caller":"traceutil/trace.go:171","msg":"trace[1407466240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1292; }","duration":"102.000419ms","start":"2026-04-20T14:32:57.774363Z","end":"2026-04-20T14:32:57.876363Z","steps":["trace[1407466240] 'agreement among raft nodes before linearized reading' (duration: 101.93279ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:33:02.586759Z","caller":"traceutil/trace.go:171","msg":"trace[1861147652] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"203.603264ms","start":"2026-04-20T14:33:02.383135Z","end":"2026-04-20T14:33:02.586738Z","steps":["trace[1861147652] 'process raft request' (duration: 184.197183ms)","trace[1861147652] 'compare' (duration: 19.265044ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:33:05.282437Z","caller":"traceutil/trace.go:171","msg":"trace[1514760562] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"109.449527ms","start":"2026-04-20T14:33:05.172966Z","end":"2026-04-20T14:33:05.282415Z","steps":["trace[1514760562] 'process raft request' (duration: 75.746355ms)","trace[1514760562] 'compare' (duration: 33.600554ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:33:05.375759Z","caller":"traceutil/trace.go:171","msg":"trace[2027490772] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"189.015369ms","start":"2026-04-20T14:33:05.186724Z","end":"2026-04-20T14:33:05.37574Z","steps":["trace[2027490772] 'process raft request' (duration: 188.892752ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:33:07.20461Z","caller":"traceutil/trace.go:171","msg":"trace[975020608] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"103.144324ms","start":"2026-04-20T14:33:07.101424Z","end":"2026-04-20T14:33:07.204568Z","steps":["trace[975020608] 'process raft request' (duration: 102.87138ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:33:11.592049Z","caller":"traceutil/trace.go:171","msg":"trace[1485066530] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"118.479592ms","start":"2026-04-20T14:33:11.473537Z","end":"2026-04-20T14:33:11.592016Z","steps":["trace[1485066530] 'process raft request' (duration: 61.707618ms)","trace[1485066530] 'compare' (duration: 56.551399ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:33:27.934849Z","caller":"traceutil/trace.go:171","msg":"trace[1207729904] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"202.801021ms","start":"2026-04-20T14:33:27.73202Z","end":"2026-04-20T14:33:27.934821Z","steps":["trace[1207729904] 'process raft request' (duration: 125.304735ms)","trace[1207729904] 'compare' (duration: 77.282941ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:33:28.181353Z","caller":"traceutil/trace.go:171","msg":"trace[1083261481] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"207.96495ms","start":"2026-04-20T14:33:27.973363Z","end":"2026-04-20T14:33:28.181328Z","steps":["trace[1083261481] 'process raft request' (duration: 109.477747ms)","trace[1083261481] 'compare' (duration: 98.395935ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:33:29.730344Z","caller":"traceutil/trace.go:171","msg":"trace[1014180553] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"141.156822ms","start":"2026-04-20T14:33:29.58916Z","end":"2026-04-20T14:33:29.730316Z","steps":["trace[1014180553] 'process raft request' (duration: 140.914227ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:33:29.888247Z","caller":"traceutil/trace.go:171","msg":"trace[922055190] linearizableReadLoop","detail":"{readStateIndex:1459; appliedIndex:1458; }","duration":"125.807244ms","start":"2026-04-20T14:33:29.762419Z","end":"2026-04-20T14:33:29.888227Z","steps":["trace[922055190] 'read index received' (duration: 38.112127ms)","trace[922055190] 'applied index is now lower than readState.Index' (duration: 87.694447ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:33:29.888357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.910682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T14:33:29.888382Z","caller":"traceutil/trace.go:171","msg":"trace[1676385980] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1397; }","duration":"125.958431ms","start":"2026-04-20T14:33:29.762415Z","end":"2026-04-20T14:33:29.888373Z","steps":["trace[1676385980] 'agreement among raft nodes before linearized reading' (duration: 125.890132ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:33:29.888521Z","caller":"traceutil/trace.go:171","msg":"trace[1708101775] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"153.973875ms","start":"2026-04-20T14:33:29.734517Z","end":"2026-04-20T14:33:29.888491Z","steps":["trace[1708101775] 'process raft request' (duration: 65.990914ms)","trace[1708101775] 'compare' (duration: 87.55428ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:33:31.235379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.081042ms","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-04-20T14:33:31.235488Z","caller":"traceutil/trace.go:171","msg":"trace[626657143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1400; }","duration":"243.230368ms","start":"2026-04-20T14:33:30.992229Z","end":"2026-04-20T14:33:31.23546Z","steps":["trace[626657143] 'range keys from in-memory index tree' (duration: 242.897235ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:33:32.123398Z","caller":"traceutil/trace.go:171","msg":"trace[524001491] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"165.616785ms","start":"2026-04-20T14:33:31.957749Z","end":"2026-04-20T14:33:32.123365Z","steps":["trace[524001491] 'process raft request' (duration: 154.25412ms)","trace[524001491] 'compare' (duration: 11.238527ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:33:32.408604Z","caller":"traceutil/trace.go:171","msg":"trace[1622345560] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"129.100433ms","start":"2026-04-20T14:33:32.279478Z","end":"2026-04-20T14:33:32.408578Z","steps":["trace[1622345560] 'process raft request' (duration: 128.951966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:33:33.285205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.197486ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:33:33.285375Z","caller":"traceutil/trace.go:171","msg":"trace[1274009193] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"198.033473ms","start":"2026-04-20T14:33:33.087293Z","end":"2026-04-20T14:33:33.285327Z","steps":["trace[1274009193] 'process raft request' (duration: 26.995916ms)","trace[1274009193] 'compare' (duration: 170.095128ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:33:33.917709Z","caller":"traceutil/trace.go:171","msg":"trace[1774163228] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"130.223469ms","start":"2026-04-20T14:33:33.787462Z","end":"2026-04-20T14:33:33.917685Z","steps":["trace[1774163228] 'process raft request' (duration: 130.101112ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:33:34.192536Z","caller":"traceutil/trace.go:171","msg":"trace[252610108] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"154.841167ms","start":"2026-04-20T14:33:34.037605Z","end":"2026-04-20T14:33:34.192446Z","steps":["trace[252610108] 'process raft request' (duration: 114.334882ms)","trace[252610108] 'compare' (duration: 40.24797ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:33:34.58376Z","caller":"traceutil/trace.go:171","msg":"trace[1837381956] linearizableReadLoop","detail":"{readStateIndex:1474; appliedIndex:1473; }","duration":"275.060781ms","start":"2026-04-20T14:33:34.30868Z","end":"2026-04-20T14:33:34.583741Z","steps":["trace[1837381956] 'read index received' (duration: 274.911304ms)","trace[1837381956] 'applied index is now lower than readState.Index' (duration: 148.787µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:33:34.584417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.692567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:33:34.58453Z","caller":"traceutil/trace.go:171","msg":"trace[48930171] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1411; }","duration":"275.866513ms","start":"2026-04-20T14:33:34.308641Z","end":"2026-04-20T14:33:34.584507Z","steps":["trace[48930171] 'agreement among raft nodes before linearized reading' (duration: 275.677467ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:33:34.58453Z","caller":"traceutil/trace.go:171","msg":"trace[96683889] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"387.479674ms","start":"2026-04-20T14:33:34.197032Z","end":"2026-04-20T14:33:34.584511Z","steps":["trace[96683889] 'process raft request' (duration: 386.605723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:33:34.58458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.710216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:33:34.584666Z","caller":"traceutil/trace.go:171","msg":"trace[199495087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1411; }","duration":"119.847593ms","start":"2026-04-20T14:33:34.4648Z","end":"2026-04-20T14:33:34.584648Z","steps":["trace[199495087] 'agreement among raft nodes before linearized reading' (duration: 119.667987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:33:34.584687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:33:34.197004Z","time spent":"387.597241ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:33:34.584443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.083104ms","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-04-20T14:33:34.585003Z","caller":"traceutil/trace.go:171","msg":"trace[697396458] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1411; }","duration":"172.650282ms","start":"2026-04-20T14:33:34.412331Z","end":"2026-04-20T14:33:34.584981Z","steps":["trace[697396458] 'agreement among raft nodes before linearized reading' (duration: 171.963607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:33:34.971817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.521408ms","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-04-20T14:33:34.971923Z","caller":"traceutil/trace.go:171","msg":"trace[2077932238] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1412; }","duration":"101.671034ms","start":"2026-04-20T14:33:34.870235Z","end":"2026-04-20T14:33:34.971906Z","steps":["trace[2077932238] 'range keys from in-memory index tree' (duration: 101.385771ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:33:35.111311Z","caller":"traceutil/trace.go:171","msg":"trace[1866630620] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"135.490094ms","start":"2026-04-20T14:33:34.975795Z","end":"2026-04-20T14:33:35.111286Z","steps":["trace[1866630620] 'process raft request' (duration: 135.344568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:33:35.853271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.570827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:33:35.853349Z","caller":"traceutil/trace.go:171","msg":"trace[40186889] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1414; }","duration":"346.703804ms","start":"2026-04-20T14:33:35.506627Z","end":"2026-04-20T14:33:35.853331Z","steps":["trace[40186889] 'count revisions from in-memory index tree' (duration: 346.496199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:33:35.853394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:33:35.506606Z","time spent":"346.777363ms","remote":"127.0.0.1:35160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-04-20T14:33:36.088997Z","caller":"traceutil/trace.go:171","msg":"trace[1098244163] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"163.176466ms","start":"2026-04-20T14:33:35.9258Z","end":"2026-04-20T14:33:36.088977Z","steps":["trace[1098244163] 'process raft request' (duration: 163.055819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:33:36.360695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.971672ms","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-04-20T14:33:36.360786Z","caller":"traceutil/trace.go:171","msg":"trace[579987084] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1415; }","duration":"200.121578ms","start":"2026-04-20T14:33:36.160645Z","end":"2026-04-20T14:33:36.360767Z","steps":["trace[579987084] 'count revisions from in-memory index tree' (duration: 199.896263ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:33:36.360884Z","caller":"traceutil/trace.go:171","msg":"trace[1983239695] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"161.645081ms","start":"2026-04-20T14:33:36.199203Z","end":"2026-04-20T14:33:36.360848Z","steps":["trace[1983239695] 'process raft request' (duration: 148.525474ms)","trace[1983239695] 'compare' (duration: 12.741295ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:33:36.780741Z","caller":"traceutil/trace.go:171","msg":"trace[502904400] linearizableReadLoop","detail":"{readStateIndex:1480; appliedIndex:1479; }","duration":"191.124854ms","start":"2026-04-20T14:33:36.589593Z","end":"2026-04-20T14:33:36.780718Z","steps":["trace[502904400] 'read index received' (duration: 190.932968ms)","trace[502904400] 'applied index is now lower than readState.Index' (duration: 191.136µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:33:36.780862Z","caller":"traceutil/trace.go:171","msg":"trace[911803100] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"222.216632ms","start":"2026-04-20T14:33:36.558616Z","end":"2026-04-20T14:33:36.780833Z","steps":["trace[911803100] 'process raft request' (duration: 221.960378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:33:36.781001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.390147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-20T14:33:36.781089Z","caller":"traceutil/trace.go:171","msg":"trace[1121473445] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1417; }","duration":"191.535445ms","start":"2026-04-20T14:33:36.589537Z","end":"2026-04-20T14:33:36.781072Z","steps":["trace[1121473445] 'agreement among raft nodes before linearized reading' (duration: 191.31039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:33:36.781148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.32424ms","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-04-20T14:33:36.781193Z","caller":"traceutil/trace.go:171","msg":"trace[1339436404] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1417; }","duration":"115.407448ms","start":"2026-04-20T14:33:36.665771Z","end":"2026-04-20T14:33:36.781179Z","steps":["trace[1339436404] 'agreement among raft nodes before linearized reading' (duration: 115.216643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:33:36.965589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.306389ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:33:36.965704Z","caller":"traceutil/trace.go:171","msg":"trace[330654709] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"180.955124ms","start":"2026-04-20T14:33:36.784737Z","end":"2026-04-20T14:33:36.965693Z","steps":["trace[330654709] 'process raft request' (duration: 180.922914ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:33:36.965889Z","caller":"traceutil/trace.go:171","msg":"trace[862464608] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"181.805664ms","start":"2026-04-20T14:33:36.784046Z","end":"2026-04-20T14:33:36.965852Z","steps":["trace[862464608] 'process raft request' (duration: 65.170043ms)","trace[862464608] 'compare' (duration: 116.209181ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:33:37.327303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.039302ms","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-04-20T14:33:37.327377Z","caller":"traceutil/trace.go:171","msg":"trace[675737200] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1419; }","duration":"212.165079ms","start":"2026-04-20T14:33:37.115197Z","end":"2026-04-20T14:33:37.327362Z","steps":["trace[675737200] 'range keys from in-memory index tree' (duration: 211.873766ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:33:37.468645Z","caller":"traceutil/trace.go:171","msg":"trace[1941030647] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"138.055ms","start":"2026-04-20T14:33:37.330559Z","end":"2026-04-20T14:33:37.468614Z","steps":["trace[1941030647] 'process raft request' (duration: 137.828435ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:33:37.702364Z","caller":"traceutil/trace.go:171","msg":"trace[1787918456] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"228.483287ms","start":"2026-04-20T14:33:37.473851Z","end":"2026-04-20T14:33:37.702334Z","steps":["trace[1787918456] 'process raft request' (duration: 136.64523ms)","trace[1787918456] 'compare' (duration: 91.71851ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:33:38.445568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.818896ms","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-04-20T14:33:38.445635Z","caller":"traceutil/trace.go:171","msg":"trace[989999135] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1422; }","duration":"236.920184ms","start":"2026-04-20T14:33:38.2087Z","end":"2026-04-20T14:33:38.44562Z","steps":["trace[989999135] 'count revisions from in-memory index tree' (duration: 236.744278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:33:38.640252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.513355ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-20T14:33:38.776148Z","caller":"traceutil/trace.go:171","msg":"trace[1444123494] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"410.719481ms","start":"2026-04-20T14:33:38.365406Z","end":"2026-04-20T14:33:38.776125Z","steps":["trace[1444123494] 'process raft request' (duration: 410.498436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:33:38.776293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:33:38.365386Z","time spent":"410.839579ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:33:39.156227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.70859ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:33:39.156306Z","caller":"traceutil/trace.go:171","msg":"trace[439763553] linearizableReadLoop","detail":"{readStateIndex:1488; appliedIndex:1487; }","duration":"187.326796ms","start":"2026-04-20T14:33:38.968967Z","end":"2026-04-20T14:33:39.156293Z","steps":["trace[439763553] 'read index received' (duration: 43.439µs)","trace[439763553] 'applied index is now lower than readState.Index' (duration: 187.282217ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:33:39.156372Z","caller":"traceutil/trace.go:171","msg":"trace[1444850596] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"351.866743ms","start":"2026-04-20T14:33:38.804478Z","end":"2026-04-20T14:33:39.156345Z","steps":["trace[1444850596] 'process raft request' (duration: 140.973126ms)","trace[1444850596] 'compare' (duration: 210.545114ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:33:39.156455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.494082ms","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-04-20T14:33:39.156476Z","caller":"traceutil/trace.go:171","msg":"trace[917255143] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1424; }","duration":"187.560171ms","start":"2026-04-20T14:33:38.968909Z","end":"2026-04-20T14:33:39.156469Z","steps":["trace[917255143] 'agreement among raft nodes before linearized reading' (duration: 187.453583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:33:39.156491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:33:38.804457Z","time spent":"351.974791ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:33:39.156613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.58531ms","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-04-20T14:33:39.156669Z","caller":"traceutil/trace.go:171","msg":"trace[1433016127] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1424; }","duration":"187.676608ms","start":"2026-04-20T14:33:38.968977Z","end":"2026-04-20T14:33:39.156653Z","steps":["trace[1433016127] 'agreement among raft nodes before linearized reading' (duration: 187.509511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:33:39.546058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.238217ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:33:39.546448Z","caller":"traceutil/trace.go:171","msg":"trace[46973015] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"386.148683ms","start":"2026-04-20T14:33:39.160285Z","end":"2026-04-20T14:33:39.546433Z","steps":["trace[46973015] 'process raft request' (duration: 229.455516ms)","trace[46973015] 'compare' (duration: 156.10992ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:33:39.546715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:33:39.16026Z","time spent":"386.429797ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:33:45.578627Z","caller":"traceutil/trace.go:171","msg":"trace[1390530469] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"295.945091ms","start":"2026-04-20T14:33:45.282652Z","end":"2026-04-20T14:33:45.578597Z","steps":["trace[1390530469] 'process raft request' (duration: 295.738095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:33:46.027319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.712243ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:33:46.027444Z","caller":"traceutil/trace.go:171","msg":"trace[11942604] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"446.087798ms","start":"2026-04-20T14:33:45.581336Z","end":"2026-04-20T14:33:46.027424Z","steps":["trace[11942604] 'process raft request' (duration: 211.109231ms)","trace[11942604] 'compare' (duration: 234.584646ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:33:46.027522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:33:45.581321Z","time spent":"446.164957ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:33:46.710492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.408259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:33:46.710614Z","caller":"traceutil/trace.go:171","msg":"trace[1164796313] linearizableReadLoop","detail":"{readStateIndex:1511; appliedIndex:1509; }","duration":"932.762101ms","start":"2026-04-20T14:33:45.777834Z","end":"2026-04-20T14:33:46.710596Z","steps":["trace[1164796313] 'read index received' (duration: 14.715472ms)","trace[1164796313] 'applied index is now lower than readState.Index' (duration: 918.045589ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:33:46.7107Z","caller":"traceutil/trace.go:171","msg":"trace[1838684277] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"1.128499265s","start":"2026-04-20T14:33:45.582167Z","end":"2026-04-20T14:33:46.710666Z","steps":["trace[1838684277] 'process raft request' (duration: 560.8357ms)","trace[1838684277] 'compare' (duration: 567.286353ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:33:46.710892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.046284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:33:46.710925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:33:45.582159Z","time spent":"1.128641442s","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:33:46.710994Z","caller":"traceutil/trace.go:171","msg":"trace[952169560] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1446; }","duration":"933.196911ms","start":"2026-04-20T14:33:45.777768Z","end":"2026-04-20T14:33:46.710964Z","steps":["trace[952169560] 'agreement among raft nodes before linearized reading' (duration: 933.043735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:33:46.711055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:33:45.77774Z","time spent":"933.30259ms","remote":"127.0.0.1:34970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T14:33:46.711197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.184685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:33:46.711247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.259056ms","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-04-20T14:33:46.711286Z","caller":"traceutil/trace.go:171","msg":"trace[1474778640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1446; }","duration":"898.334864ms","start":"2026-04-20T14:33:45.812941Z","end":"2026-04-20T14:33:46.711276Z","steps":["trace[1474778640] 'agreement among raft nodes before linearized reading' (duration: 898.034611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:33:46.71128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.726215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-20T14:33:46.711324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:33:45.812921Z","time spent":"898.396283ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-20T14:33:46.711316Z","caller":"traceutil/trace.go:171","msg":"trace[1306246787] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1446; }","duration":"812.329851ms","start":"2026-04-20T14:33:45.898944Z","end":"2026-04-20T14:33:46.711274Z","steps":["trace[1306246787] 'agreement among raft nodes before linearized reading' (duration: 812.155245ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:33:46.71136Z","caller":"traceutil/trace.go:171","msg":"trace[207881227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1446; }","duration":"490.844552ms","start":"2026-04-20T14:33:46.22049Z","end":"2026-04-20T14:33:46.711335Z","steps":["trace[207881227] 'agreement among raft nodes before linearized reading' (duration: 490.569298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:33:46.711398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:33:46.220467Z","time spent":"490.922291ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T14:33:46.711391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:33:45.898927Z","time spent":"812.444529ms","remote":"127.0.0.1:41452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"info","ts":"2026-04-20T14:33:47.153777Z","caller":"traceutil/trace.go:171","msg":"trace[1875067295] transaction","detail":"{read_only:false; response_revision:1447; number_of_response:1; }","duration":"438.051282ms","start":"2026-04-20T14:33:46.715688Z","end":"2026-04-20T14:33:47.153739Z","steps":["trace[1875067295] 'process raft request' (duration: 435.318991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:33:47.154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:33:46.715664Z","time spent":"438.201629ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:33:47.15454Z","caller":"traceutil/trace.go:171","msg":"trace[833434356] transaction","detail":"{read_only:false; response_revision:1448; number_of_response:1; }","duration":"438.800046ms","start":"2026-04-20T14:33:46.715718Z","end":"2026-04-20T14:33:47.154518Z","steps":["trace[833434356] 'process raft request' (duration: 438.61544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:33:47.154717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:33:46.715701Z","time spent":"438.907984ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:33:47.154856Z","caller":"traceutil/trace.go:171","msg":"trace[442885834] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"358.413632ms","start":"2026-04-20T14:33:46.796411Z","end":"2026-04-20T14:33:47.154825Z","steps":["trace[442885834] 'process raft request' (duration: 358.06772ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:33:47.154935Z","caller":"traceutil/trace.go:171","msg":"trace[1886332069] transaction","detail":"{read_only:false; response_revision:1450; number_of_response:1; }","duration":"233.684305ms","start":"2026-04-20T14:33:46.921239Z","end":"2026-04-20T14:33:47.154923Z","steps":["trace[1886332069] 'process raft request' (duration: 233.42703ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:33:47.154935Z","caller":"traceutil/trace.go:171","msg":"trace[124770122] linearizableReadLoop","detail":"{readStateIndex:1514; appliedIndex:1511; }","duration":"299.979213ms","start":"2026-04-20T14:33:46.854938Z","end":"2026-04-20T14:33:47.154917Z","steps":["trace[124770122] 'read index received' (duration: 296.123477ms)","trace[124770122] 'applied index is now lower than readState.Index' (duration: 3.854336ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:33:47.155112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:33:46.796391Z","time spent":"358.54645ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:33:47.155119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.18471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:33:47.155169Z","caller":"traceutil/trace.go:171","msg":"trace[1518483753] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1450; }","duration":"300.292908ms","start":"2026-04-20T14:33:46.854863Z","end":"2026-04-20T14:33:47.155156Z","steps":["trace[1518483753] 'agreement among raft nodes before linearized reading' (duration: 300.14572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:33:47.155202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:33:46.854822Z","time spent":"300.371496ms","remote":"127.0.0.1:41418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:33:55.594997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.149595ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:33:55.595139Z","caller":"traceutil/trace.go:171","msg":"trace[2109286576] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"331.801646ms","start":"2026-04-20T14:33:55.26331Z","end":"2026-04-20T14:33:55.595111Z","steps":["trace[2109286576] 'process raft request' (duration: 198.439246ms)","trace[2109286576] 'compare' (duration: 133.014358ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:33:55.595258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:33:55.263288Z","time spent":"331.912554ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:34:00.91762Z","caller":"traceutil/trace.go:171","msg":"trace[266754317] transaction","detail":"{read_only:false; response_revision:1494; number_of_response:1; }","duration":"137.546031ms","start":"2026-04-20T14:34:00.780048Z","end":"2026-04-20T14:34:00.917594Z","steps":["trace[266754317] 'process raft request' (duration: 137.356035ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:22.038469Z","caller":"traceutil/trace.go:171","msg":"trace[1089559644] transaction","detail":"{read_only:false; response_revision:1563; number_of_response:1; }","duration":"236.075548ms","start":"2026-04-20T14:34:21.802357Z","end":"2026-04-20T14:34:22.038432Z","steps":["trace[1089559644] 'process raft request' (duration: 235.941594ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:22.085826Z","caller":"traceutil/trace.go:171","msg":"trace[321693591] linearizableReadLoop","detail":"{readStateIndex:1636; appliedIndex:1634; }","duration":"224.349816ms","start":"2026-04-20T14:34:21.861446Z","end":"2026-04-20T14:34:22.085796Z","steps":["trace[321693591] 'read index received' (duration: 176.879371ms)","trace[321693591] 'applied index is now lower than readState.Index' (duration: 47.469145ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:34:22.085927Z","caller":"traceutil/trace.go:171","msg":"trace[939418249] transaction","detail":"{read_only:false; response_revision:1564; number_of_response:1; }","duration":"281.131834ms","start":"2026-04-20T14:34:21.804745Z","end":"2026-04-20T14:34:22.085877Z","steps":["trace[939418249] 'process raft request' (duration: 280.892497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:22.086042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.570852ms","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-04-20T14:34:22.08613Z","caller":"traceutil/trace.go:171","msg":"trace[976511634] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1564; }","duration":"224.732758ms","start":"2026-04-20T14:34:21.861379Z","end":"2026-04-20T14:34:22.086112Z","steps":["trace[976511634] 'agreement among raft nodes before linearized reading' (duration: 224.554932ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:22.46733Z","caller":"traceutil/trace.go:171","msg":"trace[1979959293] transaction","detail":"{read_only:false; response_revision:1565; number_of_response:1; }","duration":"131.455826ms","start":"2026-04-20T14:34:22.335596Z","end":"2026-04-20T14:34:22.467051Z","steps":["trace[1979959293] 'process raft request' (duration: 131.314852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:28.256995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.563893ms","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-04-20T14:34:28.257024Z","caller":"traceutil/trace.go:171","msg":"trace[1091493457] transaction","detail":"{read_only:false; response_revision:1583; number_of_response:1; }","duration":"118.471578ms","start":"2026-04-20T14:34:28.138519Z","end":"2026-04-20T14:34:28.256991Z","steps":["trace[1091493457] 'process raft request' (duration: 94.361204ms)","trace[1091493457] 'compare' (duration: 23.959729ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:34:28.257074Z","caller":"traceutil/trace.go:171","msg":"trace[2122734313] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1582; }","duration":"150.691957ms","start":"2026-04-20T14:34:28.106363Z","end":"2026-04-20T14:34:28.257055Z","steps":["trace[2122734313] 'range keys from in-memory index tree' (duration: 150.399639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:28.788642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.876704ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-20T14:34:28.788792Z","caller":"traceutil/trace.go:171","msg":"trace[15218436] linearizableReadLoop","detail":"{readStateIndex:1658; appliedIndex:1657; }","duration":"349.526549ms","start":"2026-04-20T14:34:28.43924Z","end":"2026-04-20T14:34:28.788766Z","steps":["trace[15218436] 'read index received' (duration: 115.616014ms)","trace[15218436] 'applied index is now lower than readState.Index' (duration: 233.907705ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:34:28.789077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.809507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:34:28.789156Z","caller":"traceutil/trace.go:171","msg":"trace[1564626419] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1584; }","duration":"349.93019ms","start":"2026-04-20T14:34:28.439207Z","end":"2026-04-20T14:34:28.789137Z","steps":["trace[1564626419] 'agreement among raft nodes before linearized reading' (duration: 349.670203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:28.78908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.832089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T14:34:28.789268Z","caller":"traceutil/trace.go:171","msg":"trace[565521829] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1584; }","duration":"301.054184ms","start":"2026-04-20T14:34:28.48819Z","end":"2026-04-20T14:34:28.789244Z","steps":["trace[565521829] 'agreement among raft nodes before linearized reading' (duration: 300.723665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:28.789333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:28.488176Z","time spent":"301.136177ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T14:34:28.789339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:28.43919Z","time spent":"350.001803ms","remote":"127.0.0.1:41542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-20T14:34:34.555323Z","caller":"traceutil/trace.go:171","msg":"trace[426452115] linearizableReadLoop","detail":"{readStateIndex:1679; appliedIndex:1678; }","duration":"112.029699ms","start":"2026-04-20T14:34:34.443269Z","end":"2026-04-20T14:34:34.555298Z","steps":["trace[426452115] 'read index received' (duration: 111.938767ms)","trace[426452115] 'applied index is now lower than readState.Index' (duration: 89.432µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:34:34.555429Z","caller":"traceutil/trace.go:171","msg":"trace[1172280454] transaction","detail":"{read_only:false; response_revision:1604; number_of_response:1; }","duration":"230.942838ms","start":"2026-04-20T14:34:34.324432Z","end":"2026-04-20T14:34:34.555374Z","steps":["trace[1172280454] 'process raft request' (duration: 230.728982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:34.555544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.242255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:34:34.555626Z","caller":"traceutil/trace.go:171","msg":"trace[704684559] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1604; }","duration":"112.41589ms","start":"2026-04-20T14:34:34.44319Z","end":"2026-04-20T14:34:34.555606Z","steps":["trace[704684559] 'agreement among raft nodes before linearized reading' (duration: 112.227965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:40.363469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.023983ms","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-04-20T14:34:40.363561Z","caller":"traceutil/trace.go:171","msg":"trace[1194803422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1622; }","duration":"204.154077ms","start":"2026-04-20T14:34:40.15938Z","end":"2026-04-20T14:34:40.363534Z","steps":["trace[1194803422] 'range keys from in-memory index tree' (duration: 203.845327ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:40.472325Z","caller":"traceutil/trace.go:171","msg":"trace[698129305] transaction","detail":"{read_only:false; response_revision:1623; number_of_response:1; }","duration":"104.469738ms","start":"2026-04-20T14:34:40.367827Z","end":"2026-04-20T14:34:40.472296Z","steps":["trace[698129305] 'process raft request' (duration: 104.311073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:40.734866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.317611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-20T14:34:40.734973Z","caller":"traceutil/trace.go:171","msg":"trace[387974733] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1623; }","duration":"156.494176ms","start":"2026-04-20T14:34:40.578455Z","end":"2026-04-20T14:34:40.73495Z","steps":["trace[387974733] 'range keys from in-memory index tree' (duration: 156.080945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:41.024364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.457848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T14:34:41.024488Z","caller":"traceutil/trace.go:171","msg":"trace[797954851] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1624; }","duration":"153.645453ms","start":"2026-04-20T14:34:40.870815Z","end":"2026-04-20T14:34:41.024461Z","steps":["trace[797954851] 'range keys from in-memory index tree' (duration: 153.169489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:46.309533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.457344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:34:46.309669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.78803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:1 size:10243"} {"level":"info","ts":"2026-04-20T14:34:46.309699Z","caller":"traceutil/trace.go:171","msg":"trace[55062971] range","detail":"{range_begin:/registry/services/specs/openstack/memcached; range_end:; response_count:0; response_revision:1689; }","duration":"218.691191ms","start":"2026-04-20T14:34:46.090955Z","end":"2026-04-20T14:34:46.309647Z","steps":["trace[55062971] 'range keys from in-memory index tree' (duration: 218.353871ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:46.309773Z","caller":"traceutil/trace.go:171","msg":"trace[1418944986] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:1; response_revision:1689; }","duration":"175.974915ms","start":"2026-04-20T14:34:46.133776Z","end":"2026-04-20T14:34:46.30975Z","steps":["trace[1418944986] 'range keys from in-memory index tree' (duration: 175.528242ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:46.43692Z","caller":"traceutil/trace.go:171","msg":"trace[1889696475] transaction","detail":"{read_only:false; response_revision:1690; number_of_response:1; }","duration":"117.270742ms","start":"2026-04-20T14:34:46.31959Z","end":"2026-04-20T14:34:46.436861Z","steps":["trace[1889696475] 'process raft request' (duration: 117.131178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:46.808832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.94197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:34:46.80892Z","caller":"traceutil/trace.go:171","msg":"trace[1743643728] linearizableReadLoop","detail":"{readStateIndex:1770; appliedIndex:1769; }","duration":"324.741815ms","start":"2026-04-20T14:34:46.484165Z","end":"2026-04-20T14:34:46.808906Z","steps":["trace[1743643728] 'read index received' (duration: 131.565908ms)","trace[1743643728] 'applied index is now lower than readState.Index' (duration: 193.174597ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:34:46.808969Z","caller":"traceutil/trace.go:171","msg":"trace[702330655] transaction","detail":"{read_only:false; response_revision:1691; number_of_response:1; }","duration":"334.16178ms","start":"2026-04-20T14:34:46.474782Z","end":"2026-04-20T14:34:46.808944Z","steps":["trace[702330655] 'process raft request' (duration: 141.047345ms)","trace[702330655] 'compare' (duration: 192.784356ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:34:46.809085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.924021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:1 size:10243"} {"level":"warn","ts":"2026-04-20T14:34:46.809087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:46.474741Z","time spent":"334.289774ms","remote":"127.0.0.1:35058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11784,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:34:46.809106Z","caller":"traceutil/trace.go:171","msg":"trace[1537305150] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:1; response_revision:1691; }","duration":"324.983223ms","start":"2026-04-20T14:34:46.484118Z","end":"2026-04-20T14:34:46.809101Z","steps":["trace[1537305150] 'agreement among raft nodes before linearized reading' (duration: 324.841869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:46.809131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:46.484091Z","time spent":"325.035485ms","remote":"127.0.0.1:35058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":10265,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"warn","ts":"2026-04-20T14:34:46.873514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.360732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:34:46.87366Z","caller":"traceutil/trace.go:171","msg":"trace[654162085] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1692; }","duration":"230.584188ms","start":"2026-04-20T14:34:46.643046Z","end":"2026-04-20T14:34:46.87363Z","steps":["trace[654162085] 'agreement among raft nodes before linearized reading' (duration: 230.33605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:46.874426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.560914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T14:34:46.874536Z","caller":"traceutil/trace.go:171","msg":"trace[137182343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1692; }","duration":"381.726379ms","start":"2026-04-20T14:34:46.492791Z","end":"2026-04-20T14:34:46.874517Z","steps":["trace[137182343] 'agreement among raft nodes before linearized reading' (duration: 381.552723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:46.874598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:46.492766Z","time spent":"381.821161ms","remote":"127.0.0.1:35222","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-04-20T14:34:47.096062Z","caller":"traceutil/trace.go:171","msg":"trace[1632178046] linearizableReadLoop","detail":"{readStateIndex:1772; appliedIndex:1771; }","duration":"222.701639ms","start":"2026-04-20T14:34:46.873338Z","end":"2026-04-20T14:34:47.096039Z","steps":["trace[1632178046] 'read index received' (duration: 140.576612ms)","trace[1632178046] 'applied index is now lower than readState.Index' (duration: 82.123957ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:34:47.096151Z","caller":"traceutil/trace.go:171","msg":"trace[2060341515] transaction","detail":"{read_only:false; response_revision:1693; number_of_response:1; }","duration":"282.669368ms","start":"2026-04-20T14:34:46.813443Z","end":"2026-04-20T14:34:47.096113Z","steps":["trace[2060341515] 'process raft request' (duration: 200.420078ms)","trace[2060341515] 'compare' (duration: 82.033094ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:34:47.096341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.183478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:1 size:10243"} {"level":"info","ts":"2026-04-20T14:34:47.096387Z","caller":"traceutil/trace.go:171","msg":"trace[1961192386] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:1; response_revision:1693; }","duration":"277.273361ms","start":"2026-04-20T14:34:46.819105Z","end":"2026-04-20T14:34:47.096378Z","steps":["trace[1961192386] 'agreement among raft nodes before linearized reading' (duration: 277.050134ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:47.357644Z","caller":"traceutil/trace.go:171","msg":"trace[1020736029] transaction","detail":"{read_only:false; response_revision:1694; number_of_response:1; }","duration":"479.958765ms","start":"2026-04-20T14:34:46.877644Z","end":"2026-04-20T14:34:47.357603Z","steps":["trace[1020736029] 'process raft request' (duration: 469.271552ms)","trace[1020736029] 'compare' (duration: 10.2375ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:34:47.35768Z","caller":"traceutil/trace.go:171","msg":"trace[274611797] transaction","detail":"{read_only:false; response_revision:1695; number_of_response:1; }","duration":"479.236453ms","start":"2026-04-20T14:34:46.878415Z","end":"2026-04-20T14:34:47.357651Z","steps":["trace[274611797] 'process raft request' (duration: 478.994716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:47.357849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:46.877624Z","time spent":"480.122789ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:34:47.357828Z","caller":"traceutil/trace.go:171","msg":"trace[65047368] linearizableReadLoop","detail":"{readStateIndex:1775; appliedIndex:1772; }","duration":"261.664234ms","start":"2026-04-20T14:34:47.096132Z","end":"2026-04-20T14:34:47.357797Z","steps":["trace[65047368] 'read index received' (duration: 250.806117ms)","trace[65047368] 'applied index is now lower than readState.Index' (duration: 10.856467ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:34:47.357866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:46.878384Z","time spent":"479.384588ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:34:47.358086Z","caller":"traceutil/trace.go:171","msg":"trace[867014795] transaction","detail":"{read_only:false; response_revision:1696; number_of_response:1; }","duration":"473.311181ms","start":"2026-04-20T14:34:46.88474Z","end":"2026-04-20T14:34:47.358051Z","steps":["trace[867014795] 'process raft request' (duration: 472.824797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:47.358206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.333524ms","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-04-20T14:34:47.358255Z","caller":"traceutil/trace.go:171","msg":"trace[1341891260] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1696; }","duration":"299.434957ms","start":"2026-04-20T14:34:47.058807Z","end":"2026-04-20T14:34:47.358242Z","steps":["trace[1341891260] 'agreement among raft nodes before linearized reading' (duration: 299.264092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:47.35827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:46.884707Z","time spent":"473.473145ms","remote":"127.0.0.1:35136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11811,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:34:47.457249Z","caller":"traceutil/trace.go:171","msg":"trace[899188500] transaction","detail":"{read_only:false; response_revision:1697; number_of_response:1; }","duration":"356.795172ms","start":"2026-04-20T14:34:47.100427Z","end":"2026-04-20T14:34:47.457222Z","steps":["trace[899188500] 'process raft request' (duration: 356.553094ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:47.457294Z","caller":"traceutil/trace.go:171","msg":"trace[346957871] transaction","detail":"{read_only:false; response_revision:1698; number_of_response:1; }","duration":"227.679434ms","start":"2026-04-20T14:34:47.229591Z","end":"2026-04-20T14:34:47.45727Z","steps":["trace[346957871] 'process raft request' (duration: 227.567051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:47.457375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:47.100407Z","time spent":"356.910764ms","remote":"127.0.0.1:35060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":943,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:34:47.457418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.194252ms","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-04-20T14:34:47.457502Z","caller":"traceutil/trace.go:171","msg":"trace[14745413] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1697; }","duration":"331.212516ms","start":"2026-04-20T14:34:47.126281Z","end":"2026-04-20T14:34:47.457493Z","steps":["trace[14745413] 'process raft request' (duration: 330.819544ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:47.45757Z","caller":"traceutil/trace.go:171","msg":"trace[1429754532] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1697; }","duration":"330.967227ms","start":"2026-04-20T14:34:47.126592Z","end":"2026-04-20T14:34:47.457559Z","steps":["trace[1429754532] 'process raft request' (duration: 330.545705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:47.457602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:47.12626Z","time spent":"331.275577ms","remote":"127.0.0.1:35022","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:34:47.457658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:47.126565Z","time spent":"331.045599ms","remote":"127.0.0.1:35022","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:34:47.4575Z","caller":"traceutil/trace.go:171","msg":"trace[20705095] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1698; }","duration":"321.320446ms","start":"2026-04-20T14:34:47.136163Z","end":"2026-04-20T14:34:47.457483Z","steps":["trace[20705095] 'agreement among raft nodes before linearized reading' (duration: 321.1017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:47.457756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:47.136144Z","time spent":"321.593994ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T14:34:47.679223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.533809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:34:47.679279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.831222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:34:47.679279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.513331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-osd\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:34:47.679309Z","caller":"traceutil/trace.go:171","msg":"trace[1848160838] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-cainjector; range_end:; response_count:0; response_revision:1698; }","duration":"361.661523ms","start":"2026-04-20T14:34:47.317624Z","end":"2026-04-20T14:34:47.679286Z","steps":["trace[1848160838] 'agreement among raft nodes before linearized reading' (duration: 139.919993ms)","trace[1848160838] 'range keys from in-memory index tree' (duration: 221.610346ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:34:47.67934Z","caller":"traceutil/trace.go:171","msg":"trace[1832109277] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx; range_end:; response_count:0; response_revision:1698; }","duration":"502.933045ms","start":"2026-04-20T14:34:47.17639Z","end":"2026-04-20T14:34:47.679323Z","steps":["trace[1832109277] 'agreement among raft nodes before linearized reading' (duration: 281.108623ms)","trace[1832109277] 'range keys from in-memory index tree' (duration: 221.698419ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:34:47.679355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:47.317608Z","time spent":"361.738255ms","remote":"127.0.0.1:35156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" "} {"level":"info","ts":"2026-04-20T14:34:47.679354Z","caller":"traceutil/trace.go:171","msg":"trace[910197717] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-ceph-osd; range_end:; response_count:0; response_revision:1698; }","duration":"528.592334ms","start":"2026-04-20T14:34:47.150741Z","end":"2026-04-20T14:34:47.679334Z","steps":["trace[910197717] 'agreement among raft nodes before linearized reading' (duration: 306.785352ms)","trace[910197717] 'range keys from in-memory index tree' (duration: 221.706889ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:34:47.679375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:47.176375Z","time spent":"502.993927ms","remote":"127.0.0.1:35156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx\" "} {"level":"warn","ts":"2026-04-20T14:34:47.679408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:47.150687Z","time spent":"528.708837ms","remote":"127.0.0.1:35156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-osd\" "} {"level":"info","ts":"2026-04-20T14:34:47.679441Z","caller":"traceutil/trace.go:171","msg":"trace[1414795972] linearizableReadLoop","detail":"{readStateIndex:1781; appliedIndex:1779; }","duration":"215.184949ms","start":"2026-04-20T14:34:47.464238Z","end":"2026-04-20T14:34:47.679423Z","steps":["trace[1414795972] 'read index received' (duration: 137.662027ms)","trace[1414795972] 'applied index is now lower than readState.Index' (duration: 77.516972ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:34:47.679449Z","caller":"traceutil/trace.go:171","msg":"trace[792871309] transaction","detail":"{read_only:false; response_revision:1700; number_of_response:1; }","duration":"235.886333ms","start":"2026-04-20T14:34:47.443533Z","end":"2026-04-20T14:34:47.67942Z","steps":["trace[792871309] 'process raft request' (duration: 235.78301ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:47.679435Z","caller":"traceutil/trace.go:171","msg":"trace[1973762690] transaction","detail":"{read_only:false; response_revision:1699; number_of_response:1; }","duration":"317.646849ms","start":"2026-04-20T14:34:47.361759Z","end":"2026-04-20T14:34:47.679406Z","steps":["trace[1973762690] 'process raft request' (duration: 240.022554ms)","trace[1973762690] 'compare' (duration: 77.273205ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:34:47.679564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.338904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/nodefeaturerules.nfd.k8s-sigs.io\" ","response":"range_response_count:1 size:10381"} {"level":"warn","ts":"2026-04-20T14:34:47.679578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.349444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/nodefeatures.nfd.k8s-sigs.io\" ","response":"range_response_count:1 size:4678"} {"level":"info","ts":"2026-04-20T14:34:47.679587Z","caller":"traceutil/trace.go:171","msg":"trace[360138171] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/nodefeaturerules.nfd.k8s-sigs.io; range_end:; response_count:1; response_revision:1700; }","duration":"215.404225ms","start":"2026-04-20T14:34:47.464174Z","end":"2026-04-20T14:34:47.679579Z","steps":["trace[360138171] 'agreement among raft nodes before linearized reading' (duration: 215.311963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:47.679583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:47.361737Z","time spent":"317.780043ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:34:47.679608Z","caller":"traceutil/trace.go:171","msg":"trace[581303125] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/nodefeatures.nfd.k8s-sigs.io; range_end:; response_count:1; response_revision:1700; }","duration":"215.425136ms","start":"2026-04-20T14:34:47.464175Z","end":"2026-04-20T14:34:47.6796Z","steps":["trace[581303125] 'agreement among raft nodes before linearized reading' (duration: 215.309583ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:47.684515Z","caller":"traceutil/trace.go:171","msg":"trace[1417785940] transaction","detail":"{read_only:false; response_revision:1701; number_of_response:1; }","duration":"217.951279ms","start":"2026-04-20T14:34:47.46655Z","end":"2026-04-20T14:34:47.684502Z","steps":["trace[1417785940] 'process raft request' (duration: 217.775164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:47.684694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.639509ms","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-04-20T14:34:47.684725Z","caller":"traceutil/trace.go:171","msg":"trace[1093833656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1701; }","duration":"134.712871ms","start":"2026-04-20T14:34:47.550003Z","end":"2026-04-20T14:34:47.684716Z","steps":["trace[1093833656] 'agreement among raft nodes before linearized reading' (duration: 134.630778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:47.684889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.59758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:113779"} {"level":"info","ts":"2026-04-20T14:34:47.684925Z","caller":"traceutil/trace.go:171","msg":"trace[1127131789] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1701; }","duration":"217.654872ms","start":"2026-04-20T14:34:47.467264Z","end":"2026-04-20T14:34:47.684919Z","steps":["trace[1127131789] 'agreement among raft nodes before linearized reading' (duration: 217.541558ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:47.822815Z","caller":"traceutil/trace.go:171","msg":"trace[2030312860] linearizableReadLoop","detail":"{readStateIndex:1783; appliedIndex:1782; }","duration":"134.811803ms","start":"2026-04-20T14:34:47.687984Z","end":"2026-04-20T14:34:47.822796Z","steps":["trace[2030312860] 'read index received' (duration: 134.586067ms)","trace[2030312860] 'applied index is now lower than readState.Index' (duration: 224.616µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:34:47.822877Z","caller":"traceutil/trace.go:171","msg":"trace[1743516229] transaction","detail":"{read_only:false; response_revision:1702; number_of_response:1; }","duration":"134.969448ms","start":"2026-04-20T14:34:47.687863Z","end":"2026-04-20T14:34:47.822833Z","steps":["trace[1743516229] 'process raft request' (duration: 134.743462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:47.822949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.973608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-webhook\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:34:47.822974Z","caller":"traceutil/trace.go:171","msg":"trace[2016659427] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-webhook; range_end:; response_count:0; response_revision:1702; }","duration":"135.05421ms","start":"2026-04-20T14:34:47.687911Z","end":"2026-04-20T14:34:47.822965Z","steps":["trace[2016659427] 'agreement among raft nodes before linearized reading' (duration: 134.977688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:48.150779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.243371ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:34:48.150886Z","caller":"traceutil/trace.go:171","msg":"trace[2012975356] transaction","detail":"{read_only:false; response_revision:1703; number_of_response:1; }","duration":"461.563438ms","start":"2026-04-20T14:34:47.689303Z","end":"2026-04-20T14:34:48.150866Z","steps":["trace[2012975356] 'process raft request' (duration: 284.162162ms)","trace[2012975356] 'compare' (duration: 177.146638ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:34:48.150914Z","caller":"traceutil/trace.go:171","msg":"trace[1213613416] transaction","detail":"{read_only:false; response_revision:1704; number_of_response:1; }","duration":"326.313591ms","start":"2026-04-20T14:34:47.824591Z","end":"2026-04-20T14:34:48.150905Z","steps":["trace[1213613416] 'process raft request' (duration: 326.27419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:48.150944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:47.689289Z","time spent":"461.628059ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:34:48.150962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:47.824574Z","time spent":"326.362383ms","remote":"127.0.0.1:35142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1151,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:34:48.151112Z","caller":"traceutil/trace.go:171","msg":"trace[999346665] linearizableReadLoop","detail":"{readStateIndex:1784; appliedIndex:1783; }","duration":"328.230827ms","start":"2026-04-20T14:34:47.822863Z","end":"2026-04-20T14:34:48.151094Z","steps":["trace[999346665] 'read index received' (duration: 150.616355ms)","trace[999346665] 'applied index is now lower than readState.Index' (duration: 177.612892ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:34:48.151236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.041234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/cert-manager/cert-manager-webhook\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:34:48.151238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.956069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-purge-osd\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:34:48.151262Z","caller":"traceutil/trace.go:171","msg":"trace[13162766] range","detail":"{range_begin:/registry/configmaps/cert-manager/cert-manager-webhook; range_end:; response_count:0; response_revision:1704; }","duration":"325.104126ms","start":"2026-04-20T14:34:47.82615Z","end":"2026-04-20T14:34:48.151254Z","steps":["trace[13162766] 'agreement among raft nodes before linearized reading' (duration: 325.049904ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:48.151264Z","caller":"traceutil/trace.go:171","msg":"trace[144421639] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-ceph-purge-osd; range_end:; response_count:0; response_revision:1704; }","duration":"462.00568ms","start":"2026-04-20T14:34:47.689252Z","end":"2026-04-20T14:34:48.151258Z","steps":["trace[144421639] 'agreement among raft nodes before linearized reading' (duration: 461.925728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:48.151282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:47.826112Z","time spent":"325.165368ms","remote":"127.0.0.1:35060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/configmaps/cert-manager/cert-manager-webhook\" "} {"level":"warn","ts":"2026-04-20T14:34:48.151284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:47.689242Z","time spent":"462.037292ms","remote":"127.0.0.1:35156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-purge-osd\" "} {"level":"warn","ts":"2026-04-20T14:34:48.151265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.97883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:34:48.151341Z","caller":"traceutil/trace.go:171","msg":"trace[728386194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1704; }","duration":"376.101064ms","start":"2026-04-20T14:34:47.775218Z","end":"2026-04-20T14:34:48.151319Z","steps":["trace[728386194] 'agreement among raft nodes before linearized reading' (duration: 375.931659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:48.151375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:47.7752Z","time spent":"376.165945ms","remote":"127.0.0.1:34970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T14:34:48.15139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.679048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/goldpinger-vxxnd\" ","response":"range_response_count:1 size:4991"} {"level":"info","ts":"2026-04-20T14:34:48.151524Z","caller":"traceutil/trace.go:171","msg":"trace[1419944441] range","detail":"{range_begin:/registry/pods/monitoring/goldpinger-vxxnd; range_end:; response_count:1; response_revision:1704; }","duration":"343.850433ms","start":"2026-04-20T14:34:47.807634Z","end":"2026-04-20T14:34:48.151485Z","steps":["trace[1419944441] 'agreement among raft nodes before linearized reading' (duration: 343.488592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:48.15158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:47.807613Z","time spent":"343.953865ms","remote":"127.0.0.1:35136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":5013,"request content":"key:\"/registry/pods/monitoring/goldpinger-vxxnd\" "} {"level":"warn","ts":"2026-04-20T14:34:48.151442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.362281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/ingress-nginx/ingress-nginx-udp\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:34:48.152436Z","caller":"traceutil/trace.go:171","msg":"trace[265584306] range","detail":"{range_begin:/registry/configmaps/ingress-nginx/ingress-nginx-udp; range_end:; response_count:0; response_revision:1704; }","duration":"462.541896ms","start":"2026-04-20T14:34:47.689878Z","end":"2026-04-20T14:34:48.15242Z","steps":["trace[265584306] 'agreement among raft nodes before linearized reading' (duration: 461.296229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:48.152482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:47.689869Z","time spent":"462.600168ms","remote":"127.0.0.1:35060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/configmaps/ingress-nginx/ingress-nginx-udp\" "} {"level":"warn","ts":"2026-04-20T14:34:48.555931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.096349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:34:48.556026Z","caller":"traceutil/trace.go:171","msg":"trace[1636548585] linearizableReadLoop","detail":"{readStateIndex:1786; appliedIndex:1785; }","duration":"400.475356ms","start":"2026-04-20T14:34:48.155537Z","end":"2026-04-20T14:34:48.556012Z","steps":["trace[1636548585] 'read index received' (duration: 127.361556ms)","trace[1636548585] 'applied index is now lower than readState.Index' (duration: 273.1125ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:34:48.556149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.608079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:34:48.556177Z","caller":"traceutil/trace.go:171","msg":"trace[494340405] range","detail":"{range_begin:/registry/configmaps/ingress-nginx/ingress-nginx-controller; range_end:; response_count:0; response_revision:1705; }","duration":"400.668021ms","start":"2026-04-20T14:34:48.155499Z","end":"2026-04-20T14:34:48.556167Z","steps":["trace[494340405] 'agreement among raft nodes before linearized reading' (duration: 400.572108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:48.556211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:48.155485Z","time spent":"400.718312ms","remote":"127.0.0.1:35060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":27,"request content":"key:\"/registry/configmaps/ingress-nginx/ingress-nginx-controller\" "} {"level":"info","ts":"2026-04-20T14:34:48.556098Z","caller":"traceutil/trace.go:171","msg":"trace[1901903391] transaction","detail":"{read_only:false; response_revision:1705; number_of_response:1; }","duration":"401.281128ms","start":"2026-04-20T14:34:48.154778Z","end":"2026-04-20T14:34:48.556059Z","steps":["trace[1901903391] 'process raft request' (duration: 127.981053ms)","trace[1901903391] 'compare' (duration: 272.883173ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:34:48.556415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:48.154764Z","time spent":"401.591968ms","remote":"127.0.0.1:35116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":499,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:34:48.568856Z","caller":"traceutil/trace.go:171","msg":"trace[1884150408] transaction","detail":"{read_only:false; response_revision:1706; number_of_response:1; }","duration":"413.305509ms","start":"2026-04-20T14:34:48.155516Z","end":"2026-04-20T14:34:48.568821Z","steps":["trace[1884150408] 'process raft request' (duration: 413.087643ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:48.568884Z","caller":"traceutil/trace.go:171","msg":"trace[1465068145] transaction","detail":"{read_only:false; response_revision:1708; number_of_response:1; }","duration":"409.55011ms","start":"2026-04-20T14:34:48.15931Z","end":"2026-04-20T14:34:48.56886Z","steps":["trace[1465068145] 'process raft request' (duration: 409.463598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:48.568991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:48.155479Z","time spent":"413.451733ms","remote":"127.0.0.1:35230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":956,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:34:48.568962Z","caller":"traceutil/trace.go:171","msg":"trace[922825855] transaction","detail":"{read_only:false; response_revision:1707; number_of_response:1; }","duration":"411.469775ms","start":"2026-04-20T14:34:48.157451Z","end":"2026-04-20T14:34:48.568921Z","steps":["trace[922825855] 'process raft request' (duration: 411.267339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:48.569042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:48.159299Z","time spent":"409.650253ms","remote":"127.0.0.1:35136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5180,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:34:48.569143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:48.157425Z","time spent":"411.626791ms","remote":"127.0.0.1:35424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6297,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:34:49.22567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.86594ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-20T14:34:49.226141Z","caller":"traceutil/trace.go:171","msg":"trace[411830383] transaction","detail":"{read_only:false; response_revision:1709; number_of_response:1; }","duration":"711.603314ms","start":"2026-04-20T14:34:48.514524Z","end":"2026-04-20T14:34:49.226128Z","steps":["trace[411830383] 'process raft request' (duration: 711.256154ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:49.226221Z","caller":"traceutil/trace.go:171","msg":"trace[973440864] linearizableReadLoop","detail":"{readStateIndex:1791; appliedIndex:1788; }","duration":"670.157064ms","start":"2026-04-20T14:34:48.556045Z","end":"2026-04-20T14:34:49.226202Z","steps":["trace[973440864] 'read index received' (duration: 12.765392ms)","trace[973440864] 'applied index is now lower than readState.Index' (duration: 657.389652ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:34:49.226255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:48.514498Z","time spent":"711.694047ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:34:49.226328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.34398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/node-feature-discovery\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:34:49.226366Z","caller":"traceutil/trace.go:171","msg":"trace[2070935690] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/node-feature-discovery; range_end:; response_count:0; response_revision:1709; }","duration":"681.381571ms","start":"2026-04-20T14:34:48.544972Z","end":"2026-04-20T14:34:49.226354Z","steps":["trace[2070935690] 'agreement among raft nodes before linearized reading' (duration: 681.32226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:49.226398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:48.544926Z","time spent":"681.464495ms","remote":"127.0.0.1:35156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/monitoring/node-feature-discovery\" "} {"level":"warn","ts":"2026-04-20T14:34:49.226541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070325891s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-default\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:34:49.226577Z","caller":"traceutil/trace.go:171","msg":"trace[1484432556] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-ceph-default; range_end:; response_count:0; response_revision:1709; }","duration":"1.070370952s","start":"2026-04-20T14:34:48.156198Z","end":"2026-04-20T14:34:49.226569Z","steps":["trace[1484432556] 'agreement among raft nodes before linearized reading' (duration: 1.07032033s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:49.226585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070416304s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/certificaterequests.cert-manager.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:34:49.226611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:48.156194Z","time spent":"1.070406924s","remote":"127.0.0.1:35156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-default\" "} {"level":"info","ts":"2026-04-20T14:34:49.226619Z","caller":"traceutil/trace.go:171","msg":"trace[685979815] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/certificaterequests.cert-manager.io; range_end:; response_count:0; response_revision:1709; }","duration":"1.070460205s","start":"2026-04-20T14:34:48.156148Z","end":"2026-04-20T14:34:49.226608Z","steps":["trace[685979815] 'agreement among raft nodes before linearized reading' (duration: 1.070408803s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:49.226647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:48.156141Z","time spent":"1.070498406s","remote":"127.0.0.1:35022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/certificaterequests.cert-manager.io\" "} {"level":"info","ts":"2026-04-20T14:34:49.227504Z","caller":"traceutil/trace.go:171","msg":"trace[1111129754] transaction","detail":"{read_only:false; response_revision:1710; number_of_response:1; }","duration":"653.403035ms","start":"2026-04-20T14:34:48.57409Z","end":"2026-04-20T14:34:49.227493Z","steps":["trace[1111129754] 'process raft request' (duration: 652.834149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:49.227562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:48.574067Z","time spent":"653.467377ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6653,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:34:49.228261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.842034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:34:49.228291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.959186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:34:49.22834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.008677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:34:49.228358Z","caller":"traceutil/trace.go:171","msg":"trace[93582308] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1711; }","duration":"272.027618ms","start":"2026-04-20T14:34:48.956291Z","end":"2026-04-20T14:34:49.228319Z","steps":["trace[93582308] 'agreement among raft nodes before linearized reading' (duration: 271.901164ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:49.22837Z","caller":"traceutil/trace.go:171","msg":"trace[479388702] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1711; }","duration":"284.071819ms","start":"2026-04-20T14:34:48.94429Z","end":"2026-04-20T14:34:49.228362Z","steps":["trace[479388702] 'agreement among raft nodes before linearized reading' (duration: 284.026998ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:49.228496Z","caller":"traceutil/trace.go:171","msg":"trace[1731648638] transaction","detail":"{read_only:false; response_revision:1711; number_of_response:1; }","duration":"652.466018ms","start":"2026-04-20T14:34:48.575997Z","end":"2026-04-20T14:34:49.228463Z","steps":["trace[1731648638] 'process raft request' (duration: 651.121388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:49.228572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:48.57597Z","time spent":"652.563591ms","remote":"127.0.0.1:35058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11763,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:34:49.228586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.398462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/goldpinger-vxxnd\" ","response":"range_response_count:1 size:5195"} {"level":"info","ts":"2026-04-20T14:34:49.228625Z","caller":"traceutil/trace.go:171","msg":"trace[659733140] range","detail":"{range_begin:/registry/pods/monitoring/goldpinger-vxxnd; range_end:; response_count:1; response_revision:1711; }","duration":"656.463284ms","start":"2026-04-20T14:34:48.572151Z","end":"2026-04-20T14:34:49.228614Z","steps":["trace[659733140] 'agreement among raft nodes before linearized reading' (duration: 656.334461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:49.228653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:48.572138Z","time spent":"656.508186ms","remote":"127.0.0.1:35136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":5217,"request content":"key:\"/registry/pods/monitoring/goldpinger-vxxnd\" "} {"level":"info","ts":"2026-04-20T14:34:49.228301Z","caller":"traceutil/trace.go:171","msg":"trace[95205996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1711; }","duration":"452.926996ms","start":"2026-04-20T14:34:48.775364Z","end":"2026-04-20T14:34:49.228291Z","steps":["trace[95205996] 'agreement among raft nodes before linearized reading' (duration: 452.865374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:49.229775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:48.77534Z","time spent":"454.387738ms","remote":"127.0.0.1:34970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T14:34:49.229438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.795774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/ingress-nginx\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:34:49.230174Z","caller":"traceutil/trace.go:171","msg":"trace[265897095] range","detail":"{range_begin:/registry/clusterroles/ingress-nginx; range_end:; response_count:0; response_revision:1711; }","duration":"670.571486ms","start":"2026-04-20T14:34:48.55959Z","end":"2026-04-20T14:34:49.230161Z","steps":["trace[265897095] 'agreement among raft nodes before linearized reading' (duration: 668.298081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:49.230213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:48.559504Z","time spent":"670.697149ms","remote":"127.0.0.1:35310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/ingress-nginx\" "} {"level":"warn","ts":"2026-04-20T14:34:49.700918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.119411ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:34:49.700997Z","caller":"traceutil/trace.go:171","msg":"trace[1438392121] linearizableReadLoop","detail":"{readStateIndex:1794; appliedIndex:1793; }","duration":"461.707751ms","start":"2026-04-20T14:34:49.239276Z","end":"2026-04-20T14:34:49.700984Z","steps":["trace[1438392121] 'read index received' (duration: 251.466506ms)","trace[1438392121] 'applied index is now lower than readState.Index' (duration: 210.239555ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:34:49.701009Z","caller":"traceutil/trace.go:171","msg":"trace[1065648520] transaction","detail":"{read_only:false; response_revision:1712; number_of_response:1; }","duration":"469.804828ms","start":"2026-04-20T14:34:49.231181Z","end":"2026-04-20T14:34:49.700986Z","steps":["trace[1065648520] 'process raft request' (duration: 259.566614ms)","trace[1065648520] 'compare' (duration: 209.939555ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:34:49.701078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.796874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:34:49.701097Z","caller":"traceutil/trace.go:171","msg":"trace[786435918] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-ceph-system; range_end:; response_count:0; response_revision:1712; }","duration":"461.849076ms","start":"2026-04-20T14:34:49.239242Z","end":"2026-04-20T14:34:49.701091Z","steps":["trace[786435918] 'agreement among raft nodes before linearized reading' (duration: 461.786614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:49.701093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:49.23115Z","time spent":"469.904992ms","remote":"127.0.0.1:35044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T14:34:49.701116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:49.239181Z","time spent":"461.930768ms","remote":"127.0.0.1:35156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/rook-ceph/rook-ceph-system\" "} {"level":"warn","ts":"2026-04-20T14:34:50.539687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.754933ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T14:34:50.540102Z","caller":"traceutil/trace.go:171","msg":"trace[648644037] transaction","detail":"{read_only:false; response_revision:1717; number_of_response:1; }","duration":"456.003946ms","start":"2026-04-20T14:34:50.084079Z","end":"2026-04-20T14:34:50.540083Z","steps":["trace[648644037] 'process raft request' (duration: 455.970935ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:50.540329Z","caller":"traceutil/trace.go:171","msg":"trace[402745269] transaction","detail":"{read_only:false; response_revision:1715; number_of_response:1; }","duration":"1.2759692s","start":"2026-04-20T14:34:49.264324Z","end":"2026-04-20T14:34:50.540293Z","steps":["trace[402745269] 'process raft request' (duration: 1.2756202s)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:50.540573Z","caller":"traceutil/trace.go:171","msg":"trace[1420758935] transaction","detail":"{read_only:false; response_revision:1714; number_of_response:1; }","duration":"1.276396004s","start":"2026-04-20T14:34:49.264145Z","end":"2026-04-20T14:34:50.540541Z","steps":["trace[1420758935] 'process raft request' (duration: 1.275667373s)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:50.540584Z","caller":"traceutil/trace.go:171","msg":"trace[864499699] linearizableReadLoop","detail":"{readStateIndex:1798; appliedIndex:1794; }","duration":"839.550336ms","start":"2026-04-20T14:34:49.701013Z","end":"2026-04-20T14:34:50.540563Z","steps":["trace[864499699] 'read index received' (duration: 290.863516ms)","trace[864499699] 'applied index is now lower than readState.Index' (duration: 548.68494ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:34:50.540687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.2300073s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T14:34:50.540711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.300924919s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/ingress-nginx\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:34:50.540699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:49.264318Z","time spent":"1.27628723s","remote":"127.0.0.1:35136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4982,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:34:50.540703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:49.26413Z","time spent":"1.276493336s","remote":"127.0.0.1:35424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6793,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:34:50.540743Z","caller":"traceutil/trace.go:171","msg":"trace[118207824] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1717; }","duration":"1.230104113s","start":"2026-04-20T14:34:49.310625Z","end":"2026-04-20T14:34:50.540729Z","steps":["trace[118207824] 'agreement among raft nodes before linearized reading' (duration: 1.22999876s)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:50.540662Z","caller":"traceutil/trace.go:171","msg":"trace[1105085985] transaction","detail":"{read_only:false; response_revision:1716; number_of_response:1; }","duration":"856.09723ms","start":"2026-04-20T14:34:49.684481Z","end":"2026-04-20T14:34:50.540578Z","steps":["trace[1105085985] 'process raft request' (duration: 855.519583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:50.540752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.179508646s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-20T14:34:50.540783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:49.310606Z","time spent":"1.230168924s","remote":"127.0.0.1:35116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-20T14:34:50.540772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.500261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-20T14:34:50.540874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.301533267s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/node-feature-discovery-worker\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T14:34:50.540877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.178364303s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T14:34:50.540931Z","caller":"traceutil/trace.go:171","msg":"trace[915117761] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/node-feature-discovery-worker; range_end:; response_count:0; response_revision:1717; }","duration":"1.301599578s","start":"2026-04-20T14:34:49.239316Z","end":"2026-04-20T14:34:50.540915Z","steps":["trace[915117761] 'agreement among raft nodes before linearized reading' (duration: 1.301528816s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:50.540935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.220263006s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:2 size:30784"} {"level":"info","ts":"2026-04-20T14:34:50.540945Z","caller":"traceutil/trace.go:171","msg":"trace[1647764868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1717; }","duration":"1.178453026s","start":"2026-04-20T14:34:49.362474Z","end":"2026-04-20T14:34:50.540927Z","steps":["trace[1647764868] 'agreement among raft nodes before linearized reading' (duration: 1.178285401s)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:50.540978Z","caller":"traceutil/trace.go:171","msg":"trace[823418320] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:2; response_revision:1717; }","duration":"1.220307107s","start":"2026-04-20T14:34:49.320662Z","end":"2026-04-20T14:34:50.540969Z","steps":["trace[823418320] 'agreement among raft nodes before linearized reading' (duration: 1.220184083s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:50.540982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:49.23931Z","time spent":"1.30166104s","remote":"127.0.0.1:35156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/monitoring/node-feature-discovery-worker\" "} {"level":"warn","ts":"2026-04-20T14:34:50.540964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:49.68446Z","time spent":"856.355187ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T14:34:50.541022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:49.32064Z","time spent":"1.220374688s","remote":"127.0.0.1:35058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":2,"response size":30806,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"info","ts":"2026-04-20T14:34:50.540869Z","caller":"traceutil/trace.go:171","msg":"trace[335731423] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1717; }","duration":"857.679145ms","start":"2026-04-20T14:34:49.683169Z","end":"2026-04-20T14:34:50.540848Z","steps":["trace[335731423] 'agreement among raft nodes before linearized reading' (duration: 857.422418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:50.541217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:49.683145Z","time spent":"858.055627ms","remote":"127.0.0.1:35222","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-04-20T14:34:50.540817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.301451764s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/certificates.cert-manager.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:34:50.54082Z","caller":"traceutil/trace.go:171","msg":"trace[1904621946] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1717; }","duration":"1.179600609s","start":"2026-04-20T14:34:49.361203Z","end":"2026-04-20T14:34:50.540803Z","steps":["trace[1904621946] 'agreement among raft nodes before linearized reading' (duration: 1.179373663s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:50.541419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:49.361189Z","time spent":"1.180219318s","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T14:34:50.54076Z","caller":"traceutil/trace.go:171","msg":"trace[1297707927] range","detail":"{range_begin:/registry/clusterrolebindings/ingress-nginx; range_end:; response_count:0; response_revision:1717; }","duration":"1.301000502s","start":"2026-04-20T14:34:49.239745Z","end":"2026-04-20T14:34:50.540746Z","steps":["trace[1297707927] 'agreement among raft nodes before linearized reading' (duration: 1.300920759s)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:50.541469Z","caller":"traceutil/trace.go:171","msg":"trace[568217501] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/certificates.cert-manager.io; range_end:; response_count:0; response_revision:1717; }","duration":"1.302041021s","start":"2026-04-20T14:34:49.239324Z","end":"2026-04-20T14:34:50.541365Z","steps":["trace[568217501] 'agreement among raft nodes before linearized reading' (duration: 1.301460535s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:50.541522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:49.23974Z","time spent":"1.301761364s","remote":"127.0.0.1:35322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/clusterrolebindings/ingress-nginx\" "} {"level":"warn","ts":"2026-04-20T14:34:50.540964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.117342ms","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-04-20T14:34:50.54163Z","caller":"traceutil/trace.go:171","msg":"trace[1634260949] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1717; }","duration":"853.818843ms","start":"2026-04-20T14:34:49.687793Z","end":"2026-04-20T14:34:50.541611Z","steps":["trace[1634260949] 'agreement among raft nodes before linearized reading' (duration: 853.04914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:50.541593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:49.239308Z","time spent":"1.302242388s","remote":"127.0.0.1:35022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/certificates.cert-manager.io\" "} {"level":"warn","ts":"2026-04-20T14:34:50.541698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:49.687774Z","time spent":"853.905576ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T14:34:50.54099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:49.362463Z","time spent":"1.178517697s","remote":"127.0.0.1:35222","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-04-20T14:34:50.540203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:50.084058Z","time spent":"456.098779ms","remote":"127.0.0.1:34990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T14:34:50.540195Z","caller":"traceutil/trace.go:171","msg":"trace[1677261475] transaction","detail":"{read_only:false; response_revision:1713; number_of_response:1; }","duration":"1.277957529s","start":"2026-04-20T14:34:49.262208Z","end":"2026-04-20T14:34:50.540165Z","steps":["trace[1677261475] 'process raft request' (duration: 729.65391ms)","trace[1677261475] 'compare' (duration: 547.597628ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:34:50.542123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:49.262189Z","time spent":"1.279857965s","remote":"127.0.0.1:35136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6302,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T14:34:50.572235Z","caller":"traceutil/trace.go:171","msg":"trace[1325986533] transaction","detail":"{read_only:false; response_revision:1718; number_of_response:1; }","duration":"265.845346ms","start":"2026-04-20T14:34:50.306369Z","end":"2026-04-20T14:34:50.572214Z","steps":["trace[1325986533] 'process raft request' (duration: 265.738823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:50.572518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.952686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-csi-cephfs-plugin-sa\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:34:50.572556Z","caller":"traceutil/trace.go:171","msg":"trace[926071995] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-csi-cephfs-plugin-sa; range_end:; response_count:0; response_revision:1718; }","duration":"869.022818ms","start":"2026-04-20T14:34:49.703521Z","end":"2026-04-20T14:34:50.572544Z","steps":["trace[926071995] 'agreement among raft nodes before linearized reading' (duration: 868.950766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:50.572623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:49.70351Z","time spent":"869.060639ms","remote":"127.0.0.1:35156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/rook-ceph/rook-csi-cephfs-plugin-sa\" "} {"level":"warn","ts":"2026-04-20T14:34:50.572619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.743822ms","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-04-20T14:34:50.572709Z","caller":"traceutil/trace.go:171","msg":"trace[774240170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1718; }","duration":"415.888625ms","start":"2026-04-20T14:34:50.156799Z","end":"2026-04-20T14:34:50.572687Z","steps":["trace[774240170] 'agreement among raft nodes before linearized reading' (duration: 415.557935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:50.572768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T14:34:50.156784Z","time spent":"415.968178ms","remote":"127.0.0.1:35222","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-04-20T14:34:50.700831Z","caller":"traceutil/trace.go:171","msg":"trace[983500987] transaction","detail":"{read_only:false; response_revision:1719; number_of_response:1; }","duration":"154.517908ms","start":"2026-04-20T14:34:50.546283Z","end":"2026-04-20T14:34:50.700801Z","steps":["trace[983500987] 'process raft request' (duration: 154.23624ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:50.701416Z","caller":"traceutil/trace.go:171","msg":"trace[1702244992] transaction","detail":"{read_only:false; response_revision:1721; number_of_response:1; }","duration":"155.071196ms","start":"2026-04-20T14:34:50.546331Z","end":"2026-04-20T14:34:50.701402Z","steps":["trace[1702244992] 'process raft request' (duration: 154.829179ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:50.701392Z","caller":"traceutil/trace.go:171","msg":"trace[1377076984] transaction","detail":"{read_only:false; response_revision:1720; number_of_response:1; }","duration":"154.997103ms","start":"2026-04-20T14:34:50.546321Z","end":"2026-04-20T14:34:50.701318Z","steps":["trace[1377076984] 'process raft request' (duration: 154.418746ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:50.701645Z","caller":"traceutil/trace.go:171","msg":"trace[374055130] transaction","detail":"{read_only:false; response_revision:1722; number_of_response:1; }","duration":"155.259591ms","start":"2026-04-20T14:34:50.546366Z","end":"2026-04-20T14:34:50.701625Z","steps":["trace[374055130] 'process raft request' (duration: 154.972413ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:50.701915Z","caller":"traceutil/trace.go:171","msg":"trace[2079488477] transaction","detail":"{read_only:false; response_revision:1723; number_of_response:1; }","duration":"154.628662ms","start":"2026-04-20T14:34:50.547218Z","end":"2026-04-20T14:34:50.701847Z","steps":["trace[2079488477] 'process raft request' (duration: 154.315513ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:50.701991Z","caller":"traceutil/trace.go:171","msg":"trace[1128861652] transaction","detail":"{read_only:false; response_revision:1726; number_of_response:1; }","duration":"154.238851ms","start":"2026-04-20T14:34:50.547744Z","end":"2026-04-20T14:34:50.701983Z","steps":["trace[1128861652] 'process raft request' (duration: 154.110437ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:50.702042Z","caller":"traceutil/trace.go:171","msg":"trace[1557187107] transaction","detail":"{read_only:false; response_revision:1727; number_of_response:1; }","duration":"152.700696ms","start":"2026-04-20T14:34:50.549327Z","end":"2026-04-20T14:34:50.702028Z","steps":["trace[1557187107] 'process raft request' (duration: 152.548931ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:50.70214Z","caller":"traceutil/trace.go:171","msg":"trace[1852640720] transaction","detail":"{read_only:false; response_revision:1725; number_of_response:1; }","duration":"154.440467ms","start":"2026-04-20T14:34:50.54769Z","end":"2026-04-20T14:34:50.70213Z","steps":["trace[1852640720] 'process raft request' (duration: 154.139738ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:50.701944Z","caller":"traceutil/trace.go:171","msg":"trace[15640758] transaction","detail":"{read_only:false; response_revision:1724; number_of_response:1; }","duration":"154.366074ms","start":"2026-04-20T14:34:50.547564Z","end":"2026-04-20T14:34:50.70193Z","steps":["trace[15640758] 'process raft request' (duration: 154.22478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:50.702241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.384683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/challenges.acme.cert-manager.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:34:50.702124Z","caller":"traceutil/trace.go:171","msg":"trace[1222314694] linearizableReadLoop","detail":"{readStateIndex:1810; appliedIndex:1800; }","duration":"129.797647ms","start":"2026-04-20T14:34:50.572302Z","end":"2026-04-20T14:34:50.7021Z","steps":["trace[1222314694] 'read index received' (duration: 128.180339ms)","trace[1222314694] 'applied index is now lower than readState.Index' (duration: 1.616288ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:34:50.702289Z","caller":"traceutil/trace.go:171","msg":"trace[1095755925] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/challenges.acme.cert-manager.io; range_end:; response_count:0; response_revision:1728; }","duration":"156.448775ms","start":"2026-04-20T14:34:50.545827Z","end":"2026-04-20T14:34:50.702275Z","steps":["trace[1095755925] 'agreement among raft nodes before linearized reading' (duration: 156.341852ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:50.702365Z","caller":"traceutil/trace.go:171","msg":"trace[1195052594] transaction","detail":"{read_only:false; response_revision:1728; number_of_response:1; }","duration":"152.260132ms","start":"2026-04-20T14:34:50.550094Z","end":"2026-04-20T14:34:50.702354Z","steps":["trace[1195052594] 'process raft request' (duration: 151.84052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:50.702424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.328732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/node-feature-discovery-master-conf\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:34:50.702564Z","caller":"traceutil/trace.go:171","msg":"trace[1221309687] range","detail":"{range_begin:/registry/configmaps/monitoring/node-feature-discovery-master-conf; range_end:; response_count:0; response_revision:1728; }","duration":"156.496647ms","start":"2026-04-20T14:34:50.546051Z","end":"2026-04-20T14:34:50.702548Z","steps":["trace[1221309687] 'agreement among raft nodes before linearized reading' (duration: 156.325912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:50.702585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.545128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/ingress-nginx/ingress-nginx\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:34:50.702667Z","caller":"traceutil/trace.go:171","msg":"trace[2093295921] range","detail":"{range_begin:/registry/roles/ingress-nginx/ingress-nginx; range_end:; response_count:0; response_revision:1728; }","duration":"156.662112ms","start":"2026-04-20T14:34:50.545988Z","end":"2026-04-20T14:34:50.70265Z","steps":["trace[2093295921] 'agreement among raft nodes before linearized reading' (duration: 156.420215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:50.702751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.795421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/memcached-memcached\" ","response":"range_response_count:1 size:6808"} {"level":"info","ts":"2026-04-20T14:34:50.702785Z","caller":"traceutil/trace.go:171","msg":"trace[651106886] range","detail":"{range_begin:/registry/deployments/openstack/memcached-memcached; range_end:; response_count:1; response_revision:1728; }","duration":"150.851592ms","start":"2026-04-20T14:34:50.551927Z","end":"2026-04-20T14:34:50.702778Z","steps":["trace[651106886] 'agreement among raft nodes before linearized reading' (duration: 150.729278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:34:50.703613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.884411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/rook-ceph/rook-csi-cephfs-provisioner-sa\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:34:50.703654Z","caller":"traceutil/trace.go:171","msg":"trace[1118903661] range","detail":"{range_begin:/registry/serviceaccounts/rook-ceph/rook-csi-cephfs-provisioner-sa; range_end:; response_count:0; response_revision:1729; }","duration":"127.949582ms","start":"2026-04-20T14:34:50.575694Z","end":"2026-04-20T14:34:50.703643Z","steps":["trace[1118903661] 'agreement among raft nodes before linearized reading' (duration: 127.87108ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:34:50.70386Z","caller":"traceutil/trace.go:171","msg":"trace[428801955] transaction","detail":"{read_only:false; response_revision:1729; number_of_response:1; }","duration":"126.933084ms","start":"2026-04-20T14:34:50.57692Z","end":"2026-04-20T14:34:50.703853Z","steps":["trace[428801955] 'process raft request' (duration: 126.553563ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:35:23.601629Z","caller":"traceutil/trace.go:171","msg":"trace[2080199835] transaction","detail":"{read_only:false; response_revision:2458; number_of_response:1; }","duration":"125.293311ms","start":"2026-04-20T14:35:23.47631Z","end":"2026-04-20T14:35:23.601604Z","steps":["trace[2080199835] 'process raft request' (duration: 124.772921ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:35:50.521488Z","caller":"traceutil/trace.go:171","msg":"trace[2023147397] transaction","detail":"{read_only:false; response_revision:2548; number_of_response:1; }","duration":"109.868632ms","start":"2026-04-20T14:35:50.411588Z","end":"2026-04-20T14:35:50.521457Z","steps":["trace[2023147397] 'process raft request' (duration: 109.694136ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:35:51.22618Z","caller":"traceutil/trace.go:171","msg":"trace[640378636] linearizableReadLoop","detail":"{readStateIndex:2647; appliedIndex:2646; }","duration":"131.225393ms","start":"2026-04-20T14:35:51.094923Z","end":"2026-04-20T14:35:51.226148Z","steps":["trace[640378636] 'read index received' (duration: 130.89421ms)","trace[640378636] 'applied index is now lower than readState.Index' (duration: 329.923µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:35:51.226332Z","caller":"traceutil/trace.go:171","msg":"trace[1346017984] transaction","detail":"{read_only:false; response_revision:2550; number_of_response:1; }","duration":"138.781547ms","start":"2026-04-20T14:35:51.087519Z","end":"2026-04-20T14:35:51.226301Z","steps":["trace[1346017984] 'process raft request' (duration: 138.427724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:35:51.226481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.329877ms","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-04-20T14:35:51.226536Z","caller":"traceutil/trace.go:171","msg":"trace[1959406197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2550; }","duration":"131.41732ms","start":"2026-04-20T14:35:51.095104Z","end":"2026-04-20T14:35:51.226521Z","steps":["trace[1959406197] 'agreement among raft nodes before linearized reading' (duration: 131.220273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:35:51.226641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.339427ms","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":"warn","ts":"2026-04-20T14:35:51.226516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.563755ms","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-04-20T14:35:51.226708Z","caller":"traceutil/trace.go:171","msg":"trace[621530898] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2550; }","duration":"123.451261ms","start":"2026-04-20T14:35:51.103244Z","end":"2026-04-20T14:35:51.226695Z","steps":["trace[621530898] 'agreement among raft nodes before linearized reading' (duration: 123.260164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:35:51.226825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.860306ms","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-04-20T14:35:51.226851Z","caller":"traceutil/trace.go:171","msg":"trace[1907913382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2550; }","duration":"131.829016ms","start":"2026-04-20T14:35:51.094886Z","end":"2026-04-20T14:35:51.226715Z","steps":["trace[1907913382] 'agreement among raft nodes before linearized reading' (duration: 131.436451ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:35:51.226944Z","caller":"traceutil/trace.go:171","msg":"trace[350765120] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2550; }","duration":"124.002022ms","start":"2026-04-20T14:35:51.102908Z","end":"2026-04-20T14:35:51.22691Z","steps":["trace[350765120] 'agreement among raft nodes before linearized reading' (duration: 123.652769ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:39:50.549428Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":1717} {"level":"info","ts":"2026-04-20T14:39:50.598431Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":1717,"took":"46.775296ms","hash":2084580596,"current-db-size-bytes":33214464,"current-db-size":"33 MB","current-db-size-in-use-bytes":14987264,"current-db-size-in-use":"15 MB"} {"level":"info","ts":"2026-04-20T14:39:50.598553Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2084580596,"revision":1717,"compact-revision":-1} {"level":"info","ts":"2026-04-20T14:44:50.555807Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3405} {"level":"info","ts":"2026-04-20T14:44:50.60227Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3405,"took":"45.241328ms","hash":2247055395,"current-db-size-bytes":33214464,"current-db-size":"33 MB","current-db-size-in-use-bytes":17780736,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-20T14:44:50.602363Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2247055395,"revision":3405,"compact-revision":1717} {"level":"info","ts":"2026-04-20T14:48:52.763582Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002456.wal"} {"level":"info","ts":"2026-04-20T14:49:17.909522Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T14:49:17.918796Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-20T14:49:17.919105Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-20T14:49:50.568086Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6041} {"level":"info","ts":"2026-04-20T14:49:50.643955Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6041,"took":"74.021084ms","hash":1531666705,"current-db-size-bytes":50601984,"current-db-size":"51 MB","current-db-size-in-use-bytes":42266624,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-04-20T14:49:50.644028Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1531666705,"revision":6041,"compact-revision":3405} {"level":"warn","ts":"2026-04-20T14:50:06.427284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.878311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-k8tw8\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-04-20T14:50:06.427398Z","caller":"traceutil/trace.go:171","msg":"trace[361891899] range","detail":"{range_begin:/registry/pods/openstack/keepalived-k8tw8; range_end:; response_count:1; response_revision:10647; }","duration":"104.047656ms","start":"2026-04-20T14:50:06.323328Z","end":"2026-04-20T14:50:06.427376Z","steps":["trace[361891899] 'range keys from in-memory index tree' (duration: 103.730948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:50:17.600691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.332211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/glance-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:50:17.600763Z","caller":"traceutil/trace.go:171","msg":"trace[445038599] range","detail":"{range_begin:/registry/jobs/openstack/glance-ks-endpoints; range_end:; response_count:0; response_revision:10811; }","duration":"104.410463ms","start":"2026-04-20T14:50:17.49634Z","end":"2026-04-20T14:50:17.60075Z","steps":["trace[445038599] 'agreement among raft nodes before linearized reading' (duration: 104.32236ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:54:50.576404Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10392} {"level":"info","ts":"2026-04-20T14:54:50.701923Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10392,"took":"123.074845ms","hash":1968003574,"current-db-size-bytes":59011072,"current-db-size":"59 MB","current-db-size-in-use-bytes":36409344,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-04-20T14:54:50.701999Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1968003574,"revision":10392,"compact-revision":6041} {"level":"info","ts":"2026-04-20T14:54:55.185289Z","caller":"traceutil/trace.go:171","msg":"trace[751041794] linearizableReadLoop","detail":"{readStateIndex:14344; appliedIndex:14343; }","duration":"116.131658ms","start":"2026-04-20T14:54:55.069135Z","end":"2026-04-20T14:54:55.185267Z","steps":["trace[751041794] 'read index received' (duration: 108.818041ms)","trace[751041794] 'applied index is now lower than readState.Index' (duration: 7.312717ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:54:55.185493Z","caller":"traceutil/trace.go:171","msg":"trace[1150007719] transaction","detail":"{read_only:false; response_revision:13884; number_of_response:1; }","duration":"175.964089ms","start":"2026-04-20T14:54:55.009515Z","end":"2026-04-20T14:54:55.185479Z","steps":["trace[1150007719] 'process raft request' (duration: 168.463978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:54:55.186489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.336806ms","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-04-20T14:54:55.186544Z","caller":"traceutil/trace.go:171","msg":"trace[1700275041] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13884; }","duration":"117.442409ms","start":"2026-04-20T14:54:55.06909Z","end":"2026-04-20T14:54:55.186532Z","steps":["trace[1700275041] 'agreement among raft nodes before linearized reading' (duration: 116.574398ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:54:55.35311Z","caller":"traceutil/trace.go:171","msg":"trace[1960688369] transaction","detail":"{read_only:false; response_revision:13888; number_of_response:1; }","duration":"160.382613ms","start":"2026-04-20T14:54:55.192707Z","end":"2026-04-20T14:54:55.35309Z","steps":["trace[1960688369] 'process raft request' (duration: 160.323711ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:54:55.353187Z","caller":"traceutil/trace.go:171","msg":"trace[158217787] transaction","detail":"{read_only:false; response_revision:13885; number_of_response:1; }","duration":"162.863492ms","start":"2026-04-20T14:54:55.190288Z","end":"2026-04-20T14:54:55.353152Z","steps":["trace[158217787] 'process raft request' (duration: 96.852643ms)","trace[158217787] 'compare' (duration: 65.18571ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:54:55.353555Z","caller":"traceutil/trace.go:171","msg":"trace[862541219] transaction","detail":"{read_only:false; response_revision:13886; number_of_response:1; }","duration":"162.097014ms","start":"2026-04-20T14:54:55.191443Z","end":"2026-04-20T14:54:55.35354Z","steps":["trace[862541219] 'process raft request' (duration: 161.145871ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:54:55.355574Z","caller":"traceutil/trace.go:171","msg":"trace[907993031] transaction","detail":"{read_only:false; response_revision:13887; number_of_response:1; }","duration":"162.052133ms","start":"2026-04-20T14:54:55.191616Z","end":"2026-04-20T14:54:55.353668Z","steps":["trace[907993031] 'process raft request' (duration: 161.366996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:54:55.725002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.308005ms","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-04-20T14:54:55.725063Z","caller":"traceutil/trace.go:171","msg":"trace[1943407434] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:13889; }","duration":"232.419198ms","start":"2026-04-20T14:54:55.492631Z","end":"2026-04-20T14:54:55.72505Z","steps":["trace[1943407434] 'count revisions from in-memory index tree' (duration: 232.221504ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:54:55.746587Z","caller":"traceutil/trace.go:171","msg":"trace[719374253] transaction","detail":"{read_only:false; response_revision:13890; number_of_response:1; }","duration":"137.126903ms","start":"2026-04-20T14:54:55.609443Z","end":"2026-04-20T14:54:55.74657Z","steps":["trace[719374253] 'process raft request' (duration: 136.911498ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:54:58.285045Z","caller":"traceutil/trace.go:171","msg":"trace[1084508728] transaction","detail":"{read_only:false; response_revision:14026; number_of_response:1; }","duration":"110.793448ms","start":"2026-04-20T14:54:58.174215Z","end":"2026-04-20T14:54:58.285008Z","steps":["trace[1084508728] 'process raft request' (duration: 70.327694ms)","trace[1084508728] 'compare' (duration: 40.30214ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T14:54:58.285256Z","caller":"traceutil/trace.go:171","msg":"trace[823774589] linearizableReadLoop","detail":"{readStateIndex:14490; appliedIndex:14489; }","duration":"110.919721ms","start":"2026-04-20T14:54:58.174287Z","end":"2026-04-20T14:54:58.285207Z","steps":["trace[823774589] 'read index received' (duration: 70.316974ms)","trace[823774589] 'applied index is now lower than readState.Index' (duration: 40.519085ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T14:54:58.285605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.29512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/capi-system/capi-serving-cert-sx48n\" ","response":"range_response_count:1 size:2470"} {"level":"info","ts":"2026-04-20T14:54:58.28569Z","caller":"traceutil/trace.go:171","msg":"trace[316887878] range","detail":"{range_begin:/registry/secrets/capi-system/capi-serving-cert-sx48n; range_end:; response_count:1; response_revision:14026; }","duration":"111.384832ms","start":"2026-04-20T14:54:58.174283Z","end":"2026-04-20T14:54:58.285668Z","steps":["trace[316887878] 'agreement among raft nodes before linearized reading' (duration: 111.141156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:54:58.295097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.807802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/capi-system/capi-leader-election-role\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T14:54:58.29589Z","caller":"traceutil/trace.go:171","msg":"trace[905092157] range","detail":"{range_begin:/registry/roles/capi-system/capi-leader-election-role; range_end:; response_count:0; response_revision:14028; }","duration":"119.611321ms","start":"2026-04-20T14:54:58.176257Z","end":"2026-04-20T14:54:58.295868Z","steps":["trace[905092157] 'agreement among raft nodes before linearized reading' (duration: 118.768501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T14:54:58.296145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.437293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-04-20T14:54:58.296815Z","caller":"traceutil/trace.go:171","msg":"trace[1265230143] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:14028; }","duration":"118.556635ms","start":"2026-04-20T14:54:58.177655Z","end":"2026-04-20T14:54:58.296212Z","steps":["trace[1265230143] 'agreement among raft nodes before linearized reading' (duration: 118.192677ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T14:59:50.583357Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13545} {"level":"info","ts":"2026-04-20T14:59:50.698337Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13545,"took":"111.112459ms","hash":1958321871,"current-db-size-bytes":59011072,"current-db-size":"59 MB","current-db-size-in-use-bytes":45170688,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-04-20T14:59:50.698402Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1958321871,"revision":13545,"compact-revision":10392} {"level":"info","ts":"2026-04-20T15:02:18.547919Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T15:02:18.552375Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-20T15:02:18.552453Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-20T15:02:51.081685Z","caller":"traceutil/trace.go:171","msg":"trace[1879530788] transaction","detail":"{read_only:false; response_revision:19709; number_of_response:1; }","duration":"113.787214ms","start":"2026-04-20T15:02:50.967866Z","end":"2026-04-20T15:02:51.081653Z","steps":["trace[1879530788] 'process raft request' (duration: 113.577979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:02:51.803128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.706712ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T15:02:51.803244Z","caller":"traceutil/trace.go:171","msg":"trace[1355759051] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:19709; }","duration":"603.831715ms","start":"2026-04-20T15:02:51.199393Z","end":"2026-04-20T15:02:51.803225Z","steps":["trace[1355759051] 'range keys from in-memory index tree' (duration: 603.691252ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T15:02:51.874203Z","caller":"traceutil/trace.go:171","msg":"trace[651771005] linearizableReadLoop","detail":"{readStateIndex:20305; appliedIndex:20303; }","duration":"828.096651ms","start":"2026-04-20T15:02:51.046084Z","end":"2026-04-20T15:02:51.87418Z","steps":["trace[651771005] 'read index received' (duration: 35.542217ms)","trace[651771005] 'applied index is now lower than readState.Index' (duration: 792.553754ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T15:02:51.874285Z","caller":"traceutil/trace.go:171","msg":"trace[354582508] transaction","detail":"{read_only:false; response_revision:19710; number_of_response:1; }","duration":"857.016438ms","start":"2026-04-20T15:02:51.017263Z","end":"2026-04-20T15:02:51.874279Z","steps":["trace[354582508] 'process raft request' (duration: 856.777563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:02:51.874363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:02:51.017242Z","time spent":"857.061029ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T15:02:51.874701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.602063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-20T15:02:51.874812Z","caller":"traceutil/trace.go:171","msg":"trace[1372845000] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19710; }","duration":"828.752746ms","start":"2026-04-20T15:02:51.04603Z","end":"2026-04-20T15:02:51.874782Z","steps":["trace[1372845000] 'agreement among raft nodes before linearized reading' (duration: 828.50718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:02:51.875242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:02:51.046009Z","time spent":"829.182447ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T15:02:51.876212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.474333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T15:02:51.876254Z","caller":"traceutil/trace.go:171","msg":"trace[259642365] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:19711; }","duration":"473.547765ms","start":"2026-04-20T15:02:51.402695Z","end":"2026-04-20T15:02:51.876242Z","steps":["trace[259642365] 'agreement among raft nodes before linearized reading' (duration: 473.448323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:02:51.876291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:02:51.402678Z","time spent":"473.600926ms","remote":"127.0.0.1:36630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true "} {"level":"info","ts":"2026-04-20T15:02:51.87652Z","caller":"traceutil/trace.go:171","msg":"trace[327438735] transaction","detail":"{read_only:false; response_revision:19711; number_of_response:1; }","duration":"525.170929ms","start":"2026-04-20T15:02:51.351337Z","end":"2026-04-20T15:02:51.876508Z","steps":["trace[327438735] 'process raft request' (duration: 524.670227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:02:51.876621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:02:51.351319Z","time spent":"525.234651ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T15:02:51.876822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.73031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-04-20T15:02:51.876858Z","caller":"traceutil/trace.go:171","msg":"trace[767125553] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19711; }","duration":"159.777871ms","start":"2026-04-20T15:02:51.717071Z","end":"2026-04-20T15:02:51.876849Z","steps":["trace[767125553] 'agreement among raft nodes before linearized reading' (duration: 159.654798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:02:51.877004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.450298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-20T15:02:51.877028Z","caller":"traceutil/trace.go:171","msg":"trace[1192032852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19711; }","duration":"160.499998ms","start":"2026-04-20T15:02:51.716522Z","end":"2026-04-20T15:02:51.877022Z","steps":["trace[1192032852] 'agreement among raft nodes before linearized reading' (duration: 160.433357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:02:51.877146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.595681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-20T15:02:51.877172Z","caller":"traceutil/trace.go:171","msg":"trace[1784798872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19711; }","duration":"160.650212ms","start":"2026-04-20T15:02:51.716515Z","end":"2026-04-20T15:02:51.877165Z","steps":["trace[1784798872] 'agreement among raft nodes before linearized reading' (duration: 160.59335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:02:51.877287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.879257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-20T15:02:51.87731Z","caller":"traceutil/trace.go:171","msg":"trace[166404078] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19711; }","duration":"206.902437ms","start":"2026-04-20T15:02:51.670402Z","end":"2026-04-20T15:02:51.877305Z","steps":["trace[166404078] 'agreement among raft nodes before linearized reading' (duration: 206.846366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:02:51.87743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.343203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29611620-kkjm2\" ","response":"range_response_count:1 size:6959"} {"level":"info","ts":"2026-04-20T15:02:51.877453Z","caller":"traceutil/trace.go:171","msg":"trace[755403224] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29611620-kkjm2; range_end:; response_count:1; response_revision:19711; }","duration":"396.368374ms","start":"2026-04-20T15:02:51.481079Z","end":"2026-04-20T15:02:51.877448Z","steps":["trace[755403224] 'agreement among raft nodes before linearized reading' (duration: 396.305242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:02:51.87747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:02:51.481045Z","time spent":"396.420975ms","remote":"127.0.0.1:35136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":6981,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-29611620-kkjm2\" "} {"level":"warn","ts":"2026-04-20T15:02:51.877617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.396028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130046"} {"level":"info","ts":"2026-04-20T15:02:51.877662Z","caller":"traceutil/trace.go:171","msg":"trace[34729228] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:19711; }","duration":"414.44626ms","start":"2026-04-20T15:02:51.463202Z","end":"2026-04-20T15:02:51.877648Z","steps":["trace[34729228] 'agreement among raft nodes before linearized reading' (duration: 414.350828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:02:51.877692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:02:51.463196Z","time spent":"414.486651ms","remote":"127.0.0.1:35024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":130068,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-04-20T15:02:51.877806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.667616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-04-20T15:02:51.877877Z","caller":"traceutil/trace.go:171","msg":"trace[1074913205] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:19711; }","duration":"429.770829ms","start":"2026-04-20T15:02:51.448091Z","end":"2026-04-20T15:02:51.877862Z","steps":["trace[1074913205] 'agreement among raft nodes before linearized reading' (duration: 429.564204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:02:51.877927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:02:51.448081Z","time spent":"429.839241ms","remote":"127.0.0.1:35028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-04-20T15:02:56.414038Z","caller":"traceutil/trace.go:171","msg":"trace[437224069] transaction","detail":"{read_only:false; response_revision:19784; number_of_response:1; }","duration":"106.118058ms","start":"2026-04-20T15:02:56.307901Z","end":"2026-04-20T15:02:56.414019Z","steps":["trace[437224069] 'process raft request' (duration: 105.610735ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T15:02:56.75872Z","caller":"traceutil/trace.go:171","msg":"trace[847729386] transaction","detail":"{read_only:false; response_revision:19788; number_of_response:1; }","duration":"187.696545ms","start":"2026-04-20T15:02:56.570991Z","end":"2026-04-20T15:02:56.758687Z","steps":["trace[847729386] 'process raft request' (duration: 119.343737ms)","trace[847729386] 'compare' (duration: 68.124461ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T15:02:56.758752Z","caller":"traceutil/trace.go:171","msg":"trace[615423016] transaction","detail":"{read_only:false; response_revision:19789; number_of_response:1; }","duration":"175.101402ms","start":"2026-04-20T15:02:56.58362Z","end":"2026-04-20T15:02:56.758722Z","steps":["trace[615423016] 'process raft request' (duration: 174.984979ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T15:02:57.196764Z","caller":"traceutil/trace.go:171","msg":"trace[1325678900] transaction","detail":"{read_only:false; response_revision:19790; number_of_response:1; }","duration":"151.025251ms","start":"2026-04-20T15:02:57.045716Z","end":"2026-04-20T15:02:57.196741Z","steps":["trace[1325678900] 'process raft request' (duration: 150.887017ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T15:03:29.052654Z","caller":"traceutil/trace.go:171","msg":"trace[712161339] transaction","detail":"{read_only:false; response_revision:20258; number_of_response:1; }","duration":"151.327587ms","start":"2026-04-20T15:03:28.90131Z","end":"2026-04-20T15:03:29.052638Z","steps":["trace[712161339] 'process raft request' (duration: 151.213145ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T15:03:29.400525Z","caller":"traceutil/trace.go:171","msg":"trace[200936748] linearizableReadLoop","detail":"{readStateIndex:20870; appliedIndex:20868; }","duration":"414.555143ms","start":"2026-04-20T15:03:28.985946Z","end":"2026-04-20T15:03:29.400501Z","steps":["trace[200936748] 'read index received' (duration: 66.555093ms)","trace[200936748] 'applied index is now lower than readState.Index' (duration: 347.99906ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T15:03:29.400773Z","caller":"traceutil/trace.go:171","msg":"trace[478639403] transaction","detail":"{read_only:false; response_revision:20259; number_of_response:1; }","duration":"491.678972ms","start":"2026-04-20T15:03:28.909083Z","end":"2026-04-20T15:03:29.400762Z","steps":["trace[478639403] 'process raft request' (duration: 409.957152ms)","trace[478639403] 'compare' (duration: 81.106434ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T15:03:29.400868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:03:28.909061Z","time spent":"491.739404ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T15:03:29.401226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.244759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T15:03:29.401341Z","caller":"traceutil/trace.go:171","msg":"trace[1897100309] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:20259; }","duration":"415.438284ms","start":"2026-04-20T15:03:28.98586Z","end":"2026-04-20T15:03:29.401298Z","steps":["trace[1897100309] 'agreement among raft nodes before linearized reading' (duration: 414.930601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:29.40147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:03:28.985838Z","time spent":"415.615168ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":28,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true "} {"level":"info","ts":"2026-04-20T15:03:29.717083Z","caller":"traceutil/trace.go:171","msg":"trace[1976245781] linearizableReadLoop","detail":"{readStateIndex:20871; appliedIndex:20870; }","duration":"316.467338ms","start":"2026-04-20T15:03:29.400585Z","end":"2026-04-20T15:03:29.717053Z","steps":["trace[1976245781] 'read index received' (duration: 255.735674ms)","trace[1976245781] 'applied index is now lower than readState.Index' (duration: 60.730444ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T15:03:29.717177Z","caller":"traceutil/trace.go:171","msg":"trace[1786186158] transaction","detail":"{read_only:false; response_revision:20260; number_of_response:1; }","duration":"371.447984ms","start":"2026-04-20T15:03:29.345669Z","end":"2026-04-20T15:03:29.717117Z","steps":["trace[1786186158] 'process raft request' (duration: 310.740551ms)","trace[1786186158] 'compare' (duration: 60.442287ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T15:03:29.717865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:03:29.345644Z","time spent":"372.166191ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T15:03:29.717195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.866225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T15:03:29.718214Z","caller":"traceutil/trace.go:171","msg":"trace[716126970] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:20260; }","duration":"723.896719ms","start":"2026-04-20T15:03:28.994301Z","end":"2026-04-20T15:03:29.718198Z","steps":["trace[716126970] 'agreement among raft nodes before linearized reading' (duration: 722.809273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:29.718252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.390108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-04-20T15:03:29.718269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:03:28.994287Z","time spent":"723.96374ms","remote":"127.0.0.1:35192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-db-sync\" "} {"level":"info","ts":"2026-04-20T15:03:29.718288Z","caller":"traceutil/trace.go:171","msg":"trace[880235203] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20260; }","duration":"185.45606ms","start":"2026-04-20T15:03:29.532816Z","end":"2026-04-20T15:03:29.718272Z","steps":["trace[880235203] 'agreement among raft nodes before linearized reading' (duration: 185.333697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:29.717205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.883855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T15:03:29.718353Z","caller":"traceutil/trace.go:171","msg":"trace[805351579] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:20260; }","duration":"724.054672ms","start":"2026-04-20T15:03:28.994277Z","end":"2026-04-20T15:03:29.718332Z","steps":["trace[805351579] 'agreement among raft nodes before linearized reading' (duration: 722.849494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:29.717283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.553955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-20T15:03:29.717284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.018018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-20T15:03:29.717302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.646963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-zftfl\" ","response":"range_response_count:1 size:9712"} {"level":"warn","ts":"2026-04-20T15:03:29.717446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.055429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T15:03:29.718452Z","caller":"traceutil/trace.go:171","msg":"trace[731510277] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:20260; }","duration":"724.110595ms","start":"2026-04-20T15:03:28.994314Z","end":"2026-04-20T15:03:29.718425Z","steps":["trace[731510277] 'agreement among raft nodes before linearized reading' (duration: 722.863675ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T15:03:29.718465Z","caller":"traceutil/trace.go:171","msg":"trace[731821632] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:20260; }","duration":"724.214807ms","start":"2026-04-20T15:03:28.994228Z","end":"2026-04-20T15:03:29.718443Z","steps":["trace[731821632] 'agreement among raft nodes before linearized reading' (duration: 723.031389ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T15:03:29.718489Z","caller":"traceutil/trace.go:171","msg":"trace[308250509] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-zftfl; range_end:; response_count:1; response_revision:20260; }","duration":"673.855603ms","start":"2026-04-20T15:03:29.044612Z","end":"2026-04-20T15:03:29.718468Z","steps":["trace[308250509] 'agreement among raft nodes before linearized reading' (duration: 672.46413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:29.718507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:03:28.994296Z","time spent":"724.192946ms","remote":"127.0.0.1:35192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"warn","ts":"2026-04-20T15:03:29.718528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:03:28.994209Z","time spent":"724.292688ms","remote":"127.0.0.1:35192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"warn","ts":"2026-04-20T15:03:29.718558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:03:29.044597Z","time spent":"673.944544ms","remote":"127.0.0.1:35136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9734,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-zftfl\" "} {"level":"warn","ts":"2026-04-20T15:03:29.718615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:03:28.994266Z","time spent":"724.32881ms","remote":"127.0.0.1:35192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-rabbit-init\" "} {"level":"info","ts":"2026-04-20T15:03:29.718731Z","caller":"traceutil/trace.go:171","msg":"trace[1655971380] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:20260; }","duration":"457.763033ms","start":"2026-04-20T15:03:29.260657Z","end":"2026-04-20T15:03:29.71842Z","steps":["trace[1655971380] 'agreement among raft nodes before linearized reading' (duration: 456.592236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:29.718818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:03:29.260636Z","time spent":"458.160503ms","remote":"127.0.0.1:51548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":6,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true "} {"level":"info","ts":"2026-04-20T15:03:38.494008Z","caller":"traceutil/trace.go:171","msg":"trace[1506256962] transaction","detail":"{read_only:false; response_revision:20351; number_of_response:1; }","duration":"243.08492ms","start":"2026-04-20T15:03:38.250897Z","end":"2026-04-20T15:03:38.493982Z","steps":["trace[1506256962] 'process raft request' (duration: 242.892836ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T15:03:38.620184Z","caller":"traceutil/trace.go:171","msg":"trace[1773041191] transaction","detail":"{read_only:false; response_revision:20352; number_of_response:1; }","duration":"368.907613ms","start":"2026-04-20T15:03:38.251256Z","end":"2026-04-20T15:03:38.620163Z","steps":["trace[1773041191] 'process raft request' (duration: 361.152026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:38.620327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:03:38.251235Z","time spent":"369.029155ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T15:03:38.620448Z","caller":"traceutil/trace.go:171","msg":"trace[637070486] transaction","detail":"{read_only:false; response_revision:20353; number_of_response:1; }","duration":"367.278913ms","start":"2026-04-20T15:03:38.253164Z","end":"2026-04-20T15:03:38.620443Z","steps":["trace[637070486] 'process raft request' (duration: 366.943185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:38.620477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:03:38.253133Z","time spent":"367.329944ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T15:03:38.797046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.731449ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-20T15:03:38.797404Z","caller":"traceutil/trace.go:171","msg":"trace[83401352] linearizableReadLoop","detail":"{readStateIndex:20965; appliedIndex:20963; }","duration":"545.803596ms","start":"2026-04-20T15:03:38.251544Z","end":"2026-04-20T15:03:38.797348Z","steps":["trace[83401352] 'read index received' (duration: 242.196558ms)","trace[83401352] 'applied index is now lower than readState.Index' (duration: 303.597988ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T15:03:38.797503Z","caller":"traceutil/trace.go:171","msg":"trace[1777092449] transaction","detail":"{read_only:false; response_revision:20354; number_of_response:1; }","duration":"179.474526ms","start":"2026-04-20T15:03:38.618014Z","end":"2026-04-20T15:03:38.797489Z","steps":["trace[1777092449] 'process raft request' (duration: 179.171438ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T15:03:38.797447Z","caller":"traceutil/trace.go:171","msg":"trace[1659423339] transaction","detail":"{read_only:false; response_revision:20355; number_of_response:1; }","duration":"118.23455ms","start":"2026-04-20T15:03:38.679189Z","end":"2026-04-20T15:03:38.797424Z","steps":["trace[1659423339] 'process raft request' (duration: 118.164038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:38.797652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.085333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-20T15:03:38.797731Z","caller":"traceutil/trace.go:171","msg":"trace[1615453315] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20355; }","duration":"546.237657ms","start":"2026-04-20T15:03:38.251475Z","end":"2026-04-20T15:03:38.797713Z","steps":["trace[1615453315] 'agreement among raft nodes before linearized reading' (duration: 546.028132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:38.797775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:03:38.251451Z","time spent":"546.315398ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T15:03:38.797985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.688413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-20T15:03:38.797986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.912783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T15:03:38.798033Z","caller":"traceutil/trace.go:171","msg":"trace[543450312] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:20355; }","duration":"121.769024ms","start":"2026-04-20T15:03:38.676253Z","end":"2026-04-20T15:03:38.798022Z","steps":["trace[543450312] 'agreement among raft nodes before linearized reading' (duration: 121.681282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:38.798062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.607945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-20T15:03:38.798126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.285779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29611620-kkjm2\" ","response":"range_response_count:1 size:6959"} {"level":"warn","ts":"2026-04-20T15:03:38.79813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.62123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"info","ts":"2026-04-20T15:03:38.798172Z","caller":"traceutil/trace.go:171","msg":"trace[1284115500] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29611620-kkjm2; range_end:; response_count:1; response_revision:20355; }","duration":"327.361711ms","start":"2026-04-20T15:03:38.4708Z","end":"2026-04-20T15:03:38.798161Z","steps":["trace[1284115500] 'agreement among raft nodes before linearized reading' (duration: 327.224217ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T15:03:38.798175Z","caller":"traceutil/trace.go:171","msg":"trace[720023182] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:20355; }","duration":"122.763989ms","start":"2026-04-20T15:03:38.675384Z","end":"2026-04-20T15:03:38.798148Z","steps":["trace[720023182] 'agreement among raft nodes before linearized reading' (duration: 122.572765ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T15:03:38.7982Z","caller":"traceutil/trace.go:171","msg":"trace[1004603289] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:20355; }","duration":"116.706332ms","start":"2026-04-20T15:03:38.681476Z","end":"2026-04-20T15:03:38.798182Z","steps":["trace[1004603289] 'agreement among raft nodes before linearized reading' (duration: 116.452656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:38.798214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:03:38.470785Z","time spent":"327.421753ms","remote":"127.0.0.1:35136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":6981,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-29611620-kkjm2\" "} {"level":"warn","ts":"2026-04-20T15:03:38.798278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.533436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T15:03:38.798072Z","caller":"traceutil/trace.go:171","msg":"trace[1228980250] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:20355; }","duration":"123.030696ms","start":"2026-04-20T15:03:38.675017Z","end":"2026-04-20T15:03:38.798048Z","steps":["trace[1228980250] 'agreement among raft nodes before linearized reading' (duration: 122.904273ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T15:03:38.79835Z","caller":"traceutil/trace.go:171","msg":"trace[1869734198] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:20355; }","duration":"510.635149ms","start":"2026-04-20T15:03:38.287699Z","end":"2026-04-20T15:03:38.798334Z","steps":["trace[1869734198] 'agreement among raft nodes before linearized reading' (duration: 510.533016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:38.798308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.372992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T15:03:38.798434Z","caller":"traceutil/trace.go:171","msg":"trace[1508013940] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:20355; }","duration":"510.524316ms","start":"2026-04-20T15:03:38.28789Z","end":"2026-04-20T15:03:38.798415Z","steps":["trace[1508013940] 'agreement among raft nodes before linearized reading' (duration: 510.30827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:38.79838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:03:38.287687Z","time spent":"510.68558ms","remote":"127.0.0.1:35192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-rabbit-init\" "} {"level":"warn","ts":"2026-04-20T15:03:38.79854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:03:38.28788Z","time spent":"510.643928ms","remote":"127.0.0.1:35192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"warn","ts":"2026-04-20T15:03:38.797798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.328151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T15:03:38.798889Z","caller":"traceutil/trace.go:171","msg":"trace[772058722] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:20355; }","duration":"511.479159ms","start":"2026-04-20T15:03:38.28739Z","end":"2026-04-20T15:03:38.798869Z","steps":["trace[772058722] 'agreement among raft nodes before linearized reading' (duration: 510.319771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:38.798973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:03:38.287363Z","time spent":"511.593663ms","remote":"127.0.0.1:35192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"warn","ts":"2026-04-20T15:03:38.798313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.085433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"info","ts":"2026-04-20T15:03:38.799155Z","caller":"traceutil/trace.go:171","msg":"trace[357637965] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:20355; }","duration":"507.952085ms","start":"2026-04-20T15:03:38.291184Z","end":"2026-04-20T15:03:38.799136Z","steps":["trace[357637965] 'agreement among raft nodes before linearized reading' (duration: 506.782606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:38.799262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:03:38.291173Z","time spent":"508.072437ms","remote":"127.0.0.1:35192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5029,"request content":"key:\"/registry/jobs/openstack/manila-db-sync\" "} {"level":"warn","ts":"2026-04-20T15:03:39.158217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.036578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-20T15:03:39.158459Z","caller":"traceutil/trace.go:171","msg":"trace[233647326] linearizableReadLoop","detail":"{readStateIndex:20970; appliedIndex:20969; }","duration":"309.819869ms","start":"2026-04-20T15:03:38.848625Z","end":"2026-04-20T15:03:39.158445Z","steps":["trace[233647326] 'read index received' (duration: 205.415642ms)","trace[233647326] 'applied index is now lower than readState.Index' (duration: 104.402527ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T15:03:39.158607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.959472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T15:03:39.158691Z","caller":"traceutil/trace.go:171","msg":"trace[1744100916] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:20356; }","duration":"310.056584ms","start":"2026-04-20T15:03:38.848612Z","end":"2026-04-20T15:03:39.158669Z","steps":["trace[1744100916] 'agreement among raft nodes before linearized reading' (duration: 309.918771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:39.158742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:03:38.848595Z","time spent":"310.133036ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":28,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"info","ts":"2026-04-20T15:03:39.15875Z","caller":"traceutil/trace.go:171","msg":"trace[1010604551] transaction","detail":"{read_only:false; response_revision:20356; number_of_response:1; }","duration":"355.520189ms","start":"2026-04-20T15:03:38.802988Z","end":"2026-04-20T15:03:39.158508Z","steps":["trace[1010604551] 'process raft request' (duration: 251.120303ms)","trace[1010604551] 'compare' (duration: 103.775521ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T15:03:39.15893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:03:38.80297Z","time spent":"355.862178ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T15:03:39.16227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.262076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-20T15:03:39.162307Z","caller":"traceutil/trace.go:171","msg":"trace[355842405] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:20357; }","duration":"251.338628ms","start":"2026-04-20T15:03:38.91096Z","end":"2026-04-20T15:03:39.162299Z","steps":["trace[355842405] 'agreement among raft nodes before linearized reading' (duration: 251.216595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:39.162396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.848349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T15:03:39.162499Z","caller":"traceutil/trace.go:171","msg":"trace[1673017263] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:20357; }","duration":"225.011944ms","start":"2026-04-20T15:03:38.937474Z","end":"2026-04-20T15:03:39.162486Z","steps":["trace[1673017263] 'agreement among raft nodes before linearized reading' (duration: 224.893681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:39.162275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.294535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T15:03:39.162713Z","caller":"traceutil/trace.go:171","msg":"trace[1533496983] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:20357; }","duration":"119.762967ms","start":"2026-04-20T15:03:39.042931Z","end":"2026-04-20T15:03:39.162694Z","steps":["trace[1533496983] 'agreement among raft nodes before linearized reading' (duration: 119.280115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:39.494966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.274844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-20T15:03:39.495411Z","caller":"traceutil/trace.go:171","msg":"trace[1980834573] transaction","detail":"{read_only:false; response_revision:20358; number_of_response:1; }","duration":"328.525709ms","start":"2026-04-20T15:03:39.166856Z","end":"2026-04-20T15:03:39.495382Z","steps":["trace[1980834573] 'process raft request' (duration: 111.725764ms)","trace[1980834573] 'compare' (duration: 216.116399ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T15:03:39.495569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:03:39.166834Z","time spent":"328.639471ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T15:03:40.012078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.076478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"info","ts":"2026-04-20T15:03:40.012189Z","caller":"traceutil/trace.go:171","msg":"trace[152581689] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:20359; }","duration":"267.237912ms","start":"2026-04-20T15:03:39.744925Z","end":"2026-04-20T15:03:40.012163Z","steps":["trace[152581689] 'range keys from in-memory index tree' (duration: 266.894684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:40.012308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.784779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T15:03:40.012366Z","caller":"traceutil/trace.go:171","msg":"trace[1380752605] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:20359; }","duration":"268.877271ms","start":"2026-04-20T15:03:39.743477Z","end":"2026-04-20T15:03:40.012354Z","steps":["trace[1380752605] 'range keys from in-memory index tree' (duration: 268.725027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:40.012387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.454251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-20T15:03:40.012282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.757761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7749"} {"level":"info","ts":"2026-04-20T15:03:40.012447Z","caller":"traceutil/trace.go:171","msg":"trace[354165065] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:20359; }","duration":"268.537313ms","start":"2026-04-20T15:03:39.743893Z","end":"2026-04-20T15:03:40.012431Z","steps":["trace[354165065] 'range keys from in-memory index tree' (duration: 268.41817ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T15:03:40.012472Z","caller":"traceutil/trace.go:171","msg":"trace[696000222] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:20359; }","duration":"354.995727ms","start":"2026-04-20T15:03:39.657459Z","end":"2026-04-20T15:03:40.012455Z","steps":["trace[696000222] 'range keys from in-memory index tree' (duration: 354.529686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:40.012502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.267786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T15:03:40.012518Z","caller":"traceutil/trace.go:171","msg":"trace[444713085] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:20359; }","duration":"268.292768ms","start":"2026-04-20T15:03:39.744221Z","end":"2026-04-20T15:03:40.012514Z","steps":["trace[444713085] 'range keys from in-memory index tree' (duration: 268.242926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:40.012515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:03:39.657436Z","time spent":"355.070289ms","remote":"127.0.0.1:54760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7771,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"warn","ts":"2026-04-20T15:03:40.012713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.145268ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-20T15:03:40.01275Z","caller":"traceutil/trace.go:171","msg":"trace[391659577] linearizableReadLoop","detail":"{readStateIndex:20974; appliedIndex:20973; }","duration":"247.879635ms","start":"2026-04-20T15:03:39.764865Z","end":"2026-04-20T15:03:40.012745Z","steps":["trace[391659577] 'read index received' (duration: 107.589904ms)","trace[391659577] 'applied index is now lower than readState.Index' (duration: 140.289051ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T15:03:40.012793Z","caller":"traceutil/trace.go:171","msg":"trace[1423236013] transaction","detail":"{read_only:false; response_revision:20360; number_of_response:1; }","duration":"257.573009ms","start":"2026-04-20T15:03:39.755195Z","end":"2026-04-20T15:03:40.012768Z","steps":["trace[1423236013] 'process raft request' (duration: 117.324628ms)","trace[1423236013] 'compare' (duration: 139.747939ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T15:03:40.012867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.003779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-04-20T15:03:40.012882Z","caller":"traceutil/trace.go:171","msg":"trace[1589355588] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:20360; }","duration":"248.019009ms","start":"2026-04-20T15:03:39.764857Z","end":"2026-04-20T15:03:40.012876Z","steps":["trace[1589355588] 'agreement among raft nodes before linearized reading' (duration: 247.946837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:40.013015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.238848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T15:03:40.013039Z","caller":"traceutil/trace.go:171","msg":"trace[391140719] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:20360; }","duration":"193.302069ms","start":"2026-04-20T15:03:39.819731Z","end":"2026-04-20T15:03:40.013033Z","steps":["trace[391140719] 'agreement among raft nodes before linearized reading' (duration: 193.264568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:40.013074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.915821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"info","ts":"2026-04-20T15:03:40.013107Z","caller":"traceutil/trace.go:171","msg":"trace[1042220917] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:20360; }","duration":"193.074744ms","start":"2026-04-20T15:03:39.820021Z","end":"2026-04-20T15:03:40.013096Z","steps":["trace[1042220917] 'agreement among raft nodes before linearized reading' (duration: 192.977412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:40.013108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.241768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T15:03:40.013136Z","caller":"traceutil/trace.go:171","msg":"trace[2136699823] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:20360; }","duration":"193.32296ms","start":"2026-04-20T15:03:39.819807Z","end":"2026-04-20T15:03:40.01313Z","steps":["trace[2136699823] 'agreement among raft nodes before linearized reading' (duration: 193.276959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:40.013054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.51487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-20T15:03:40.01316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.921594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T15:03:40.013198Z","caller":"traceutil/trace.go:171","msg":"trace[2083415649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20360; }","duration":"236.716706ms","start":"2026-04-20T15:03:39.77646Z","end":"2026-04-20T15:03:40.013177Z","steps":["trace[2083415649] 'agreement among raft nodes before linearized reading' (duration: 236.491321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:40.013235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.194522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-zftfl\" ","response":"range_response_count:1 size:9712"} {"level":"info","ts":"2026-04-20T15:03:40.013239Z","caller":"traceutil/trace.go:171","msg":"trace[237968169] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:20360; }","duration":"194.024326ms","start":"2026-04-20T15:03:39.819189Z","end":"2026-04-20T15:03:40.013214Z","steps":["trace[237968169] 'agreement among raft nodes before linearized reading' (duration: 193.932644ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T15:03:40.013279Z","caller":"traceutil/trace.go:171","msg":"trace[544145645] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-zftfl; range_end:; response_count:1; response_revision:20360; }","duration":"148.269274ms","start":"2026-04-20T15:03:39.864998Z","end":"2026-04-20T15:03:40.013267Z","steps":["trace[544145645] 'agreement among raft nodes before linearized reading' (duration: 148.151352ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T15:03:40.238054Z","caller":"traceutil/trace.go:171","msg":"trace[1364442573] transaction","detail":"{read_only:false; response_revision:20362; number_of_response:1; }","duration":"140.147098ms","start":"2026-04-20T15:03:40.097887Z","end":"2026-04-20T15:03:40.238034Z","steps":["trace[1364442573] 'process raft request' (duration: 140.000575ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T15:03:40.238093Z","caller":"traceutil/trace.go:171","msg":"trace[623165241] linearizableReadLoop","detail":"{readStateIndex:20976; appliedIndex:20975; }","duration":"129.677656ms","start":"2026-04-20T15:03:40.108378Z","end":"2026-04-20T15:03:40.238055Z","steps":["trace[623165241] 'read index received' (duration: 129.633445ms)","trace[623165241] 'applied index is now lower than readState.Index' (duration: 41.061µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T15:03:40.238301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.530511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-20T15:03:40.238409Z","caller":"traceutil/trace.go:171","msg":"trace[1501651537] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20362; }","duration":"219.662834ms","start":"2026-04-20T15:03:40.018738Z","end":"2026-04-20T15:03:40.238401Z","steps":["trace[1501651537] 'agreement among raft nodes before linearized reading' (duration: 219.472669ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T15:03:40.377943Z","caller":"traceutil/trace.go:171","msg":"trace[673616224] transaction","detail":"{read_only:false; response_revision:20364; number_of_response:1; }","duration":"171.593116ms","start":"2026-04-20T15:03:40.206325Z","end":"2026-04-20T15:03:40.377918Z","steps":["trace[673616224] 'process raft request' (duration: 171.420732ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T15:03:40.378039Z","caller":"traceutil/trace.go:171","msg":"trace[312794444] linearizableReadLoop","detail":"{readStateIndex:20978; appliedIndex:20976; }","duration":"139.657027ms","start":"2026-04-20T15:03:40.23836Z","end":"2026-04-20T15:03:40.378018Z","steps":["trace[312794444] 'read index received' (duration: 68.852ms)","trace[312794444] 'applied index is now lower than readState.Index' (duration: 70.803077ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T15:03:40.378064Z","caller":"traceutil/trace.go:171","msg":"trace[170859547] transaction","detail":"{read_only:false; response_revision:20363; number_of_response:1; }","duration":"175.985732ms","start":"2026-04-20T15:03:40.202061Z","end":"2026-04-20T15:03:40.378047Z","steps":["trace[170859547] 'process raft request' (duration: 105.171206ms)","trace[170859547] 'compare' (duration: 70.319235ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T15:03:40.378066Z","caller":"traceutil/trace.go:171","msg":"trace[1100271404] transaction","detail":"{read_only:false; response_revision:20365; number_of_response:1; }","duration":"135.036225ms","start":"2026-04-20T15:03:40.242995Z","end":"2026-04-20T15:03:40.378031Z","steps":["trace[1100271404] 'process raft request' (duration: 134.804389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:03:40.378231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.897583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-20T15:03:40.378253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.155088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-20T15:03:40.378258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.636282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18231"} {"level":"info","ts":"2026-04-20T15:03:40.378291Z","caller":"traceutil/trace.go:171","msg":"trace[256762419] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20366; }","duration":"128.000845ms","start":"2026-04-20T15:03:40.250273Z","end":"2026-04-20T15:03:40.378274Z","steps":["trace[256762419] 'agreement among raft nodes before linearized reading' (duration: 127.872232ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T15:03:40.378294Z","caller":"traceutil/trace.go:171","msg":"trace[1978011856] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20366; }","duration":"167.239561ms","start":"2026-04-20T15:03:40.211041Z","end":"2026-04-20T15:03:40.378281Z","steps":["trace[1978011856] 'agreement among raft nodes before linearized reading' (duration: 167.102898ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T15:03:40.378323Z","caller":"traceutil/trace.go:171","msg":"trace[775630747] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:20366; }","duration":"138.739424ms","start":"2026-04-20T15:03:40.239569Z","end":"2026-04-20T15:03:40.378308Z","steps":["trace[775630747] 'agreement among raft nodes before linearized reading' (duration: 138.56772ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T15:04:50.598346Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17736} {"level":"info","ts":"2026-04-20T15:04:50.937576Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17736,"took":"335.162699ms","hash":788699879,"current-db-size-bytes":59432960,"current-db-size":"59 MB","current-db-size-in-use-bytes":40517632,"current-db-size-in-use":"40 MB"} {"level":"info","ts":"2026-04-20T15:04:50.937645Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":788699879,"revision":17736,"compact-revision":13545} {"level":"info","ts":"2026-04-20T15:04:55.2192Z","caller":"traceutil/trace.go:171","msg":"trace[1934547049] linearizableReadLoop","detail":"{readStateIndex:21871; appliedIndex:21870; }","duration":"106.722103ms","start":"2026-04-20T15:04:55.11245Z","end":"2026-04-20T15:04:55.219172Z","steps":["trace[1934547049] 'read index received' (duration: 84.117138ms)","trace[1934547049] 'applied index is now lower than readState.Index' (duration: 22.603835ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T15:04:55.219295Z","caller":"traceutil/trace.go:171","msg":"trace[434749858] transaction","detail":"{read_only:false; response_revision:21239; number_of_response:1; }","duration":"106.899567ms","start":"2026-04-20T15:04:55.11237Z","end":"2026-04-20T15:04:55.21927Z","steps":["trace[434749858] 'process raft request' (duration: 84.234251ms)","trace[434749858] 'compare' (duration: 22.442271ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T15:04:55.219398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.930547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:1 size:21345"} {"level":"info","ts":"2026-04-20T15:04:55.219426Z","caller":"traceutil/trace.go:171","msg":"trace[1419215911] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:1; response_revision:21239; }","duration":"106.990898ms","start":"2026-04-20T15:04:55.112427Z","end":"2026-04-20T15:04:55.219417Z","steps":["trace[1419215911] 'agreement among raft nodes before linearized reading' (duration: 106.848955ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T15:04:55.227744Z","caller":"traceutil/trace.go:171","msg":"trace[1429020] transaction","detail":"{read_only:false; response_revision:21240; number_of_response:1; }","duration":"101.531648ms","start":"2026-04-20T15:04:55.126189Z","end":"2026-04-20T15:04:55.22772Z","steps":["trace[1429020] 'process raft request' (duration: 101.225711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:04:55.227731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.186217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-20T15:04:55.227985Z","caller":"traceutil/trace.go:171","msg":"trace[1224373064] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:21241; }","duration":"115.462943ms","start":"2026-04-20T15:04:55.112473Z","end":"2026-04-20T15:04:55.227936Z","steps":["trace[1224373064] 'agreement among raft nodes before linearized reading' (duration: 115.147545ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T15:04:56.838453Z","caller":"traceutil/trace.go:171","msg":"trace[706350561] transaction","detail":"{read_only:false; response_revision:21254; number_of_response:1; }","duration":"137.629567ms","start":"2026-04-20T15:04:56.700799Z","end":"2026-04-20T15:04:56.838428Z","steps":["trace[706350561] 'process raft request' (duration: 137.239698ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T15:05:42.226296Z","caller":"traceutil/trace.go:171","msg":"trace[1841853920] transaction","detail":"{read_only:false; response_revision:21826; number_of_response:1; }","duration":"269.054287ms","start":"2026-04-20T15:05:41.957227Z","end":"2026-04-20T15:05:42.226282Z","steps":["trace[1841853920] 'process raft request' (duration: 268.79143ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T15:06:39.647847Z","caller":"traceutil/trace.go:171","msg":"trace[1058407988] linearizableReadLoop","detail":"{readStateIndex:23044; appliedIndex:23043; }","duration":"447.019524ms","start":"2026-04-20T15:06:39.200803Z","end":"2026-04-20T15:06:39.647822Z","steps":["trace[1058407988] 'read index received' (duration: 446.957803ms)","trace[1058407988] 'applied index is now lower than readState.Index' (duration: 60.681µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T15:06:39.648054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.232859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18231"} {"level":"info","ts":"2026-04-20T15:06:39.648087Z","caller":"traceutil/trace.go:171","msg":"trace[968693190] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:22387; }","duration":"447.29534ms","start":"2026-04-20T15:06:39.200784Z","end":"2026-04-20T15:06:39.64808Z","steps":["trace[968693190] 'agreement among raft nodes before linearized reading' (duration: 447.141347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:06:39.64811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:06:39.200776Z","time spent":"447.329761ms","remote":"127.0.0.1:35122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18253,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-20T15:06:39.648266Z","caller":"traceutil/trace.go:171","msg":"trace[1051827437] transaction","detail":"{read_only:false; response_revision:22387; number_of_response:1; }","duration":"447.982717ms","start":"2026-04-20T15:06:39.200242Z","end":"2026-04-20T15:06:39.648225Z","steps":["trace[1051827437] 'process raft request' (duration: 447.411053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:06:39.648513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:06:39.200234Z","time spent":"448.1481ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T15:06:39.651144Z","caller":"traceutil/trace.go:171","msg":"trace[808846293] transaction","detail":"{read_only:false; response_revision:22388; number_of_response:1; }","duration":"387.531533ms","start":"2026-04-20T15:06:39.26359Z","end":"2026-04-20T15:06:39.651122Z","steps":["trace[808846293] 'process raft request' (duration: 387.420651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:06:39.651291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:06:39.263565Z","time spent":"387.647586ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T15:07:51.434953Z","caller":"traceutil/trace.go:171","msg":"trace[1246886840] linearizableReadLoop","detail":"{readStateIndex:23677; appliedIndex:23676; }","duration":"232.704445ms","start":"2026-04-20T15:07:51.202233Z","end":"2026-04-20T15:07:51.434938Z","steps":["trace[1246886840] 'read index received' (duration: 232.609743ms)","trace[1246886840] 'applied index is now lower than readState.Index' (duration: 94.022µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T15:07:51.435185Z","caller":"traceutil/trace.go:171","msg":"trace[891175919] transaction","detail":"{read_only:false; response_revision:23002; number_of_response:1; }","duration":"293.487339ms","start":"2026-04-20T15:07:51.141495Z","end":"2026-04-20T15:07:51.434982Z","steps":["trace[891175919] 'process raft request' (duration: 293.336545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:07:51.435324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.080534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:39 size:323653"} {"level":"info","ts":"2026-04-20T15:07:51.435355Z","caller":"traceutil/trace.go:171","msg":"trace[685797130] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:39; response_revision:23002; }","duration":"233.151576ms","start":"2026-04-20T15:07:51.202197Z","end":"2026-04-20T15:07:51.435348Z","steps":["trace[685797130] 'agreement among raft nodes before linearized reading' (duration: 232.824058ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T15:07:51.692634Z","caller":"traceutil/trace.go:171","msg":"trace[150918229] linearizableReadLoop","detail":"{readStateIndex:23678; appliedIndex:23677; }","duration":"257.595805ms","start":"2026-04-20T15:07:51.435003Z","end":"2026-04-20T15:07:51.692598Z","steps":["trace[150918229] 'read index received' (duration: 255.900195ms)","trace[150918229] 'applied index is now lower than readState.Index' (duration: 1.69433ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T15:07:51.692755Z","caller":"traceutil/trace.go:171","msg":"trace[1586729967] transaction","detail":"{read_only:false; response_revision:23003; number_of_response:1; }","duration":"488.040685ms","start":"2026-04-20T15:07:51.204687Z","end":"2026-04-20T15:07:51.692728Z","steps":["trace[1586729967] 'process raft request' (duration: 486.311674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T15:07:51.692888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T15:07:51.20467Z","time spent":"488.140338ms","remote":"127.0.0.1:35222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T15:07:51.693088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.36516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-04-20T15:07:51.693133Z","caller":"traceutil/trace.go:171","msg":"trace[1344418095] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:23003; }","duration":"267.462043ms","start":"2026-04-20T15:07:51.42566Z","end":"2026-04-20T15:07:51.693122Z","steps":["trace[1344418095] 'agreement among raft nodes before linearized reading' (duration: 267.081773ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T15:09:50.606304Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21186} {"level":"info","ts":"2026-04-20T15:09:50.76754Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21186,"took":"156.999447ms","hash":582072410,"current-db-size-bytes":59432960,"current-db-size":"59 MB","current-db-size-in-use-bytes":35639296,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-04-20T15:09:50.767603Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":582072410,"revision":21186,"compact-revision":17736} {"level":"info","ts":"2026-04-20T15:14:50.614516Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23910} {"level":"info","ts":"2026-04-20T15:14:50.75382Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23910,"took":"134.855562ms","hash":1101651610,"current-db-size-bytes":61120512,"current-db-size":"61 MB","current-db-size-in-use-bytes":31911936,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-20T15:14:50.753917Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1101651610,"revision":23910,"compact-revision":21186} {"level":"info","ts":"2026-04-20T15:16:52.598786Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006cdc.wal"} {"level":"info","ts":"2026-04-20T15:19:50.625361Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26139} {"level":"info","ts":"2026-04-20T15:19:50.746909Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26139,"took":"118.620138ms","hash":3335002855,"current-db-size-bytes":61120512,"current-db-size":"61 MB","current-db-size-in-use-bytes":33103872,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-20T15:19:50.746972Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3335002855,"revision":26139,"compact-revision":23910} {"level":"info","ts":"2026-04-20T15:21:30.595535Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T15:21:30.600023Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-20T15:21:30.600103Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}