{"level":"warn","ts":"2026-04-09T02:06:09.401977Z","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-09T02:06:09.402107Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.244: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.244:2380","--initial-cluster=instance=https://199.204.45.244:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.244:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.244: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-09T02:06:09.402195Z","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-09T02:06:09.402211Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-04-09T02:06:09.402234Z","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-09T02:06:09.40311Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"]} {"level":"info","ts":"2026-04-09T02:06:09.403398Z","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.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.244: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-09T02:06:09.407476Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.817079ms"} {"level":"info","ts":"2026-04-09T02:06:09.414038Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1686e9b1ea717edf","cluster-id":"71c60fe001ca5b3c"} {"level":"info","ts":"2026-04-09T02:06:09.414127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T02:06:09.414155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 0"} {"level":"info","ts":"2026-04-09T02:06:09.41417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1686e9b1ea717edf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T02:06:09.414179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 1"} {"level":"info","ts":"2026-04-09T02:06:09.414218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"warn","ts":"2026-04-09T02:06:09.426553Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T02:06:09.428747Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T02:06:09.429969Z","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-09T02:06:09.43308Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1686e9b1ea717edf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T02:06:09.433799Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1686e9b1ea717edf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T02:06:09.43392Z","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-09T02:06:09.434212Z","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-09T02:06:09.436575Z","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-09T02:06:09.436865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"info","ts":"2026-04-09T02:06:09.437084Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","added-peer-id":"1686e9b1ea717edf","added-peer-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-04-09T02:06:09.438493Z","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-09T02:06:09.438669Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-04-09T02:06:09.43872Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-04-09T02:06:09.438757Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1686e9b1ea717edf","initial-advertise-peer-urls":["https://199.204.45.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T02:06:09.438807Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T02:06:10.115091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T02:06:10.115157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T02:06:10.115183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgPreVoteResp from 1686e9b1ea717edf at term 1"} {"level":"info","ts":"2026-04-09T02:06:10.1152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became candidate at term 2"} {"level":"info","ts":"2026-04-09T02:06:10.115207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgVoteResp from 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-04-09T02:06:10.115234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became leader at term 2"} {"level":"info","ts":"2026-04-09T02:06:10.115243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1686e9b1ea717edf elected leader 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-04-09T02:06:10.116222Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:06:10.117155Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T02:06:10.11716Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1686e9b1ea717edf","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.244:2379]}","request-path":"/0/members/1686e9b1ea717edf/attributes","cluster-id":"71c60fe001ca5b3c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T02:06:10.117248Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T02:06:10.117872Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T02:06:10.117973Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T02:06:10.11898Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:06:10.119115Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:06:10.119252Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:06:10.120287Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.244:2379"} {"level":"info","ts":"2026-04-09T02:06:10.120809Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T02:06:17.295514Z","caller":"traceutil/trace.go:171","msg":"trace[554837052] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"136.295953ms","start":"2026-04-09T02:06:17.159188Z","end":"2026-04-09T02:06:17.295484Z","steps":["trace[554837052] 'process raft request' (duration: 136.103518ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:17.502751Z","caller":"traceutil/trace.go:171","msg":"trace[842345803] linearizableReadLoop","detail":"{readStateIndex:285; appliedIndex:284; }","duration":"130.978977ms","start":"2026-04-09T02:06:17.371735Z","end":"2026-04-09T02:06:17.502714Z","steps":["trace[842345803] 'read index received' (duration: 40.262541ms)","trace[842345803] 'applied index is now lower than readState.Index' (duration: 90.715056ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:17.502852Z","caller":"traceutil/trace.go:171","msg":"trace[1872380446] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"193.813717ms","start":"2026-04-09T02:06:17.30898Z","end":"2026-04-09T02:06:17.502794Z","steps":["trace[1872380446] 'process raft request' (duration: 103.106032ms)","trace[1872380446] 'compare' (duration: 90.487409ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:17.502936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.171471ms","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-09T02:06:17.503024Z","caller":"traceutil/trace.go:171","msg":"trace[865135476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:275; }","duration":"131.334965ms","start":"2026-04-09T02:06:17.371673Z","end":"2026-04-09T02:06:17.503008Z","steps":["trace[865135476] 'agreement among raft nodes before linearized reading' (duration: 131.157101ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:17.817917Z","caller":"traceutil/trace.go:171","msg":"trace[866442208] linearizableReadLoop","detail":"{readStateIndex:287; appliedIndex:286; }","duration":"171.70021ms","start":"2026-04-09T02:06:17.646188Z","end":"2026-04-09T02:06:17.817888Z","steps":["trace[866442208] 'read index received' (duration: 171.560117ms)","trace[866442208] 'applied index is now lower than readState.Index' (duration: 138.643µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:17.81799Z","caller":"traceutil/trace.go:171","msg":"trace[508830188] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"251.022453ms","start":"2026-04-09T02:06:17.566928Z","end":"2026-04-09T02:06:17.81795Z","steps":["trace[508830188] 'process raft request' (duration: 250.791687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:17.818145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.529142ms","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-09T02:06:17.81819Z","caller":"traceutil/trace.go:171","msg":"trace[462601062] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:277; }","duration":"101.635935ms","start":"2026-04-09T02:06:17.716542Z","end":"2026-04-09T02:06:17.818178Z","steps":["trace[462601062] 'agreement among raft nodes before linearized reading' (duration: 101.504201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:17.818531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.325497ms","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-09T02:06:17.818789Z","caller":"traceutil/trace.go:171","msg":"trace[209102204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:277; }","duration":"172.521051ms","start":"2026-04-09T02:06:17.646108Z","end":"2026-04-09T02:06:17.818629Z","steps":["trace[209102204] 'agreement among raft nodes before linearized reading' (duration: 171.933696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:18.074173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.507957ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:06:18.074304Z","caller":"traceutil/trace.go:171","msg":"trace[1329919886] linearizableReadLoop","detail":"{readStateIndex:289; appliedIndex:287; }","duration":"247.267837ms","start":"2026-04-09T02:06:17.827017Z","end":"2026-04-09T02:06:18.074285Z","steps":["trace[1329919886] 'read index received' (duration: 53.032199ms)","trace[1329919886] 'applied index is now lower than readState.Index' (duration: 194.234268ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:18.0744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.571639ms","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-09T02:06:18.074424Z","caller":"traceutil/trace.go:171","msg":"trace[2057258336] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:279; }","duration":"134.624881ms","start":"2026-04-09T02:06:17.939792Z","end":"2026-04-09T02:06:18.074416Z","steps":["trace[2057258336] 'agreement among raft nodes before linearized reading' (duration: 134.573099ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:18.074385Z","caller":"traceutil/trace.go:171","msg":"trace[1761544850] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"250.656944ms","start":"2026-04-09T02:06:17.823684Z","end":"2026-04-09T02:06:18.074341Z","steps":["trace[1761544850] 'process raft request' (duration: 122.384627ms)","trace[1761544850] 'compare' (duration: 127.366664ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:18.074414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.3797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:18.074494Z","caller":"traceutil/trace.go:171","msg":"trace[1625621479] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:0; response_revision:279; }","duration":"247.490213ms","start":"2026-04-09T02:06:17.826982Z","end":"2026-04-09T02:06:18.074473Z","steps":["trace[1625621479] 'agreement among raft nodes before linearized reading' (duration: 247.363939ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:19.728415Z","caller":"traceutil/trace.go:171","msg":"trace[1186004681] linearizableReadLoop","detail":"{readStateIndex:302; appliedIndex:301; }","duration":"119.527444ms","start":"2026-04-09T02:06:19.608853Z","end":"2026-04-09T02:06:19.728381Z","steps":["trace[1186004681] 'read index received' (duration: 62.228515ms)","trace[1186004681] 'applied index is now lower than readState.Index' (duration: 57.297199ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:19.728571Z","caller":"traceutil/trace.go:171","msg":"trace[731756682] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"139.513135ms","start":"2026-04-09T02:06:19.589032Z","end":"2026-04-09T02:06:19.728546Z","steps":["trace[731756682] 'process raft request' (duration: 82.150405ms)","trace[731756682] 'compare' (duration: 57.055442ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:19.728604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.720428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:19.728645Z","caller":"traceutil/trace.go:171","msg":"trace[1468611146] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:0; response_revision:292; }","duration":"119.81113ms","start":"2026-04-09T02:06:19.608821Z","end":"2026-04-09T02:06:19.728632Z","steps":["trace[1468611146] 'agreement among raft nodes before linearized reading' (duration: 119.673997ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:25.944874Z","caller":"traceutil/trace.go:171","msg":"trace[1311571710] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:379; }","duration":"188.037629ms","start":"2026-04-09T02:06:25.756805Z","end":"2026-04-09T02:06:25.944842Z","steps":["trace[1311571710] 'read index received' (duration: 143.591559ms)","trace[1311571710] 'applied index is now lower than readState.Index' (duration: 44.44471ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:25.944893Z","caller":"traceutil/trace.go:171","msg":"trace[1927914327] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"408.294414ms","start":"2026-04-09T02:06:25.536555Z","end":"2026-04-09T02:06:25.94485Z","steps":["trace[1927914327] 'process raft request' (duration: 363.912916ms)","trace[1927914327] 'compare' (duration: 44.121001ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:25.9451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.409462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:25.94519Z","caller":"traceutil/trace.go:171","msg":"trace[469347205] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:368; }","duration":"105.539825ms","start":"2026-04-09T02:06:25.839629Z","end":"2026-04-09T02:06:25.945169Z","steps":["trace[469347205] 'agreement among raft nodes before linearized reading' (duration: 105.401931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:25.945376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.250315ms","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-09T02:06:25.945428Z","caller":"traceutil/trace.go:171","msg":"trace[1107008458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:368; }","duration":"188.662885ms","start":"2026-04-09T02:06:25.756752Z","end":"2026-04-09T02:06:25.945415Z","steps":["trace[1107008458] 'agreement among raft nodes before linearized reading' (duration: 188.242655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:25.945524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:25.536527Z","time spent":"408.801237ms","remote":"127.0.0.1:34564","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-09T02:06:26.363662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.389637ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:06:26.363775Z","caller":"traceutil/trace.go:171","msg":"trace[247411116] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"414.650646ms","start":"2026-04-09T02:06:25.949105Z","end":"2026-04-09T02:06:26.363755Z","steps":["trace[247411116] 'process raft request' (duration: 228.074484ms)","trace[247411116] 'compare' (duration: 186.244803ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:26.363832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:25.949087Z","time spent":"414.718257ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:06:26.653884Z","caller":"traceutil/trace.go:171","msg":"trace[1972442426] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:380; }","duration":"540.785328ms","start":"2026-04-09T02:06:26.113038Z","end":"2026-04-09T02:06:26.653823Z","steps":["trace[1972442426] 'read index received' (duration: 64.151663ms)","trace[1972442426] 'applied index is now lower than readState.Index' (duration: 476.632015ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:26.654108Z","caller":"traceutil/trace.go:171","msg":"trace[1946784057] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"703.975621ms","start":"2026-04-09T02:06:25.950117Z","end":"2026-04-09T02:06:26.654093Z","steps":["trace[1946784057] 'process raft request' (duration: 702.00168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:26.654278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:25.950102Z","time spent":"704.054354ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:06:26.65435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.235706ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:26.65453Z","caller":"traceutil/trace.go:171","msg":"trace[1252505784] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:370; }","duration":"167.431741ms","start":"2026-04-09T02:06:26.487074Z","end":"2026-04-09T02:06:26.654506Z","steps":["trace[1252505784] 'agreement among raft nodes before linearized reading' (duration: 167.195995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:26.654306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.579677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-09T02:06:26.654392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.324832ms","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-09T02:06:26.654696Z","caller":"traceutil/trace.go:171","msg":"trace[929930573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:370; }","duration":"541.665471ms","start":"2026-04-09T02:06:26.112986Z","end":"2026-04-09T02:06:26.654652Z","steps":["trace[929930573] 'agreement among raft nodes before linearized reading' (duration: 541.2305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:26.654778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:26.112965Z","time spent":"541.791955ms","remote":"127.0.0.1:34564","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-09T02:06:26.654653Z","caller":"traceutil/trace.go:171","msg":"trace[2059292556] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:370; }","duration":"530.904606ms","start":"2026-04-09T02:06:26.123676Z","end":"2026-04-09T02:06:26.654581Z","steps":["trace[2059292556] 'agreement among raft nodes before linearized reading' (duration: 530.542537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:26.65513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:26.123665Z","time spent":"531.44723ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-09T02:06:47.951597Z","caller":"traceutil/trace.go:171","msg":"trace[1197522590] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"238.205594ms","start":"2026-04-09T02:06:47.713345Z","end":"2026-04-09T02:06:47.95155Z","steps":["trace[1197522590] 'process raft request' (duration: 237.542067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:48.531003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.36752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-sgzd4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-09T02:06:48.531114Z","caller":"traceutil/trace.go:171","msg":"trace[724371097] range","detail":"{range_begin:/registry/pods/kube-system/cilium-sgzd4; range_end:; response_count:1; response_revision:578; }","duration":"176.497623ms","start":"2026-04-09T02:06:48.354592Z","end":"2026-04-09T02:06:48.531089Z","steps":["trace[724371097] 'range keys from in-memory index tree' (duration: 176.156475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:49.277518Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142198872903256795,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T02:06:49.843677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.359871ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:49.843872Z","caller":"traceutil/trace.go:171","msg":"trace[1513459286] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:579; }","duration":"201.575736ms","start":"2026-04-09T02:06:49.642262Z","end":"2026-04-09T02:06:49.843838Z","steps":["trace[1513459286] 'range keys from in-memory index tree' (duration: 201.33349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:49.845506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.426674ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:06:49.845791Z","caller":"traceutil/trace.go:171","msg":"trace[390797477] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"1.310206736s","start":"2026-04-09T02:06:48.535554Z","end":"2026-04-09T02:06:49.845761Z","steps":["trace[390797477] 'process raft request' (duration: 824.103899ms)","trace[390797477] 'compare' (duration: 484.235539ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:49.845998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:48.535529Z","time spent":"1.310382641s","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:06:49.84627Z","caller":"traceutil/trace.go:171","msg":"trace[640433576] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"1.276476822s","start":"2026-04-09T02:06:48.569785Z","end":"2026-04-09T02:06:49.846262Z","steps":["trace[640433576] 'process raft request' (duration: 1.275943228s)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:49.847515Z","caller":"traceutil/trace.go:171","msg":"trace[1296615459] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.231619563s","start":"2026-04-09T02:06:48.615776Z","end":"2026-04-09T02:06:49.847395Z","steps":["trace[1296615459] 'process raft request' (duration: 1.230125785s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:49.847773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:48.61575Z","time spent":"1.231919131s","remote":"127.0.0.1:34538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:06:49.848088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:48.569761Z","time spent":"1.277486469s","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:06:49.859195Z","caller":"traceutil/trace.go:171","msg":"trace[534359656] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:598; }","duration":"1.081928256s","start":"2026-04-09T02:06:48.777217Z","end":"2026-04-09T02:06:49.859145Z","steps":["trace[534359656] 'read index received' (duration: 582.439296ms)","trace[534359656] 'applied index is now lower than readState.Index' (duration: 499.48792ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:49.859407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019681872s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T02:06:49.859423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082199524s","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-09T02:06:49.859459Z","caller":"traceutil/trace.go:171","msg":"trace[480961199] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"1.019779894s","start":"2026-04-09T02:06:48.839666Z","end":"2026-04-09T02:06:49.859445Z","steps":["trace[480961199] 'agreement among raft nodes before linearized reading' (duration: 1.019694852s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:49.85946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.081926027s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-09T02:06:49.859488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:48.839643Z","time spent":"1.019840056s","remote":"127.0.0.1:34242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-09T02:06:49.859502Z","caller":"traceutil/trace.go:171","msg":"trace[1915255873] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"1.082019469s","start":"2026-04-09T02:06:48.777471Z","end":"2026-04-09T02:06:49.85949Z","steps":["trace[1915255873] 'agreement among raft nodes before linearized reading' (duration: 1.081870025s)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:49.859499Z","caller":"traceutil/trace.go:171","msg":"trace[1223536745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"1.082311047s","start":"2026-04-09T02:06:48.777165Z","end":"2026-04-09T02:06:49.859476Z","steps":["trace[1223536745] 'agreement among raft nodes before linearized reading' (duration: 1.082101971s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:49.859547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:48.777143Z","time spent":"1.082396079s","remote":"127.0.0.1:34564","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":"warn","ts":"2026-04-09T02:06:49.859546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:48.777443Z","time spent":"1.082092762s","remote":"127.0.0.1:34564","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-09T02:06:49.85961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075178664s","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-09T02:06:49.859679Z","caller":"traceutil/trace.go:171","msg":"trace[1456220343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"1.075275527s","start":"2026-04-09T02:06:48.784386Z","end":"2026-04-09T02:06:49.859662Z","steps":["trace[1456220343] 'agreement among raft nodes before linearized reading' (duration: 1.075039931s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:49.859722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:48.784375Z","time spent":"1.075340078s","remote":"127.0.0.1:34564","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-09T02:06:50.527922Z","caller":"traceutil/trace.go:171","msg":"trace[765490771] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"145.274792ms","start":"2026-04-09T02:06:50.382617Z","end":"2026-04-09T02:06:50.527892Z","steps":["trace[765490771] 'process raft request' (duration: 144.951624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:50.527996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.758981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:50.528108Z","caller":"traceutil/trace.go:171","msg":"trace[1496386650] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:592; }","duration":"112.907974ms","start":"2026-04-09T02:06:50.415183Z","end":"2026-04-09T02:06:50.528091Z","steps":["trace[1496386650] 'agreement among raft nodes before linearized reading' (duration: 112.725888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:50.528414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.92257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:50.528536Z","caller":"traceutil/trace.go:171","msg":"trace[348026872] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:592; }","duration":"114.105494ms","start":"2026-04-09T02:06:50.414397Z","end":"2026-04-09T02:06:50.528502Z","steps":["trace[348026872] 'agreement among raft nodes before linearized reading' (duration: 113.876428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:50.528607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.084554ms","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-09T02:06:50.528663Z","caller":"traceutil/trace.go:171","msg":"trace[868405480] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:592; }","duration":"114.225458ms","start":"2026-04-09T02:06:50.414424Z","end":"2026-04-09T02:06:50.528649Z","steps":["trace[868405480] 'agreement among raft nodes before linearized reading' (duration: 113.402126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:51.0516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.228876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:06:51.05173Z","caller":"traceutil/trace.go:171","msg":"trace[1021673188] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"234.851529ms","start":"2026-04-09T02:06:50.816859Z","end":"2026-04-09T02:06:51.05171Z","steps":["trace[1021673188] 'read index received' (duration: 42.181µs)","trace[1021673188] 'applied index is now lower than readState.Index' (duration: 234.808118ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:51.051798Z","caller":"traceutil/trace.go:171","msg":"trace[820862242] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"298.10924ms","start":"2026-04-09T02:06:50.753643Z","end":"2026-04-09T02:06:51.051752Z","steps":["trace[820862242] 'process raft request' (duration: 57.641267ms)","trace[820862242] 'compare' (duration: 240.073652ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:51.051874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.000192ms","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-09T02:06:51.051906Z","caller":"traceutil/trace.go:171","msg":"trace[1745561509] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:594; }","duration":"235.074564ms","start":"2026-04-09T02:06:50.816821Z","end":"2026-04-09T02:06:51.051896Z","steps":["trace[1745561509] 'agreement among raft nodes before linearized reading' (duration: 234.987512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:51.051964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.163572ms","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-09T02:06:51.052055Z","caller":"traceutil/trace.go:171","msg":"trace[1766743822] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:594; }","duration":"110.301436ms","start":"2026-04-09T02:06:50.941734Z","end":"2026-04-09T02:06:51.052035Z","steps":["trace[1766743822] 'agreement among raft nodes before linearized reading' (duration: 110.131522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:51.052073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.537106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:51.052264Z","caller":"traceutil/trace.go:171","msg":"trace[1703115519] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:594; }","duration":"109.728871ms","start":"2026-04-09T02:06:50.942445Z","end":"2026-04-09T02:06:51.052173Z","steps":["trace[1703115519] 'agreement among raft nodes before linearized reading' (duration: 109.439764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:51.052336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.443559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:51.052406Z","caller":"traceutil/trace.go:171","msg":"trace[795748476] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:594; }","duration":"110.589514ms","start":"2026-04-09T02:06:50.941799Z","end":"2026-04-09T02:06:51.052388Z","steps":["trace[795748476] 'agreement among raft nodes before linearized reading' (duration: 110.45619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:51.052443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.568487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:51.052538Z","caller":"traceutil/trace.go:171","msg":"trace[1342623850] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:594; }","duration":"235.703211ms","start":"2026-04-09T02:06:50.816818Z","end":"2026-04-09T02:06:51.052522Z","steps":["trace[1342623850] 'agreement among raft nodes before linearized reading' (duration: 234.960192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:52.103118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.768976ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:06:52.103262Z","caller":"traceutil/trace.go:171","msg":"trace[406956359] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"265.055453ms","start":"2026-04-09T02:06:51.838188Z","end":"2026-04-09T02:06:52.103244Z","steps":["trace[406956359] 'read index received' (duration: 56.682µs)","trace[406956359] 'applied index is now lower than readState.Index' (duration: 264.997271ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:52.103372Z","caller":"traceutil/trace.go:171","msg":"trace[1750101717] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"392.502709ms","start":"2026-04-09T02:06:51.710829Z","end":"2026-04-09T02:06:52.103332Z","steps":["trace[1750101717] 'process raft request' (duration: 70.423835ms)","trace[1750101717] 'compare' (duration: 321.51349ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:52.103703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:51.710798Z","time spent":"392.676193ms","remote":"127.0.0.1:34344","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-09T02:06:52.103734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.597153ms","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-09T02:06:52.103798Z","caller":"traceutil/trace.go:171","msg":"trace[759669939] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:598; }","duration":"250.698434ms","start":"2026-04-09T02:06:51.853083Z","end":"2026-04-09T02:06:52.103781Z","steps":["trace[759669939] 'agreement among raft nodes before linearized reading' (duration: 250.50881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:52.103926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.928687ms","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-09T02:06:52.104072Z","caller":"traceutil/trace.go:171","msg":"trace[652208925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:598; }","duration":"236.112771ms","start":"2026-04-09T02:06:51.867931Z","end":"2026-04-09T02:06:52.104044Z","steps":["trace[652208925] 'agreement among raft nodes before linearized reading' (duration: 235.677929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:52.104065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.763887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-09T02:06:52.104114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.905926ms","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-09T02:06:52.104168Z","caller":"traceutil/trace.go:171","msg":"trace[1147757856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:598; }","duration":"234.912651ms","start":"2026-04-09T02:06:51.86923Z","end":"2026-04-09T02:06:52.104143Z","steps":["trace[1147757856] 'agreement among raft nodes before linearized reading' (duration: 234.368317ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:52.104182Z","caller":"traceutil/trace.go:171","msg":"trace[407088968] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:598; }","duration":"236.017399ms","start":"2026-04-09T02:06:51.868149Z","end":"2026-04-09T02:06:52.104166Z","steps":["trace[407088968] 'agreement among raft nodes before linearized reading' (duration: 235.446664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:52.104199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.011997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:52.1043Z","caller":"traceutil/trace.go:171","msg":"trace[831696743] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"266.14245ms","start":"2026-04-09T02:06:51.838138Z","end":"2026-04-09T02:06:52.10428Z","steps":["trace[831696743] 'agreement among raft nodes before linearized reading' (duration: 265.201307ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:52.236206Z","caller":"traceutil/trace.go:171","msg":"trace[371543725] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"126.867902ms","start":"2026-04-09T02:06:52.10931Z","end":"2026-04-09T02:06:52.236178Z","steps":["trace[371543725] 'process raft request' (duration: 126.665126ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:52.236397Z","caller":"traceutil/trace.go:171","msg":"trace[272232924] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"126.969964ms","start":"2026-04-09T02:06:52.109412Z","end":"2026-04-09T02:06:52.236382Z","steps":["trace[272232924] 'process raft request' (duration: 126.716057ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:52.236537Z","caller":"traceutil/trace.go:171","msg":"trace[709563478] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"126.997054ms","start":"2026-04-09T02:06:52.1095Z","end":"2026-04-09T02:06:52.236497Z","steps":["trace[709563478] 'process raft request' (duration: 126.81676ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:07.632823Z","caller":"traceutil/trace.go:171","msg":"trace[1515072466] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"145.851569ms","start":"2026-04-09T02:07:07.486937Z","end":"2026-04-09T02:07:07.632789Z","steps":["trace[1515072466] 'process raft request' (duration: 145.669743ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:40.02417Z","caller":"traceutil/trace.go:171","msg":"trace[754602014] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"158.434785ms","start":"2026-04-09T02:07:39.865711Z","end":"2026-04-09T02:07:40.024146Z","steps":["trace[754602014] 'process raft request' (duration: 158.305962ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:40.024453Z","caller":"traceutil/trace.go:171","msg":"trace[837686995] linearizableReadLoop","detail":"{readStateIndex:1199; appliedIndex:1199; }","duration":"144.620261ms","start":"2026-04-09T02:07:39.879819Z","end":"2026-04-09T02:07:40.02444Z","steps":["trace[837686995] 'read index received' (duration: 144.131089ms)","trace[837686995] 'applied index is now lower than readState.Index' (duration: 487.072µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:07:40.024648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.803066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-startupapicheck\" ","response":"range_response_count:1 size:1054"} {"level":"info","ts":"2026-04-09T02:07:40.024683Z","caller":"traceutil/trace.go:171","msg":"trace[1988029063] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-startupapicheck; range_end:; response_count:1; response_revision:1167; }","duration":"144.899029ms","start":"2026-04-09T02:07:39.879775Z","end":"2026-04-09T02:07:40.024674Z","steps":["trace[1988029063] 'agreement among raft nodes before linearized reading' (duration: 144.734274ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:55.377164Z","caller":"traceutil/trace.go:171","msg":"trace[991124549] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"153.661463ms","start":"2026-04-09T02:07:55.223474Z","end":"2026-04-09T02:07:55.377136Z","steps":["trace[991124549] 'process raft request' (duration: 153.492459ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:57.098324Z","caller":"traceutil/trace.go:171","msg":"trace[512387367] linearizableReadLoop","detail":"{readStateIndex:1442; appliedIndex:1441; }","duration":"258.837784ms","start":"2026-04-09T02:07:56.839454Z","end":"2026-04-09T02:07:57.098292Z","steps":["trace[512387367] 'read index received' (duration: 258.615449ms)","trace[512387367] 'applied index is now lower than readState.Index' (duration: 220.685µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:07:57.09842Z","caller":"traceutil/trace.go:171","msg":"trace[315481058] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"316.190921ms","start":"2026-04-09T02:07:56.782177Z","end":"2026-04-09T02:07:57.098368Z","steps":["trace[315481058] 'process raft request' (duration: 315.911334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:57.098571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.084692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T02:07:57.098633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:56.78214Z","time spent":"316.383467ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:07:57.098659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.591547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-09T02:07:57.098638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.207011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T02:07:57.098693Z","caller":"traceutil/trace.go:171","msg":"trace[1610326630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1401; }","duration":"259.279376ms","start":"2026-04-09T02:07:56.839383Z","end":"2026-04-09T02:07:57.098662Z","steps":["trace[1610326630] 'agreement among raft nodes before linearized reading' (duration: 259.049849ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:57.098707Z","caller":"traceutil/trace.go:171","msg":"trace[2069506025] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1401; }","duration":"240.69551ms","start":"2026-04-09T02:07:56.857997Z","end":"2026-04-09T02:07:57.098693Z","steps":["trace[2069506025] 'agreement among raft nodes before linearized reading' (duration: 240.490004ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:57.098762Z","caller":"traceutil/trace.go:171","msg":"trace[110985256] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1401; }","duration":"153.407286ms","start":"2026-04-09T02:07:56.945324Z","end":"2026-04-09T02:07:57.098731Z","steps":["trace[110985256] 'agreement among raft nodes before linearized reading' (duration: 153.205141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:22.099655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.79438ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:08:22.099992Z","caller":"traceutil/trace.go:171","msg":"trace[1675718585] linearizableReadLoop","detail":"{readStateIndex:1874; appliedIndex:1871; }","duration":"797.764485ms","start":"2026-04-09T02:08:21.3022Z","end":"2026-04-09T02:08:22.099964Z","steps":["trace[1675718585] 'read index received' (duration: 379.511072ms)","trace[1675718585] 'applied index is now lower than readState.Index' (duration: 418.251343ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:08:22.09998Z","caller":"traceutil/trace.go:171","msg":"trace[1390092086] transaction","detail":"{read_only:false; response_revision:1805; number_of_response:1; }","duration":"1.023115952s","start":"2026-04-09T02:08:21.076826Z","end":"2026-04-09T02:08:22.099942Z","steps":["trace[1390092086] 'process raft request' (duration: 604.8974ms)","trace[1390092086] 'compare' (duration: 417.632707ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:08:22.100202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:08:21.076799Z","time spent":"1.023273456s","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:08:22.100191Z","caller":"traceutil/trace.go:171","msg":"trace[1625444339] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"864.877102ms","start":"2026-04-09T02:08:21.23524Z","end":"2026-04-09T02:08:22.100117Z","steps":["trace[1625444339] 'process raft request' (duration: 864.608556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:22.100372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.799581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-09T02:08:22.100423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:08:21.235189Z","time spent":"865.103998ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:08:22.100458Z","caller":"traceutil/trace.go:171","msg":"trace[391977006] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1807; }","duration":"472.931193ms","start":"2026-04-09T02:08:21.627503Z","end":"2026-04-09T02:08:22.100435Z","steps":["trace[391977006] 'agreement among raft nodes before linearized reading' (duration: 472.700426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:22.100502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:08:21.627478Z","time spent":"473.015105ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-09T02:08:22.100502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.55957ms","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":"warn","ts":"2026-04-09T02:08:22.100507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.582088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T02:08:22.100564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.469153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-97bf3ef0-ecec-4b4e-a6c0-fbf3df160c3a\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-04-09T02:08:22.100564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.254956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-09T02:08:22.100576Z","caller":"traceutil/trace.go:171","msg":"trace[1147919685] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1807; }","duration":"187.682663ms","start":"2026-04-09T02:08:21.912874Z","end":"2026-04-09T02:08:22.100557Z","steps":["trace[1147919685] 'agreement among raft nodes before linearized reading' (duration: 187.544909ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:08:22.100585Z","caller":"traceutil/trace.go:171","msg":"trace[178980723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1807; }","duration":"260.715862ms","start":"2026-04-09T02:08:21.83985Z","end":"2026-04-09T02:08:22.100566Z","steps":["trace[178980723] 'agreement among raft nodes before linearized reading' (duration: 260.587709ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:08:22.100611Z","caller":"traceutil/trace.go:171","msg":"trace[1044877845] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-97bf3ef0-ecec-4b4e-a6c0-fbf3df160c3a; range_end:; response_count:1; response_revision:1807; }","duration":"204.573005ms","start":"2026-04-09T02:08:21.896023Z","end":"2026-04-09T02:08:22.100597Z","steps":["trace[1044877845] 'agreement among raft nodes before linearized reading' (duration: 204.340819ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:08:22.100616Z","caller":"traceutil/trace.go:171","msg":"trace[1464959997] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1807; }","duration":"327.355608ms","start":"2026-04-09T02:08:21.773245Z","end":"2026-04-09T02:08:22.100601Z","steps":["trace[1464959997] 'agreement among raft nodes before linearized reading' (duration: 327.181684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:22.100647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:08:21.773204Z","time spent":"327.43355ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-09T02:08:22.100665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.702298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-97bf3ef0-ecec-4b4e-a6c0-fbf3df160c3a\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-09T02:08:22.100752Z","caller":"traceutil/trace.go:171","msg":"trace[1576886340] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-97bf3ef0-ecec-4b4e-a6c0-fbf3df160c3a; range_end:; response_count:1; response_revision:1807; }","duration":"433.816442ms","start":"2026-04-09T02:08:21.666898Z","end":"2026-04-09T02:08:22.100714Z","steps":["trace[1576886340] 'agreement among raft nodes before linearized reading' (duration: 433.549175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:22.10074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.463153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-09T02:08:22.100817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:08:21.666879Z","time spent":"433.920665ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-97bf3ef0-ecec-4b4e-a6c0-fbf3df160c3a\" "} {"level":"info","ts":"2026-04-09T02:08:22.100834Z","caller":"traceutil/trace.go:171","msg":"trace[1506171577] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1807; }","duration":"798.654578ms","start":"2026-04-09T02:08:21.302151Z","end":"2026-04-09T02:08:22.100806Z","steps":["trace[1506171577] 'agreement among raft nodes before linearized reading' (duration: 798.31399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:22.100878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:08:21.302132Z","time spent":"798.7361ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-09T02:08:22.100986Z","caller":"traceutil/trace.go:171","msg":"trace[1266553604] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"800.70018ms","start":"2026-04-09T02:08:21.299478Z","end":"2026-04-09T02:08:22.100178Z","steps":["trace[1266553604] 'process raft request' (duration: 800.435223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:22.101103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:08:21.299451Z","time spent":"801.608114ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:08:22.268677Z","caller":"traceutil/trace.go:171","msg":"trace[1259627710] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"163.587456ms","start":"2026-04-09T02:08:22.10506Z","end":"2026-04-09T02:08:22.268648Z","steps":["trace[1259627710] 'process raft request' (duration: 99.827125ms)","trace[1259627710] 'compare' (duration: 63.599068ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:08:22.331057Z","caller":"traceutil/trace.go:171","msg":"trace[1381887623] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"224.914876ms","start":"2026-04-09T02:08:22.106107Z","end":"2026-04-09T02:08:22.331021Z","steps":["trace[1381887623] 'process raft request' (duration: 224.632629ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:08:22.33131Z","caller":"traceutil/trace.go:171","msg":"trace[1035993660] linearizableReadLoop","detail":"{readStateIndex:1877; appliedIndex:1874; }","duration":"215.056474ms","start":"2026-04-09T02:08:22.116228Z","end":"2026-04-09T02:08:22.331284Z","steps":["trace[1035993660] 'read index received' (duration: 88.642709ms)","trace[1035993660] 'applied index is now lower than readState.Index' (duration: 126.412254ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:08:22.331412Z","caller":"traceutil/trace.go:171","msg":"trace[582213632] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"222.083484ms","start":"2026-04-09T02:08:22.109285Z","end":"2026-04-09T02:08:22.331369Z","steps":["trace[582213632] 'process raft request' (duration: 221.618442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:22.332047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.791864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:08:22.33211Z","caller":"traceutil/trace.go:171","msg":"trace[1403454923] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1810; }","duration":"215.901536ms","start":"2026-04-09T02:08:22.116193Z","end":"2026-04-09T02:08:22.332095Z","steps":["trace[1403454923] 'agreement among raft nodes before linearized reading' (duration: 215.178557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:23.018237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.730694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:08:23.018346Z","caller":"traceutil/trace.go:171","msg":"trace[1503378052] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1810; }","duration":"337.895798ms","start":"2026-04-09T02:08:22.680421Z","end":"2026-04-09T02:08:23.018317Z","steps":["trace[1503378052] 'count revisions from in-memory index tree' (duration: 337.5596ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:08:23.018361Z","caller":"traceutil/trace.go:171","msg":"trace[468252391] linearizableReadLoop","detail":"{readStateIndex:1878; appliedIndex:1877; }","duration":"236.624705ms","start":"2026-04-09T02:08:22.781715Z","end":"2026-04-09T02:08:23.018339Z","steps":["trace[468252391] 'read index received' (duration: 220.311658ms)","trace[468252391] 'applied index is now lower than readState.Index' (duration: 16.311657ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:08:23.018439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:08:22.680399Z","time spent":"338.02201ms","remote":"127.0.0.1:56324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-09T02:08:23.018506Z","caller":"traceutil/trace.go:171","msg":"trace[627557459] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"236.873881ms","start":"2026-04-09T02:08:22.781587Z","end":"2026-04-09T02:08:23.018461Z","steps":["trace[627557459] 'process raft request' (duration: 220.542824ms)","trace[627557459] 'compare' (duration: 15.720291ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:08:23.018675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.731418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-09T02:08:23.018702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.921003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-09T02:08:23.018648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.880942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:08:23.018745Z","caller":"traceutil/trace.go:171","msg":"trace[940972711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1811; }","duration":"168.990255ms","start":"2026-04-09T02:08:22.849737Z","end":"2026-04-09T02:08:23.018727Z","steps":["trace[940972711] 'agreement among raft nodes before linearized reading' (duration: 168.80661ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:08:23.018798Z","caller":"traceutil/trace.go:171","msg":"trace[678339208] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1811; }","duration":"237.064357ms","start":"2026-04-09T02:08:22.781701Z","end":"2026-04-09T02:08:23.018766Z","steps":["trace[678339208] 'agreement among raft nodes before linearized reading' (duration: 236.826821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:23.01884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.832113ms","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-09T02:08:23.018893Z","caller":"traceutil/trace.go:171","msg":"trace[1635973068] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1811; }","duration":"188.917865ms","start":"2026-04-09T02:08:22.829963Z","end":"2026-04-09T02:08:23.018881Z","steps":["trace[1635973068] 'agreement among raft nodes before linearized reading' (duration: 188.636228ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:08:23.018736Z","caller":"traceutil/trace.go:171","msg":"trace[2026228881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1811; }","duration":"168.81469ms","start":"2026-04-09T02:08:22.849907Z","end":"2026-04-09T02:08:23.018721Z","steps":["trace[2026228881] 'agreement among raft nodes before linearized reading' (duration: 168.597185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:23.01904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.724963ms","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-09T02:08:23.019301Z","caller":"traceutil/trace.go:171","msg":"trace[173154208] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1811; }","duration":"236.01145ms","start":"2026-04-09T02:08:22.783269Z","end":"2026-04-09T02:08:23.019281Z","steps":["trace[173154208] 'agreement among raft nodes before linearized reading' (duration: 235.23819ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:08:23.147107Z","caller":"traceutil/trace.go:171","msg":"trace[836373861] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"123.765857ms","start":"2026-04-09T02:08:23.023315Z","end":"2026-04-09T02:08:23.147081Z","steps":["trace[836373861] 'process raft request' (duration: 115.741231ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:08:23.147533Z","caller":"traceutil/trace.go:171","msg":"trace[408408770] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"124.091075ms","start":"2026-04-09T02:08:23.023409Z","end":"2026-04-09T02:08:23.1475Z","steps":["trace[408408770] 'process raft request' (duration: 123.629534ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:08:23.147584Z","caller":"traceutil/trace.go:171","msg":"trace[1031830175] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"123.704494ms","start":"2026-04-09T02:08:23.023852Z","end":"2026-04-09T02:08:23.147556Z","steps":["trace[1031830175] 'process raft request' (duration: 123.500319ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:08:23.147855Z","caller":"traceutil/trace.go:171","msg":"trace[381432020] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"123.779357ms","start":"2026-04-09T02:08:23.024028Z","end":"2026-04-09T02:08:23.147807Z","steps":["trace[381432020] 'process raft request' (duration: 123.423528ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:08:36.891666Z","caller":"traceutil/trace.go:171","msg":"trace[435879657] linearizableReadLoop","detail":"{readStateIndex:2001; appliedIndex:2000; }","duration":"491.055215ms","start":"2026-04-09T02:08:36.400586Z","end":"2026-04-09T02:08:36.891641Z","steps":["trace[435879657] 'read index received' (duration: 490.736988ms)","trace[435879657] 'applied index is now lower than readState.Index' (duration: 317.407µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:08:36.891906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.301123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-09T02:08:36.891948Z","caller":"traceutil/trace.go:171","msg":"trace[1566243650] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1930; }","duration":"491.408156ms","start":"2026-04-09T02:08:36.400529Z","end":"2026-04-09T02:08:36.891937Z","steps":["trace[1566243650] 'agreement among raft nodes before linearized reading' (duration: 491.247161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:36.89199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:08:36.400506Z","time spent":"491.474687ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-09T02:08:36.891881Z","caller":"traceutil/trace.go:171","msg":"trace[1276453794] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"496.736701ms","start":"2026-04-09T02:08:36.394968Z","end":"2026-04-09T02:08:36.891704Z","steps":["trace[1276453794] 'process raft request' (duration: 496.417083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:36.892262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.992512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-7f8596f788-d8pjr\" ","response":"range_response_count:1 size:6114"} {"level":"info","ts":"2026-04-09T02:08:36.892348Z","caller":"traceutil/trace.go:171","msg":"trace[1097232626] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-messaging-topology-operator-7f8596f788-d8pjr; range_end:; response_count:1; response_revision:1930; }","duration":"118.132596ms","start":"2026-04-09T02:08:36.774195Z","end":"2026-04-09T02:08:36.892328Z","steps":["trace[1097232626] 'agreement among raft nodes before linearized reading' (duration: 117.89067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:36.892699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:08:36.394946Z","time spent":"497.198633ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:08:37.033781Z","caller":"traceutil/trace.go:171","msg":"trace[1834963177] transaction","detail":"{read_only:false; response_revision:1931; number_of_response:1; }","duration":"136.65373ms","start":"2026-04-09T02:08:36.897085Z","end":"2026-04-09T02:08:37.033738Z","steps":["trace[1834963177] 'process raft request' (duration: 104.576468ms)","trace[1834963177] 'compare' (duration: 31.722293ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:08:37.03396Z","caller":"traceutil/trace.go:171","msg":"trace[1019803112] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"130.996465ms","start":"2026-04-09T02:08:36.902921Z","end":"2026-04-09T02:08:37.033918Z","steps":["trace[1019803112] 'process raft request' (duration: 130.718328ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:09:07.266789Z","caller":"traceutil/trace.go:171","msg":"trace[1128986027] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"159.029883ms","start":"2026-04-09T02:09:07.107726Z","end":"2026-04-09T02:09:07.266756Z","steps":["trace[1128986027] 'process raft request' (duration: 158.679545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:09:08.065925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.688754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:09:08.066043Z","caller":"traceutil/trace.go:171","msg":"trace[1323734591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2111; }","duration":"226.856489ms","start":"2026-04-09T02:09:07.83916Z","end":"2026-04-09T02:09:08.066017Z","steps":["trace[1323734591] 'range keys from in-memory index tree' (duration: 226.526541ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:09:08.594001Z","caller":"traceutil/trace.go:171","msg":"trace[716370067] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"306.001326ms","start":"2026-04-09T02:09:08.287953Z","end":"2026-04-09T02:09:08.593954Z","steps":["trace[716370067] 'process raft request' (duration: 305.819301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:09:08.594178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:09:08.287902Z","time spent":"306.17913ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:09:09.348259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.603817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:09:09.348696Z","caller":"traceutil/trace.go:171","msg":"trace[234578404] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"1.054355332s","start":"2026-04-09T02:09:08.294271Z","end":"2026-04-09T02:09:09.348626Z","steps":["trace[234578404] 'process raft request' (duration: 1.054075915s)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:09:09.349109Z","caller":"traceutil/trace.go:171","msg":"trace[122736498] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"1.057676557s","start":"2026-04-09T02:09:08.291002Z","end":"2026-04-09T02:09:09.348678Z","steps":["trace[122736498] 'process raft request' (duration: 703.569397ms)","trace[122736498] 'compare' (duration: 353.328611ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:09:09.349278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:09:08.294251Z","time spent":"1.054900406s","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:09:09.349087Z","caller":"traceutil/trace.go:171","msg":"trace[1471899864] linearizableReadLoop","detail":"{readStateIndex:2192; appliedIndex:2189; }","duration":"787.753966ms","start":"2026-04-09T02:09:08.561249Z","end":"2026-04-09T02:09:09.349003Z","steps":["trace[1471899864] 'read index received' (duration: 32.576945ms)","trace[1471899864] 'applied index is now lower than readState.Index' (duration: 755.159161ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:09:09.349462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:09:08.290972Z","time spent":"1.058247303s","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:09:09.402645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.243285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:09:09.402738Z","caller":"traceutil/trace.go:171","msg":"trace[1079082496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2115; }","duration":"563.412739ms","start":"2026-04-09T02:09:08.839303Z","end":"2026-04-09T02:09:09.402715Z","steps":["trace[1079082496] 'agreement among raft nodes before linearized reading' (duration: 563.250985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:09:09.402792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:09:08.839273Z","time spent":"563.510332ms","remote":"127.0.0.1:34258","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-09T02:09:09.402833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.649403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-09T02:09:09.402816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.566324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-04-09T02:09:09.402875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.563939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-09T02:09:09.402909Z","caller":"traceutil/trace.go:171","msg":"trace[1666916752] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2115; }","duration":"441.761944ms","start":"2026-04-09T02:09:08.961125Z","end":"2026-04-09T02:09:09.402887Z","steps":["trace[1666916752] 'agreement among raft nodes before linearized reading' (duration: 441.596389ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:09:09.40297Z","caller":"traceutil/trace.go:171","msg":"trace[1653763713] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2115; }","duration":"131.767665ms","start":"2026-04-09T02:09:09.271182Z","end":"2026-04-09T02:09:09.402949Z","steps":["trace[1653763713] 'agreement among raft nodes before linearized reading' (duration: 131.471367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:09:09.402981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:09:08.961106Z","time spent":"441.860667ms","remote":"127.0.0.1:34656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":67,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-04-09T02:09:09.402921Z","caller":"traceutil/trace.go:171","msg":"trace[1877423660] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2115; }","duration":"841.726589ms","start":"2026-04-09T02:09:08.561177Z","end":"2026-04-09T02:09:09.402904Z","steps":["trace[1877423660] 'agreement among raft nodes before linearized reading' (duration: 787.618572ms)","trace[1877423660] 'range keys from in-memory index tree' (duration: 53.864099ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:09:09.403085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:09:08.561146Z","time spent":"841.914084ms","remote":"127.0.0.1:45978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-09T02:10:00.929905Z","caller":"traceutil/trace.go:171","msg":"trace[1777821546] transaction","detail":"{read_only:false; response_revision:2569; number_of_response:1; }","duration":"220.04515ms","start":"2026-04-09T02:10:00.709824Z","end":"2026-04-09T02:10:00.929869Z","steps":["trace[1777821546] 'process raft request' (duration: 219.829735ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:10:00.998299Z","caller":"traceutil/trace.go:171","msg":"trace[1991024313] transaction","detail":"{read_only:false; response_revision:2570; number_of_response:1; }","duration":"118.280647ms","start":"2026-04-09T02:10:00.879984Z","end":"2026-04-09T02:10:00.998264Z","steps":["trace[1991024313] 'process raft request' (duration: 117.370894ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:10:01.92662Z","caller":"traceutil/trace.go:171","msg":"trace[37533895] transaction","detail":"{read_only:false; response_revision:2571; number_of_response:1; }","duration":"396.006263ms","start":"2026-04-09T02:10:01.530586Z","end":"2026-04-09T02:10:01.926592Z","steps":["trace[37533895] 'process raft request' (duration: 395.797458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:10:01.926843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:10:01.530562Z","time spent":"396.157126ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:10:01.927287Z","caller":"traceutil/trace.go:171","msg":"trace[1543134934] linearizableReadLoop","detail":"{readStateIndex:2669; appliedIndex:2667; }","duration":"220.319607ms","start":"2026-04-09T02:10:01.706933Z","end":"2026-04-09T02:10:01.927252Z","steps":["trace[1543134934] 'read index received' (duration: 219.492856ms)","trace[1543134934] 'applied index is now lower than readState.Index' (duration: 825.301µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:10:01.927347Z","caller":"traceutil/trace.go:171","msg":"trace[1655115006] transaction","detail":"{read_only:false; response_revision:2572; number_of_response:1; }","duration":"391.930199ms","start":"2026-04-09T02:10:01.535385Z","end":"2026-04-09T02:10:01.927315Z","steps":["trace[1655115006] 'process raft request' (duration: 391.669322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:10:01.927521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:10:01.535354Z","time spent":"392.071531ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:10:01.927625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.659566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-09T02:10:01.927685Z","caller":"traceutil/trace.go:171","msg":"trace[964604399] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2572; }","duration":"220.797419ms","start":"2026-04-09T02:10:01.706872Z","end":"2026-04-09T02:10:01.927669Z","steps":["trace[964604399] 'agreement among raft nodes before linearized reading' (duration: 220.564503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:10:01.927937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.289985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-09T02:10:01.928041Z","caller":"traceutil/trace.go:171","msg":"trace[92582348] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2572; }","duration":"218.414969ms","start":"2026-04-09T02:10:01.709608Z","end":"2026-04-09T02:10:01.928023Z","steps":["trace[92582348] 'agreement among raft nodes before linearized reading' (duration: 217.883455ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:10:35.423681Z","caller":"traceutil/trace.go:171","msg":"trace[1243094028] transaction","detail":"{read_only:false; response_revision:2775; number_of_response:1; }","duration":"299.388091ms","start":"2026-04-09T02:10:35.12426Z","end":"2026-04-09T02:10:35.423648Z","steps":["trace[1243094028] 'process raft request' (duration: 298.706544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:13:21.93499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.199447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-09T02:13:21.935122Z","caller":"traceutil/trace.go:171","msg":"trace[826363666] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3852; }","duration":"325.378182ms","start":"2026-04-09T02:13:21.609712Z","end":"2026-04-09T02:13:21.93509Z","steps":["trace[826363666] 'range keys from in-memory index tree' (duration: 324.846187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:13:21.935153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.063665ms","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-09T02:13:21.935248Z","caller":"traceutil/trace.go:171","msg":"trace[1308634360] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:3852; }","duration":"174.224029ms","start":"2026-04-09T02:13:21.761008Z","end":"2026-04-09T02:13:21.935232Z","steps":["trace[1308634360] 'count revisions from in-memory index tree' (duration: 173.498501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:13:21.935189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:13:21.609689Z","time spent":"325.481764ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-09T02:14:33.707197Z","caller":"traceutil/trace.go:171","msg":"trace[917818864] transaction","detail":"{read_only:false; response_revision:4338; number_of_response:1; }","duration":"100.508976ms","start":"2026-04-09T02:14:33.606645Z","end":"2026-04-09T02:14:33.707154Z","steps":["trace[917818864] 'process raft request' (duration: 100.2913ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:14:45.330172Z","caller":"traceutil/trace.go:171","msg":"trace[659376137] transaction","detail":"{read_only:false; response_revision:4852; number_of_response:1; }","duration":"128.227403ms","start":"2026-04-09T02:14:45.201901Z","end":"2026-04-09T02:14:45.330129Z","steps":["trace[659376137] 'process raft request' (duration: 126.919799ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:14:48.668226Z","caller":"traceutil/trace.go:171","msg":"trace[1837544943] transaction","detail":"{read_only:false; response_revision:5020; number_of_response:1; }","duration":"280.711071ms","start":"2026-04-09T02:14:48.387474Z","end":"2026-04-09T02:14:48.668185Z","steps":["trace[1837544943] 'process raft request' (duration: 280.122916ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:14:48.800077Z","caller":"traceutil/trace.go:171","msg":"trace[2077254556] linearizableReadLoop","detail":"{readStateIndex:5209; appliedIndex:5208; }","duration":"112.465256ms","start":"2026-04-09T02:14:48.687564Z","end":"2026-04-09T02:14:48.800029Z","steps":["trace[2077254556] 'read index received' (duration: 100.747623ms)","trace[2077254556] 'applied index is now lower than readState.Index' (duration: 11.716213ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:14:48.800328Z","caller":"traceutil/trace.go:171","msg":"trace[1075461356] transaction","detail":"{read_only:false; response_revision:5021; number_of_response:1; }","duration":"113.781698ms","start":"2026-04-09T02:14:48.686511Z","end":"2026-04-09T02:14:48.800293Z","steps":["trace[1075461356] 'process raft request' (duration: 101.819989ms)","trace[1075461356] 'compare' (duration: 11.556499ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:14:48.800712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.128573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:719276"} {"level":"info","ts":"2026-04-09T02:14:48.800781Z","caller":"traceutil/trace.go:171","msg":"trace[1914107858] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5021; }","duration":"113.281027ms","start":"2026-04-09T02:14:48.687487Z","end":"2026-04-09T02:14:48.800768Z","steps":["trace[1914107858] 'agreement among raft nodes before linearized reading' (duration: 112.711822ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:15:00.446387Z","caller":"traceutil/trace.go:171","msg":"trace[1716837752] transaction","detail":"{read_only:false; response_revision:5256; number_of_response:1; }","duration":"113.999655ms","start":"2026-04-09T02:15:00.332347Z","end":"2026-04-09T02:15:00.446346Z","steps":["trace[1716837752] 'process raft request' (duration: 74.481981ms)","trace[1716837752] 'compare' (duration: 39.32768ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:15:00.446687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.863503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:131180"} {"level":"info","ts":"2026-04-09T02:15:00.446786Z","caller":"traceutil/trace.go:171","msg":"trace[1065134478] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:5256; }","duration":"113.996796ms","start":"2026-04-09T02:15:00.332775Z","end":"2026-04-09T02:15:00.446772Z","steps":["trace[1065134478] 'agreement among raft nodes before linearized reading' (duration: 113.645426ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:15:00.446378Z","caller":"traceutil/trace.go:171","msg":"trace[587893868] linearizableReadLoop","detail":"{readStateIndex:5453; appliedIndex:5452; }","duration":"113.524964ms","start":"2026-04-09T02:15:00.332789Z","end":"2026-04-09T02:15:00.446314Z","steps":["trace[587893868] 'read index received' (duration: 73.947708ms)","trace[587893868] 'applied index is now lower than readState.Index' (duration: 39.575146ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:15:00.598305Z","caller":"traceutil/trace.go:171","msg":"trace[243478679] linearizableReadLoop","detail":"{readStateIndex:5454; appliedIndex:5453; }","duration":"147.578566ms","start":"2026-04-09T02:15:00.450694Z","end":"2026-04-09T02:15:00.598272Z","steps":["trace[243478679] 'read index received' (duration: 95.890296ms)","trace[243478679] 'applied index is now lower than readState.Index' (duration: 51.68697ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:15:00.598466Z","caller":"traceutil/trace.go:171","msg":"trace[1549169706] transaction","detail":"{read_only:false; response_revision:5257; number_of_response:1; }","duration":"148.88989ms","start":"2026-04-09T02:15:00.449529Z","end":"2026-04-09T02:15:00.598419Z","steps":["trace[1549169706] 'process raft request' (duration: 97.098157ms)","trace[1549169706] 'compare' (duration: 51.455094ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:15:00.598577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.859823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-alertmanager.18a48e8a074181fd\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-04-09T02:15:00.598631Z","caller":"traceutil/trace.go:171","msg":"trace[536568256] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-alertmanager.18a48e8a074181fd; range_end:; response_count:1; response_revision:5257; }","duration":"147.942115ms","start":"2026-04-09T02:15:00.450674Z","end":"2026-04-09T02:15:00.598616Z","steps":["trace[536568256] 'agreement among raft nodes before linearized reading' (duration: 147.71481ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:15:00.977432Z","caller":"traceutil/trace.go:171","msg":"trace[1464392610] transaction","detail":"{read_only:false; response_revision:5263; number_of_response:1; }","duration":"122.475086ms","start":"2026-04-09T02:15:00.854928Z","end":"2026-04-09T02:15:00.977403Z","steps":["trace[1464392610] 'process raft request' (duration: 122.305771ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:15:09.204458Z","caller":"traceutil/trace.go:171","msg":"trace[969375803] linearizableReadLoop","detail":"{readStateIndex:5605; appliedIndex:5603; }","duration":"116.48152ms","start":"2026-04-09T02:15:09.087943Z","end":"2026-04-09T02:15:09.204425Z","steps":["trace[969375803] 'read index received' (duration: 8.617153ms)","trace[969375803] 'applied index is now lower than readState.Index' (duration: 107.862657ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:15:09.204713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.738157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T02:15:09.204837Z","caller":"traceutil/trace.go:171","msg":"trace[946182553] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:5406; }","duration":"116.913401ms","start":"2026-04-09T02:15:09.087898Z","end":"2026-04-09T02:15:09.204812Z","steps":["trace[946182553] 'agreement among raft nodes before linearized reading' (duration: 116.665324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:15:09.205082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.99702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ebff8ea3-90f8-4cfc-9d68-9937cbf0a73e\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-09T02:15:09.205178Z","caller":"traceutil/trace.go:171","msg":"trace[1244548313] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ebff8ea3-90f8-4cfc-9d68-9937cbf0a73e; range_end:; response_count:1; response_revision:5406; }","duration":"113.355379ms","start":"2026-04-09T02:15:09.091803Z","end":"2026-04-09T02:15:09.205158Z","steps":["trace[1244548313] 'agreement among raft nodes before linearized reading' (duration: 112.892207ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:15:09.843794Z","caller":"traceutil/trace.go:171","msg":"trace[1375440321] transaction","detail":"{read_only:false; response_revision:5408; number_of_response:1; }","duration":"497.770777ms","start":"2026-04-09T02:15:09.34599Z","end":"2026-04-09T02:15:09.84376Z","steps":["trace[1375440321] 'process raft request' (duration: 497.559741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:15:09.844079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:15:09.345974Z","time spent":"497.945782ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:15:09.930409Z","caller":"traceutil/trace.go:171","msg":"trace[1906304506] linearizableReadLoop","detail":"{readStateIndex:5608; appliedIndex:5606; }","duration":"450.47982ms","start":"2026-04-09T02:15:09.479896Z","end":"2026-04-09T02:15:09.930376Z","steps":["trace[1906304506] 'read index received' (duration: 363.580937ms)","trace[1906304506] 'applied index is now lower than readState.Index' (duration: 86.897813ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:15:09.930502Z","caller":"traceutil/trace.go:171","msg":"trace[441322041] transaction","detail":"{read_only:false; response_revision:5409; number_of_response:1; }","duration":"470.4794ms","start":"2026-04-09T02:15:09.459969Z","end":"2026-04-09T02:15:09.930448Z","steps":["trace[441322041] 'process raft request' (duration: 470.235684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:15:09.930714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.783938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-09T02:15:09.930822Z","caller":"traceutil/trace.go:171","msg":"trace[1356789785] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5409; }","duration":"450.930272ms","start":"2026-04-09T02:15:09.47987Z","end":"2026-04-09T02:15:09.9308Z","steps":["trace[1356789785] 'agreement among raft nodes before linearized reading' (duration: 450.633364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:15:09.930872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:15:09.479858Z","time spent":"451.004654ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-09T02:15:09.931034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:15:09.459953Z","time spent":"470.704766ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:15:21.685071Z","caller":"traceutil/trace.go:171","msg":"trace[614804405] transaction","detail":"{read_only:false; response_revision:5598; number_of_response:1; }","duration":"126.158361ms","start":"2026-04-09T02:15:21.55888Z","end":"2026-04-09T02:15:21.685038Z","steps":["trace[614804405] 'process raft request' (duration: 49.224636ms)","trace[614804405] 'compare' (duration: 76.72118ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:15:21.685628Z","caller":"traceutil/trace.go:171","msg":"trace[886282810] transaction","detail":"{read_only:false; response_revision:5600; number_of_response:1; }","duration":"126.572712ms","start":"2026-04-09T02:15:21.559041Z","end":"2026-04-09T02:15:21.685614Z","steps":["trace[886282810] 'process raft request' (duration: 126.224923ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:15:21.685652Z","caller":"traceutil/trace.go:171","msg":"trace[1544085590] transaction","detail":"{read_only:false; response_revision:5599; number_of_response:1; }","duration":"126.659384ms","start":"2026-04-09T02:15:21.558954Z","end":"2026-04-09T02:15:21.685613Z","steps":["trace[1544085590] 'process raft request' (duration: 126.039508ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:16:10.153195Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2963} {"level":"info","ts":"2026-04-09T02:16:10.224504Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2963,"took":"69.696133ms","hash":3087205018,"current-db-size-bytes":56627200,"current-db-size":"57 MB","current-db-size-in-use-bytes":33439744,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-09T02:16:10.224638Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3087205018,"revision":2963,"compact-revision":-1} {"level":"info","ts":"2026-04-09T02:20:57.196278Z","caller":"traceutil/trace.go:171","msg":"trace[19854376] transaction","detail":"{read_only:false; response_revision:8166; number_of_response:1; }","duration":"738.715502ms","start":"2026-04-09T02:20:56.457525Z","end":"2026-04-09T02:20:57.196241Z","steps":["trace[19854376] 'process raft request' (duration: 738.334263ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:20:57.196402Z","caller":"traceutil/trace.go:171","msg":"trace[362093873] linearizableReadLoop","detail":"{readStateIndex:8450; appliedIndex:8449; }","duration":"464.551419ms","start":"2026-04-09T02:20:56.7318Z","end":"2026-04-09T02:20:57.196352Z","steps":["trace[362093873] 'read index received' (duration: 464.079676ms)","trace[362093873] 'applied index is now lower than readState.Index' (duration: 464.823µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:20:57.196601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:20:56.45751Z","time spent":"738.863696ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:20:57.196686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.853936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-09T02:20:57.196785Z","caller":"traceutil/trace.go:171","msg":"trace[1959607937] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8166; }","duration":"465.0039ms","start":"2026-04-09T02:20:56.731759Z","end":"2026-04-09T02:20:57.196763Z","steps":["trace[1959607937] 'agreement among raft nodes before linearized reading' (duration: 464.699122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:20:57.196856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:20:56.731743Z","time spent":"465.101453ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-09T02:20:57.198249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.492242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:20:57.198304Z","caller":"traceutil/trace.go:171","msg":"trace[2070364253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8167; }","duration":"359.593065ms","start":"2026-04-09T02:20:56.8387Z","end":"2026-04-09T02:20:57.198293Z","steps":["trace[2070364253] 'agreement among raft nodes before linearized reading' (duration: 359.481912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:20:57.198331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:20:56.838675Z","time spent":"359.650557ms","remote":"127.0.0.1:34258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-09T02:20:57.198338Z","caller":"traceutil/trace.go:171","msg":"trace[120013005] transaction","detail":"{read_only:false; response_revision:8167; number_of_response:1; }","duration":"391.514944ms","start":"2026-04-09T02:20:56.806799Z","end":"2026-04-09T02:20:57.198314Z","steps":["trace[120013005] 'process raft request' (duration: 391.257598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:20:57.198489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:20:56.806771Z","time spent":"391.632878ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:21:08.021183Z","caller":"traceutil/trace.go:171","msg":"trace[207787209] transaction","detail":"{read_only:false; response_revision:8224; number_of_response:1; }","duration":"149.313731ms","start":"2026-04-09T02:21:07.871829Z","end":"2026-04-09T02:21:08.021142Z","steps":["trace[207787209] 'process raft request' (duration: 148.945641ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:08.021233Z","caller":"traceutil/trace.go:171","msg":"trace[1247822625] transaction","detail":"{read_only:false; response_revision:8225; number_of_response:1; }","duration":"149.192247ms","start":"2026-04-09T02:21:07.872002Z","end":"2026-04-09T02:21:08.021195Z","steps":["trace[1247822625] 'process raft request' (duration: 149.010132ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:08.02129Z","caller":"traceutil/trace.go:171","msg":"trace[1836525972] linearizableReadLoop","detail":"{readStateIndex:8514; appliedIndex:8509; }","duration":"146.54507ms","start":"2026-04-09T02:21:07.874715Z","end":"2026-04-09T02:21:08.02126Z","steps":["trace[1836525972] 'read index received' (duration: 80.850974ms)","trace[1836525972] 'applied index is now lower than readState.Index' (duration: 65.692626ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:21:08.021347Z","caller":"traceutil/trace.go:171","msg":"trace[1379026920] transaction","detail":"{read_only:false; response_revision:8226; number_of_response:1; }","duration":"147.515515ms","start":"2026-04-09T02:21:07.873791Z","end":"2026-04-09T02:21:08.021307Z","steps":["trace[1379026920] 'process raft request' (duration: 147.284389ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:08.021408Z","caller":"traceutil/trace.go:171","msg":"trace[1318616270] transaction","detail":"{read_only:false; response_revision:8227; number_of_response:1; }","duration":"147.512485ms","start":"2026-04-09T02:21:07.873863Z","end":"2026-04-09T02:21:08.021375Z","steps":["trace[1318616270] 'process raft request' (duration: 147.263049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:08.021521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.771316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-09T02:21:08.021577Z","caller":"traceutil/trace.go:171","msg":"trace[1261310045] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8227; }","duration":"146.855948ms","start":"2026-04-09T02:21:07.874704Z","end":"2026-04-09T02:21:08.02156Z","steps":["trace[1261310045] 'agreement among raft nodes before linearized reading' (duration: 146.664024ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:10.252244Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6010} {"level":"info","ts":"2026-04-09T02:21:10.348797Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6010,"took":"90.604644ms","hash":2995732065,"current-db-size-bytes":56627200,"current-db-size":"57 MB","current-db-size-in-use-bytes":23072768,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-09T02:21:10.348843Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2995732065,"revision":6010,"compact-revision":2963} {"level":"info","ts":"2026-04-09T02:21:36.257754Z","caller":"traceutil/trace.go:171","msg":"trace[196260182] transaction","detail":"{read_only:false; response_revision:8428; number_of_response:1; }","duration":"155.081218ms","start":"2026-04-09T02:21:36.102656Z","end":"2026-04-09T02:21:36.257737Z","steps":["trace[196260182] 'process raft request' (duration: 154.961075ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:36.647815Z","caller":"traceutil/trace.go:171","msg":"trace[2107409213] transaction","detail":"{read_only:false; response_revision:8429; number_of_response:1; }","duration":"384.505714ms","start":"2026-04-09T02:21:36.263292Z","end":"2026-04-09T02:21:36.647798Z","steps":["trace[2107409213] 'process raft request' (duration: 381.386525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:36.647913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:36.263269Z","time spent":"384.592357ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:21:36.675268Z","caller":"traceutil/trace.go:171","msg":"trace[944862827] linearizableReadLoop","detail":"{readStateIndex:8729; appliedIndex:8724; }","duration":"266.330502ms","start":"2026-04-09T02:21:36.408918Z","end":"2026-04-09T02:21:36.675248Z","steps":["trace[944862827] 'read index received' (duration: 235.786349ms)","trace[944862827] 'applied index is now lower than readState.Index' (duration: 30.543313ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:21:36.675283Z","caller":"traceutil/trace.go:171","msg":"trace[2095632600] transaction","detail":"{read_only:false; response_revision:8430; number_of_response:1; }","duration":"411.905128ms","start":"2026-04-09T02:21:36.26335Z","end":"2026-04-09T02:21:36.675255Z","steps":["trace[2095632600] 'process raft request' (duration: 411.707853ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:36.675301Z","caller":"traceutil/trace.go:171","msg":"trace[1834477560] transaction","detail":"{read_only:false; response_revision:8432; number_of_response:1; }","duration":"410.094002ms","start":"2026-04-09T02:21:36.265177Z","end":"2026-04-09T02:21:36.675271Z","steps":["trace[1834477560] 'process raft request' (duration: 410.002039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:36.67541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.483497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-j45t2\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-09T02:21:36.675436Z","caller":"traceutil/trace.go:171","msg":"trace[2030041672] range","detail":"{range_begin:/registry/pods/openstack/keepalived-j45t2; range_end:; response_count:1; response_revision:8433; }","duration":"266.542139ms","start":"2026-04-09T02:21:36.408885Z","end":"2026-04-09T02:21:36.675427Z","steps":["trace[2030041672] 'agreement among raft nodes before linearized reading' (duration: 266.436016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:36.67543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:36.263335Z","time spent":"412.009001ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:21:36.675466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:36.265155Z","time spent":"410.224415ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:21:36.675463Z","caller":"traceutil/trace.go:171","msg":"trace[246646551] transaction","detail":"{read_only:false; response_revision:8433; number_of_response:1; }","duration":"410.176814ms","start":"2026-04-09T02:21:36.265269Z","end":"2026-04-09T02:21:36.675446Z","steps":["trace[246646551] 'process raft request' (duration: 409.937227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:36.675546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:36.265253Z","time spent":"410.252206ms","remote":"127.0.0.1:34564","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-09T02:21:36.675539Z","caller":"traceutil/trace.go:171","msg":"trace[1122900424] transaction","detail":"{read_only:false; response_revision:8431; number_of_response:1; }","duration":"411.96098ms","start":"2026-04-09T02:21:36.263557Z","end":"2026-04-09T02:21:36.675518Z","steps":["trace[1122900424] 'process raft request' (duration: 411.59229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:36.675657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:36.263543Z","time spent":"412.048892ms","remote":"127.0.0.1:34564","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-09T02:21:37.493289Z","caller":"traceutil/trace.go:171","msg":"trace[41885917] transaction","detail":"{read_only:false; response_revision:8434; number_of_response:1; }","duration":"125.790648ms","start":"2026-04-09T02:21:37.367474Z","end":"2026-04-09T02:21:37.493265Z","steps":["trace[41885917] 'process raft request' (duration: 125.633484ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:37.503046Z","caller":"traceutil/trace.go:171","msg":"trace[1027012604] transaction","detail":"{read_only:false; response_revision:8435; number_of_response:1; }","duration":"100.355806ms","start":"2026-04-09T02:21:37.402668Z","end":"2026-04-09T02:21:37.503024Z","steps":["trace[1027012604] 'process raft request' (duration: 100.221162ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:48.667766Z","caller":"traceutil/trace.go:171","msg":"trace[397479350] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8532; }","duration":"132.252713ms","start":"2026-04-09T02:21:48.53548Z","end":"2026-04-09T02:21:48.667733Z","steps":["trace[397479350] 'process raft request' (duration: 120.375358ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:48.80366Z","caller":"traceutil/trace.go:171","msg":"trace[227105171] linearizableReadLoop","detail":"{readStateIndex:8834; appliedIndex:8832; }","duration":"252.350874ms","start":"2026-04-09T02:21:48.551286Z","end":"2026-04-09T02:21:48.803637Z","steps":["trace[227105171] 'read index received' (duration: 104.646334ms)","trace[227105171] 'applied index is now lower than readState.Index' (duration: 147.70345ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:21:48.803772Z","caller":"traceutil/trace.go:171","msg":"trace[1527363167] transaction","detail":"{read_only:false; response_revision:8533; number_of_response:1; }","duration":"266.632501ms","start":"2026-04-09T02:21:48.537083Z","end":"2026-04-09T02:21:48.803716Z","steps":["trace[1527363167] 'process raft request' (duration: 266.368453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:48.80406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.759224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:414394"} {"level":"info","ts":"2026-04-09T02:21:48.804121Z","caller":"traceutil/trace.go:171","msg":"trace[145802587] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:8533; }","duration":"252.876797ms","start":"2026-04-09T02:21:48.551232Z","end":"2026-04-09T02:21:48.804109Z","steps":["trace[145802587] 'agreement among raft nodes before linearized reading' (duration: 252.5618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:49.906296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.27755ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:21:49.906431Z","caller":"traceutil/trace.go:171","msg":"trace[1703473690] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8542; }","duration":"264.426254ms","start":"2026-04-09T02:21:49.641988Z","end":"2026-04-09T02:21:49.906415Z","steps":["trace[1703473690] 'range keys from in-memory index tree' (duration: 264.26239ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:49.906706Z","caller":"traceutil/trace.go:171","msg":"trace[409101306] transaction","detail":"{read_only:false; response_revision:8544; number_of_response:1; }","duration":"365.61946ms","start":"2026-04-09T02:21:49.541057Z","end":"2026-04-09T02:21:49.906677Z","steps":["trace[409101306] 'process raft request' (duration: 365.374054ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:49.906761Z","caller":"traceutil/trace.go:171","msg":"trace[2011556187] transaction","detail":"{read_only:false; response_revision:8545; number_of_response:1; }","duration":"280.6315ms","start":"2026-04-09T02:21:49.626114Z","end":"2026-04-09T02:21:49.906745Z","steps":["trace[2011556187] 'process raft request' (duration: 280.366334ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:49.906764Z","caller":"traceutil/trace.go:171","msg":"trace[1780320510] linearizableReadLoop","detail":"{readStateIndex:8846; appliedIndex:8844; }","duration":"297.859183ms","start":"2026-04-09T02:21:49.608655Z","end":"2026-04-09T02:21:49.906514Z","steps":["trace[1780320510] 'read index received' (duration: 246.193887ms)","trace[1780320510] 'applied index is now lower than readState.Index' (duration: 51.664266ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:21:49.906871Z","caller":"traceutil/trace.go:171","msg":"trace[1261645020] transaction","detail":"{read_only:false; response_revision:8543; number_of_response:1; }","duration":"370.514496ms","start":"2026-04-09T02:21:49.536298Z","end":"2026-04-09T02:21:49.906813Z","steps":["trace[1261645020] 'process raft request' (duration: 318.534353ms)","trace[1261645020] 'compare' (duration: 51.46484ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:21:49.906896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:49.541025Z","time spent":"365.766724ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:21:49.906959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.720632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T02:21:49.907Z","caller":"traceutil/trace.go:171","msg":"trace[958937172] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:8545; }","duration":"112.800893ms","start":"2026-04-09T02:21:49.794186Z","end":"2026-04-09T02:21:49.906987Z","steps":["trace[958937172] 'agreement among raft nodes before linearized reading' (duration: 112.710791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:49.907018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.049075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-04-09T02:21:49.907051Z","caller":"traceutil/trace.go:171","msg":"trace[140689596] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8545; }","duration":"296.138488ms","start":"2026-04-09T02:21:49.610904Z","end":"2026-04-09T02:21:49.907042Z","steps":["trace[140689596] 'agreement among raft nodes before linearized reading' (duration: 295.994474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:21:49.907054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:21:49.536276Z","time spent":"370.68428ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:21:49.907052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.377635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19384"} {"level":"warn","ts":"2026-04-09T02:21:49.907093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.098064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:21:49.907159Z","caller":"traceutil/trace.go:171","msg":"trace[110951426] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8545; }","duration":"298.5294ms","start":"2026-04-09T02:21:49.608604Z","end":"2026-04-09T02:21:49.907133Z","steps":["trace[110951426] 'agreement among raft nodes before linearized reading' (duration: 298.244512ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:49.907204Z","caller":"traceutil/trace.go:171","msg":"trace[1947711815] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:8545; }","duration":"202.323321ms","start":"2026-04-09T02:21:49.704852Z","end":"2026-04-09T02:21:49.907176Z","steps":["trace[1947711815] 'agreement among raft nodes before linearized reading' (duration: 202.065534ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:21:50.109045Z","caller":"traceutil/trace.go:171","msg":"trace[513908305] transaction","detail":"{read_only:false; response_revision:8546; number_of_response:1; }","duration":"188.760963ms","start":"2026-04-09T02:21:49.920264Z","end":"2026-04-09T02:21:50.109025Z","steps":["trace[513908305] 'process raft request' (duration: 92.71848ms)","trace[513908305] 'compare' (duration: 95.680613ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:22:03.021138Z","caller":"traceutil/trace.go:171","msg":"trace[2125180739] linearizableReadLoop","detail":"{readStateIndex:8961; appliedIndex:8960; }","duration":"116.830198ms","start":"2026-04-09T02:22:02.904271Z","end":"2026-04-09T02:22:03.021101Z","steps":["trace[2125180739] 'read index received' (duration: 116.550191ms)","trace[2125180739] 'applied index is now lower than readState.Index' (duration: 278.767µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:22:03.021512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.213017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T02:22:03.021521Z","caller":"traceutil/trace.go:171","msg":"trace[1076838987] transaction","detail":"{read_only:false; response_revision:8656; number_of_response:1; }","duration":"127.431079ms","start":"2026-04-09T02:22:02.894038Z","end":"2026-04-09T02:22:03.021469Z","steps":["trace[1076838987] 'process raft request' (duration: 126.878775ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:22:03.021601Z","caller":"traceutil/trace.go:171","msg":"trace[191083069] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:8656; }","duration":"117.32442ms","start":"2026-04-09T02:22:02.904257Z","end":"2026-04-09T02:22:03.021582Z","steps":["trace[191083069] 'agreement among raft nodes before linearized reading' (duration: 117.124435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:22:03.021751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.674303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T02:22:03.021849Z","caller":"traceutil/trace.go:171","msg":"trace[1218369003] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:8656; }","duration":"109.843228ms","start":"2026-04-09T02:22:02.91198Z","end":"2026-04-09T02:22:03.021823Z","steps":["trace[1218369003] 'agreement among raft nodes before linearized reading' (duration: 109.565921ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:22:40.322706Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024b1.wal"} {"level":"info","ts":"2026-04-09T02:23:49.544005Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1686e9b1ea717edf","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-09T02:23:49.557347Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-09T02:23:49.557464Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-09T02:25:16.859768Z","caller":"traceutil/trace.go:171","msg":"trace[128931941] transaction","detail":"{read_only:false; response_revision:10340; number_of_response:1; }","duration":"133.063064ms","start":"2026-04-09T02:25:16.726673Z","end":"2026-04-09T02:25:16.859736Z","steps":["trace[128931941] 'process raft request' (duration: 65.382538ms)","trace[128931941] 'compare' (duration: 67.552093ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:26:10.260675Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8240} {"level":"info","ts":"2026-04-09T02:26:10.339406Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8240,"took":"76.581096ms","hash":4003753355,"current-db-size-bytes":56627200,"current-db-size":"57 MB","current-db-size-in-use-bytes":25174016,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-09T02:26:10.339481Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4003753355,"revision":8240,"compact-revision":6010} {"level":"info","ts":"2026-04-09T02:31:10.266677Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10668} {"level":"info","ts":"2026-04-09T02:31:10.347707Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10668,"took":"78.645675ms","hash":1082923444,"current-db-size-bytes":56627200,"current-db-size":"57 MB","current-db-size-in-use-bytes":20742144,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-09T02:31:10.347764Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1082923444,"revision":10668,"compact-revision":8240}