{"level":"warn","ts":"2026-05-11T21:05:47.222277Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T21:05:47.222364Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162: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.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-11T21:05:47.222426Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T21:05:47.222436Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-05-11T21:05:47.222455Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-11T21:05:47.223072Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-05-11T21:05:47.223148Z","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.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-11T21:05:47.227048Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.694334ms"} {"level":"info","ts":"2026-05-11T21:05:47.237473Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-05-11T21:05:47.237595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T21:05:47.237634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-05-11T21:05:47.237651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T21:05:47.237663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-05-11T21:05:47.237715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-05-11T21:05:47.243422Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T21:05:47.245009Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T21:05:47.246161Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-11T21:05:47.249954Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T21:05:47.250158Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T21:05:47.250995Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T21:05:47.251118Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T21:05:47.251139Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T21:05:47.25136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-05-11T21:05:47.252216Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-05-11T21:05:47.2552Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-11T21:05:47.255356Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-05-11T21:05:47.2554Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-05-11T21:05:47.255533Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T21:05:47.255596Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T21:05:47.637997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T21:05:47.638096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T21:05:47.638124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-05-11T21:05:47.638148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-05-11T21:05:47.638159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-05-11T21:05:47.638175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-05-11T21:05:47.638188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-05-11T21:05:47.639206Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T21:05:47.639218Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T21:05:47.639263Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T21:05:47.639288Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T21:05:47.639583Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T21:05:47.639692Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T21:05:47.640287Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T21:05:47.640469Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T21:05:47.640521Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T21:05:47.644183Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T21:05:47.644465Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-05-11T21:06:01.128208Z","caller":"traceutil/trace.go:171","msg":"trace[2072783375] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"335.544694ms","start":"2026-05-11T21:06:00.792623Z","end":"2026-05-11T21:06:01.128168Z","steps":["trace[2072783375] 'process raft request' (duration: 335.327699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:06:01.129001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:06:00.792568Z","time spent":"335.757009ms","remote":"127.0.0.1:39312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:06:22.59939Z","caller":"traceutil/trace.go:171","msg":"trace[895504380] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"142.087056ms","start":"2026-05-11T21:06:22.457281Z","end":"2026-05-11T21:06:22.599368Z","steps":["trace[895504380] 'process raft request' (duration: 141.391492ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:06:22.784412Z","caller":"traceutil/trace.go:171","msg":"trace[266009402] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:596; }","duration":"194.039747ms","start":"2026-05-11T21:06:22.590315Z","end":"2026-05-11T21:06:22.784355Z","steps":["trace[266009402] 'read index received' (duration: 8.0156ms)","trace[266009402] 'applied index is now lower than readState.Index' (duration: 186.022937ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:06:22.784462Z","caller":"traceutil/trace.go:171","msg":"trace[957845770] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"323.305946ms","start":"2026-05-11T21:06:22.461121Z","end":"2026-05-11T21:06:22.784427Z","steps":["trace[957845770] 'process raft request' (duration: 301.658754ms)","trace[957845770] 'compare' (duration: 21.454749ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:06:22.784665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:06:22.461091Z","time spent":"323.449399ms","remote":"127.0.0.1:39458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:06:22.784675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.342523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T21:06:22.784855Z","caller":"traceutil/trace.go:171","msg":"trace[76931497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"194.582479ms","start":"2026-05-11T21:06:22.590242Z","end":"2026-05-11T21:06:22.784824Z","steps":["trace[76931497] 'agreement among raft nodes before linearized reading' (duration: 194.263573ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:06:22.809968Z","caller":"traceutil/trace.go:171","msg":"trace[1262528540] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"149.905118ms","start":"2026-05-11T21:06:22.660041Z","end":"2026-05-11T21:06:22.809946Z","steps":["trace[1262528540] 'process raft request' (duration: 149.777965ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:06:23.674202Z","caller":"traceutil/trace.go:171","msg":"trace[1967944037] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"634.659754ms","start":"2026-05-11T21:06:23.039494Z","end":"2026-05-11T21:06:23.674154Z","steps":["trace[1967944037] 'process raft request' (duration: 621.449225ms)","trace[1967944037] 'compare' (duration: 12.886742ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:06:23.674529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:06:23.039469Z","time spent":"634.821486ms","remote":"127.0.0.1:39452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4543,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:06:23.683722Z","caller":"traceutil/trace.go:171","msg":"trace[1240080812] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:600; }","duration":"409.240617ms","start":"2026-05-11T21:06:23.274457Z","end":"2026-05-11T21:06:23.683697Z","steps":["trace[1240080812] 'read index received' (duration: 386.580075ms)","trace[1240080812] 'applied index is now lower than readState.Index' (duration: 22.659562ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:06:23.68384Z","caller":"traceutil/trace.go:171","msg":"trace[1235506053] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"483.221706ms","start":"2026-05-11T21:06:23.200609Z","end":"2026-05-11T21:06:23.68383Z","steps":["trace[1235506053] 'process raft request' (duration: 482.41903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:06:23.683954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:06:23.200547Z","time spent":"483.326878ms","remote":"127.0.0.1:39378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:06:23.684077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.609714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-11T21:06:23.684112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.680048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-11T21:06:23.684152Z","caller":"traceutil/trace.go:171","msg":"trace[623588090] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:578; }","duration":"409.735647ms","start":"2026-05-11T21:06:23.274397Z","end":"2026-05-11T21:06:23.684133Z","steps":["trace[623588090] 'agreement among raft nodes before linearized reading' (duration: 409.594484ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:06:23.684185Z","caller":"traceutil/trace.go:171","msg":"trace[219906018] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"316.968334ms","start":"2026-05-11T21:06:23.367201Z","end":"2026-05-11T21:06:23.684169Z","steps":["trace[219906018] 'agreement among raft nodes before linearized reading' (duration: 316.787631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:06:23.684209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:06:23.274376Z","time spent":"409.821508ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T21:06:23.684221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:06:23.367135Z","time spent":"317.078617ms","remote":"127.0.0.1:39526","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":"warn","ts":"2026-05-11T21:06:23.684252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.268261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-11T21:06:23.684322Z","caller":"traceutil/trace.go:171","msg":"trace[656986102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"191.374453ms","start":"2026-05-11T21:06:23.492925Z","end":"2026-05-11T21:06:23.6843Z","steps":["trace[656986102] 'agreement among raft nodes before linearized reading' (duration: 191.185929ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:07:09.909984Z","caller":"traceutil/trace.go:171","msg":"trace[709050509] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"159.260679ms","start":"2026-05-11T21:07:09.75069Z","end":"2026-05-11T21:07:09.90995Z","steps":["trace[709050509] 'process raft request' (duration: 159.093405ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:07:54.477996Z","caller":"traceutil/trace.go:171","msg":"trace[2082234164] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"275.849047ms","start":"2026-05-11T21:07:54.202117Z","end":"2026-05-11T21:07:54.477966Z","steps":["trace[2082234164] 'process raft request' (duration: 275.643632ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:07:54.487822Z","caller":"traceutil/trace.go:171","msg":"trace[1409567687] linearizableReadLoop","detail":"{readStateIndex:1880; appliedIndex:1877; }","duration":"246.67682ms","start":"2026-05-11T21:07:54.241114Z","end":"2026-05-11T21:07:54.487791Z","steps":["trace[1409567687] 'read index received' (duration: 236.712798ms)","trace[1409567687] 'applied index is now lower than readState.Index' (duration: 9.962872ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:07:54.487865Z","caller":"traceutil/trace.go:171","msg":"trace[368741669] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"285.4246ms","start":"2026-05-11T21:07:54.202403Z","end":"2026-05-11T21:07:54.487827Z","steps":["trace[368741669] 'process raft request' (duration: 285.174815ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:07:54.487908Z","caller":"traceutil/trace.go:171","msg":"trace[1874168611] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"285.289088ms","start":"2026-05-11T21:07:54.2026Z","end":"2026-05-11T21:07:54.487889Z","steps":["trace[1874168611] 'process raft request' (duration: 285.142685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:07:54.488076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.503665ms","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":"warn","ts":"2026-05-11T21:07:54.488124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.989806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-11T21:07:54.488163Z","caller":"traceutil/trace.go:171","msg":"trace[1835071484] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1808; }","duration":"171.653808ms","start":"2026-05-11T21:07:54.316496Z","end":"2026-05-11T21:07:54.488149Z","steps":["trace[1835071484] 'agreement among raft nodes before linearized reading' (duration: 171.445144ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:07:54.488212Z","caller":"traceutil/trace.go:171","msg":"trace[264161195] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1808; }","duration":"247.111168ms","start":"2026-05-11T21:07:54.241076Z","end":"2026-05-11T21:07:54.488188Z","steps":["trace[264161195] 'agreement among raft nodes before linearized reading' (duration: 246.855482ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:08:06.6858Z","caller":"traceutil/trace.go:171","msg":"trace[206916021] linearizableReadLoop","detail":"{readStateIndex:1987; appliedIndex:1986; }","duration":"153.012462ms","start":"2026-05-11T21:08:06.532762Z","end":"2026-05-11T21:08:06.685774Z","steps":["trace[206916021] 'read index received' (duration: 152.779107ms)","trace[206916021] 'applied index is now lower than readState.Index' (duration: 232.595µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:08:06.685867Z","caller":"traceutil/trace.go:171","msg":"trace[865870565] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"156.936252ms","start":"2026-05-11T21:08:06.528899Z","end":"2026-05-11T21:08:06.685836Z","steps":["trace[865870565] 'process raft request' (duration: 156.702267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:08:06.686066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.275627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-11T21:08:06.686165Z","caller":"traceutil/trace.go:171","msg":"trace[1534945844] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1911; }","duration":"153.39828ms","start":"2026-05-11T21:08:06.532734Z","end":"2026-05-11T21:08:06.686132Z","steps":["trace[1534945844] 'agreement among raft nodes before linearized reading' (duration: 153.188136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:08:06.686239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.316168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-11T21:08:06.68634Z","caller":"traceutil/trace.go:171","msg":"trace[1489621701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1911; }","duration":"153.475882ms","start":"2026-05-11T21:08:06.532838Z","end":"2026-05-11T21:08:06.686313Z","steps":["trace[1489621701] 'agreement among raft nodes before linearized reading' (duration: 153.188716ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:08:06.792899Z","caller":"traceutil/trace.go:171","msg":"trace[114307019] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"102.893333ms","start":"2026-05-11T21:08:06.689973Z","end":"2026-05-11T21:08:06.792867Z","steps":["trace[114307019] 'process raft request' (duration: 39.514606ms)","trace[114307019] 'compare' (duration: 63.189333ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:08:06.793164Z","caller":"traceutil/trace.go:171","msg":"trace[582056976] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"102.846082ms","start":"2026-05-11T21:08:06.690302Z","end":"2026-05-11T21:08:06.793148Z","steps":["trace[582056976] 'process raft request' (duration: 102.519876ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:08:10.361377Z","caller":"traceutil/trace.go:171","msg":"trace[605875489] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"215.376908ms","start":"2026-05-11T21:08:10.145971Z","end":"2026-05-11T21:08:10.361348Z","steps":["trace[605875489] 'process raft request' (duration: 215.208365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:08:43.150953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.523301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-11T21:08:43.151061Z","caller":"traceutil/trace.go:171","msg":"trace[928928594] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2122; }","duration":"109.670995ms","start":"2026-05-11T21:08:43.041363Z","end":"2026-05-11T21:08:43.151034Z","steps":["trace[928928594] 'range keys from in-memory index tree' (duration: 109.295707ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:08:49.212673Z","caller":"traceutil/trace.go:171","msg":"trace[2096708434] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"117.097604ms","start":"2026-05-11T21:08:49.095543Z","end":"2026-05-11T21:08:49.212641Z","steps":["trace[2096708434] 'process raft request' (duration: 116.829439ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:09:57.867116Z","caller":"traceutil/trace.go:171","msg":"trace[351568528] linearizableReadLoop","detail":"{readStateIndex:2831; appliedIndex:2830; }","duration":"130.142306ms","start":"2026-05-11T21:09:57.736949Z","end":"2026-05-11T21:09:57.867091Z","steps":["trace[351568528] 'read index received' (duration: 33.586868ms)","trace[351568528] 'applied index is now lower than readState.Index' (duration: 96.554428ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:09:57.867289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.31291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T21:09:57.867332Z","caller":"traceutil/trace.go:171","msg":"trace[1597035090] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:2724; }","duration":"130.431662ms","start":"2026-05-11T21:09:57.736885Z","end":"2026-05-11T21:09:57.867317Z","steps":["trace[1597035090] 'agreement among raft nodes before linearized reading' (duration: 130.296589ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:09:57.867406Z","caller":"traceutil/trace.go:171","msg":"trace[207509887] transaction","detail":"{read_only:false; response_revision:2724; number_of_response:1; }","duration":"191.778109ms","start":"2026-05-11T21:09:57.675575Z","end":"2026-05-11T21:09:57.867353Z","steps":["trace[207509887] 'process raft request' (duration: 95.042098ms)","trace[207509887] 'compare' (duration: 96.367584ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:09:58.045073Z","caller":"traceutil/trace.go:171","msg":"trace[385846874] transaction","detail":"{read_only:false; response_revision:2725; number_of_response:1; }","duration":"147.459551ms","start":"2026-05-11T21:09:57.897585Z","end":"2026-05-11T21:09:58.045044Z","steps":["trace[385846874] 'process raft request' (duration: 147.313438ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:13:49.877069Z","caller":"traceutil/trace.go:171","msg":"trace[1314863071] linearizableReadLoop","detail":"{readStateIndex:4815; appliedIndex:4814; }","duration":"101.445148ms","start":"2026-05-11T21:13:49.775594Z","end":"2026-05-11T21:13:49.877039Z","steps":["trace[1314863071] 'read index received' (duration: 101.257624ms)","trace[1314863071] 'applied index is now lower than readState.Index' (duration: 186.124µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:13:49.877232Z","caller":"traceutil/trace.go:171","msg":"trace[63726089] transaction","detail":"{read_only:false; response_revision:4633; number_of_response:1; }","duration":"102.256125ms","start":"2026-05-11T21:13:49.774949Z","end":"2026-05-11T21:13:49.877205Z","steps":["trace[63726089] 'process raft request' (duration: 101.905088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:13:49.877709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.072701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:9 size:61968"} {"level":"info","ts":"2026-05-11T21:13:49.877795Z","caller":"traceutil/trace.go:171","msg":"trace[725305636] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:9; response_revision:4633; }","duration":"102.405548ms","start":"2026-05-11T21:13:49.77537Z","end":"2026-05-11T21:13:49.877776Z","steps":["trace[725305636] 'agreement among raft nodes before linearized reading' (duration: 101.839597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:13:49.881837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.611692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-crwcp\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-11T21:13:49.881922Z","caller":"traceutil/trace.go:171","msg":"trace[586623704] range","detail":"{range_begin:/registry/pods/openstack/keepalived-crwcp; range_end:; response_count:1; response_revision:4635; }","duration":"100.721814ms","start":"2026-05-11T21:13:49.781184Z","end":"2026-05-11T21:13:49.881906Z","steps":["trace[586623704] 'agreement among raft nodes before linearized reading' (duration: 100.5158ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:13:57.975951Z","caller":"traceutil/trace.go:171","msg":"trace[1707012744] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"152.310869ms","start":"2026-05-11T21:13:57.823604Z","end":"2026-05-11T21:13:57.975915Z","steps":["trace[1707012744] 'process raft request' (duration: 152.063454ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:14:10.067471Z","caller":"traceutil/trace.go:171","msg":"trace[1669611586] transaction","detail":"{read_only:false; response_revision:5140; number_of_response:1; }","duration":"117.136007ms","start":"2026-05-11T21:14:09.950298Z","end":"2026-05-11T21:14:10.067434Z","steps":["trace[1669611586] 'process raft request' (duration: 116.883772ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:14:17.863693Z","caller":"traceutil/trace.go:171","msg":"trace[129538812] linearizableReadLoop","detail":"{readStateIndex:5520; appliedIndex:5519; }","duration":"124.173197ms","start":"2026-05-11T21:14:17.739491Z","end":"2026-05-11T21:14:17.863664Z","steps":["trace[129538812] 'read index received' (duration: 35.647801ms)","trace[129538812] 'applied index is now lower than readState.Index' (duration: 88.524166ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:14:17.863748Z","caller":"traceutil/trace.go:171","msg":"trace[388089676] transaction","detail":"{read_only:false; response_revision:5318; number_of_response:1; }","duration":"156.018713ms","start":"2026-05-11T21:14:17.707672Z","end":"2026-05-11T21:14:17.86369Z","steps":["trace[388089676] 'process raft request' (duration: 67.470706ms)","trace[388089676] 'compare' (duration: 88.264491ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:14:17.863906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.395481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2bf24b3b-3754-4204-bede-6d85af7b4f4a\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-11T21:14:17.864007Z","caller":"traceutil/trace.go:171","msg":"trace[719382743] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2bf24b3b-3754-4204-bede-6d85af7b4f4a; range_end:; response_count:1; response_revision:5318; }","duration":"124.555665ms","start":"2026-05-11T21:14:17.739434Z","end":"2026-05-11T21:14:17.86399Z","steps":["trace[719382743] 'agreement among raft nodes before linearized reading' (duration: 124.34032ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:15:47.670602Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2996} {"level":"info","ts":"2026-05-11T21:15:47.743399Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2996,"took":"71.078271ms","hash":2341777146,"current-db-size-bytes":58478592,"current-db-size":"58 MB","current-db-size-in-use-bytes":34111488,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-11T21:15:47.743491Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2341777146,"revision":2996,"compact-revision":-1} {"level":"info","ts":"2026-05-11T21:20:24.375049Z","caller":"traceutil/trace.go:171","msg":"trace[1396251281] transaction","detail":"{read_only:false; response_revision:8143; number_of_response:1; }","duration":"273.56463ms","start":"2026-05-11T21:20:24.101457Z","end":"2026-05-11T21:20:24.375021Z","steps":["trace[1396251281] 'process raft request' (duration: 273.290185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:20:36.112234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.118101ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-11T21:20:36.173152Z","caller":"traceutil/trace.go:171","msg":"trace[1115576474] transaction","detail":"{read_only:false; response_revision:8207; number_of_response:1; }","duration":"199.529225ms","start":"2026-05-11T21:20:35.973598Z","end":"2026-05-11T21:20:36.173127Z","steps":["trace[1115576474] 'process raft request' (duration: 199.376872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:20:36.922483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.143123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T21:20:36.9226Z","caller":"traceutil/trace.go:171","msg":"trace[1279782137] linearizableReadLoop","detail":"{readStateIndex:8503; appliedIndex:8502; }","duration":"265.005999ms","start":"2026-05-11T21:20:36.657576Z","end":"2026-05-11T21:20:36.922582Z","steps":["trace[1279782137] 'read index received' (duration: 8.598931ms)","trace[1279782137] 'applied index is now lower than readState.Index' (duration: 256.405708ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:20:36.922726Z","caller":"traceutil/trace.go:171","msg":"trace[812475847] transaction","detail":"{read_only:false; response_revision:8210; number_of_response:1; }","duration":"296.247973ms","start":"2026-05-11T21:20:36.626441Z","end":"2026-05-11T21:20:36.922689Z","steps":["trace[812475847] 'process raft request' (duration: 39.780523ms)","trace[812475847] 'compare' (duration: 255.867638ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:20:36.922822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.250605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-11T21:20:36.92286Z","caller":"traceutil/trace.go:171","msg":"trace[1959688453] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8210; }","duration":"265.333457ms","start":"2026-05-11T21:20:36.657517Z","end":"2026-05-11T21:20:36.92285Z","steps":["trace[1959688453] 'agreement among raft nodes before linearized reading' (duration: 265.180643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:20:36.922874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.190695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-11T21:20:36.922887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.320688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-11T21:20:36.922936Z","caller":"traceutil/trace.go:171","msg":"trace[1998779209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8210; }","duration":"256.296337ms","start":"2026-05-11T21:20:36.666624Z","end":"2026-05-11T21:20:36.92292Z","steps":["trace[1998779209] 'agreement among raft nodes before linearized reading' (duration: 256.123784ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:20:36.922942Z","caller":"traceutil/trace.go:171","msg":"trace[1260453287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8210; }","duration":"256.42602ms","start":"2026-05-11T21:20:36.666503Z","end":"2026-05-11T21:20:36.922929Z","steps":["trace[1260453287] 'agreement among raft nodes before linearized reading' (duration: 256.239067ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:20:47.6783Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6103} {"level":"info","ts":"2026-05-11T21:20:47.780184Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6103,"took":"99.557413ms","hash":1490520585,"current-db-size-bytes":58478592,"current-db-size":"58 MB","current-db-size-in-use-bytes":23158784,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-11T21:20:47.78028Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1490520585,"revision":6103,"compact-revision":2996} {"level":"info","ts":"2026-05-11T21:21:04.42507Z","caller":"traceutil/trace.go:171","msg":"trace[1503385595] transaction","detail":"{read_only:false; response_revision:8413; number_of_response:1; }","duration":"153.504469ms","start":"2026-05-11T21:21:04.271533Z","end":"2026-05-11T21:21:04.425037Z","steps":["trace[1503385595] 'process raft request' (duration: 153.336545ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:21:17.601047Z","caller":"traceutil/trace.go:171","msg":"trace[1485130574] transaction","detail":"{read_only:false; response_revision:8522; number_of_response:1; }","duration":"182.724891ms","start":"2026-05-11T21:21:17.418237Z","end":"2026-05-11T21:21:17.600961Z","steps":["trace[1485130574] 'process raft request' (duration: 166.502929ms)","trace[1485130574] 'compare' (duration: 15.796714ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:21:17.609203Z","caller":"traceutil/trace.go:171","msg":"trace[733641789] transaction","detail":"{read_only:false; response_revision:8523; number_of_response:1; }","duration":"142.366157ms","start":"2026-05-11T21:21:17.466799Z","end":"2026-05-11T21:21:17.609165Z","steps":["trace[733641789] 'process raft request' (duration: 142.218834ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:21:18.213856Z","caller":"traceutil/trace.go:171","msg":"trace[866963513] transaction","detail":"{read_only:false; response_revision:8528; number_of_response:1; }","duration":"231.741087ms","start":"2026-05-11T21:21:17.982086Z","end":"2026-05-11T21:21:18.213827Z","steps":["trace[866963513] 'process raft request' (duration: 187.631388ms)","trace[866963513] 'compare' (duration: 43.968857ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:21:18.790474Z","caller":"traceutil/trace.go:171","msg":"trace[2129826022] transaction","detail":"{read_only:false; response_revision:8534; number_of_response:1; }","duration":"130.883708ms","start":"2026-05-11T21:21:18.659571Z","end":"2026-05-11T21:21:18.790455Z","steps":["trace[2129826022] 'process raft request' (duration: 87.474013ms)","trace[2129826022] 'compare' (duration: 43.038998ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:22:04.544104Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000242f.wal"} {"level":"info","ts":"2026-05-11T21:22:37.216793Z","caller":"traceutil/trace.go:171","msg":"trace[1835375073] transaction","detail":"{read_only:false; response_revision:9294; number_of_response:1; }","duration":"126.165163ms","start":"2026-05-11T21:22:37.090595Z","end":"2026-05-11T21:22:37.21676Z","steps":["trace[1835375073] 'process raft request' (duration: 122.796646ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:23:29.070001Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T21:23:29.075879Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-11T21:23:29.076039Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-11T21:25:47.685084Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8273} {"level":"info","ts":"2026-05-11T21:25:47.766603Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8273,"took":"78.489241ms","hash":1426157755,"current-db-size-bytes":58478592,"current-db-size":"58 MB","current-db-size-in-use-bytes":24014848,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-11T21:25:47.766692Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1426157755,"revision":8273,"compact-revision":6103} {"level":"info","ts":"2026-05-11T21:28:16.010285Z","caller":"traceutil/trace.go:171","msg":"trace[1432055274] linearizableReadLoop","detail":"{readStateIndex:12135; appliedIndex:12134; }","duration":"112.365089ms","start":"2026-05-11T21:28:15.897867Z","end":"2026-05-11T21:28:16.010232Z","steps":["trace[1432055274] 'read index received' (duration: 62.156355ms)","trace[1432055274] 'applied index is now lower than readState.Index' (duration: 50.207174ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:28:16.01047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.561134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T21:28:16.010563Z","caller":"traceutil/trace.go:171","msg":"trace[98504095] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:11727; }","duration":"112.756159ms","start":"2026-05-11T21:28:15.897789Z","end":"2026-05-11T21:28:16.010545Z","steps":["trace[98504095] 'agreement among raft nodes before linearized reading' (duration: 112.564074ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:30:47.692992Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10491} {"level":"info","ts":"2026-05-11T21:30:47.774546Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10491,"took":"78.110434ms","hash":3553586123,"current-db-size-bytes":58478592,"current-db-size":"58 MB","current-db-size-in-use-bytes":26005504,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-11T21:30:47.774641Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3553586123,"revision":10491,"compact-revision":8273} {"level":"info","ts":"2026-05-11T21:34:52.265243Z","caller":"traceutil/trace.go:171","msg":"trace[1240605910] transaction","detail":"{read_only:false; response_revision:14377; number_of_response:1; }","duration":"263.324991ms","start":"2026-05-11T21:34:52.001882Z","end":"2026-05-11T21:34:52.265207Z","steps":["trace[1240605910] 'process raft request' (duration: 263.126417ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:34:52.40565Z","caller":"traceutil/trace.go:171","msg":"trace[2133340423] transaction","detail":"{read_only:false; response_revision:14378; number_of_response:1; }","duration":"346.978025ms","start":"2026-05-11T21:34:52.058643Z","end":"2026-05-11T21:34:52.405621Z","steps":["trace[2133340423] 'process raft request' (duration: 346.785772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:34:52.405854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:34:52.058617Z","time spent":"347.134719ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:34:54.850414Z","caller":"traceutil/trace.go:171","msg":"trace[2108199607] transaction","detail":"{read_only:false; response_revision:14396; number_of_response:1; }","duration":"109.831128ms","start":"2026-05-11T21:34:54.740547Z","end":"2026-05-11T21:34:54.850378Z","steps":["trace[2108199607] 'process raft request' (duration: 109.655995ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:35:47.700866Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12746} {"level":"info","ts":"2026-05-11T21:35:47.784077Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12746,"took":"79.499342ms","hash":1229030013,"current-db-size-bytes":58478592,"current-db-size":"58 MB","current-db-size-in-use-bytes":28721152,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-11T21:35:47.784145Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1229030013,"revision":12746,"compact-revision":10491} {"level":"info","ts":"2026-05-11T21:36:46.859023Z","caller":"traceutil/trace.go:171","msg":"trace[1204287813] transaction","detail":"{read_only:false; response_revision:15527; number_of_response:1; }","duration":"136.967903ms","start":"2026-05-11T21:36:46.722026Z","end":"2026-05-11T21:36:46.858994Z","steps":["trace[1204287813] 'process raft request' (duration: 136.633607ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:36:58.333493Z","caller":"traceutil/trace.go:171","msg":"trace[2027271939] transaction","detail":"{read_only:false; response_revision:15581; number_of_response:1; }","duration":"103.582388ms","start":"2026-05-11T21:36:58.229861Z","end":"2026-05-11T21:36:58.333443Z","steps":["trace[2027271939] 'process raft request' (duration: 103.268611ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:40:47.707069Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14896} {"level":"info","ts":"2026-05-11T21:40:47.805694Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14896,"took":"94.838014ms","hash":704834385,"current-db-size-bytes":58478592,"current-db-size":"58 MB","current-db-size-in-use-bytes":30498816,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-11T21:40:47.805758Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":704834385,"revision":14896,"compact-revision":12746} {"level":"info","ts":"2026-05-11T21:42:44.905986Z","caller":"traceutil/trace.go:171","msg":"trace[1879670869] transaction","detail":"{read_only:false; response_revision:18163; number_of_response:1; }","duration":"122.39034ms","start":"2026-05-11T21:42:44.783564Z","end":"2026-05-11T21:42:44.905954Z","steps":["trace[1879670869] 'process raft request' (duration: 122.142195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:42:45.282574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.755303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:42:45.282694Z","caller":"traceutil/trace.go:171","msg":"trace[1286532249] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:18164; }","duration":"185.955917ms","start":"2026-05-11T21:42:45.096722Z","end":"2026-05-11T21:42:45.282678Z","steps":["trace[1286532249] 'count revisions from in-memory index tree' (duration: 185.645551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:42:45.2829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.400259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T21:42:45.283064Z","caller":"traceutil/trace.go:171","msg":"trace[374487362] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:18164; }","duration":"141.667404ms","start":"2026-05-11T21:42:45.141366Z","end":"2026-05-11T21:42:45.283033Z","steps":["trace[374487362] 'count revisions from in-memory index tree' (duration: 141.308637ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:42:45.58474Z","caller":"traceutil/trace.go:171","msg":"trace[749201588] linearizableReadLoop","detail":"{readStateIndex:18791; appliedIndex:18790; }","duration":"113.697606ms","start":"2026-05-11T21:42:45.471019Z","end":"2026-05-11T21:42:45.584717Z","steps":["trace[749201588] 'read index received' (duration: 113.377969ms)","trace[749201588] 'applied index is now lower than readState.Index' (duration: 318.287µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:42:45.584961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.92917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-11T21:42:45.584928Z","caller":"traceutil/trace.go:171","msg":"trace[688826532] transaction","detail":"{read_only:false; response_revision:18165; number_of_response:1; }","duration":"152.871317ms","start":"2026-05-11T21:42:45.431973Z","end":"2026-05-11T21:42:45.584844Z","steps":["trace[688826532] 'process raft request' (duration: 152.538611ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:42:45.585018Z","caller":"traceutil/trace.go:171","msg":"trace[1339838583] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18165; }","duration":"114.037552ms","start":"2026-05-11T21:42:45.470962Z","end":"2026-05-11T21:42:45.585Z","steps":["trace[1339838583] 'agreement among raft nodes before linearized reading' (duration: 113.866589ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:44:50.942423Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T21:44:50.947933Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-11T21:44:50.94805Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-11T21:45:47.71364Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17387} {"level":"info","ts":"2026-05-11T21:45:47.809174Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17387,"took":"90.982904ms","hash":1791594138,"current-db-size-bytes":58478592,"current-db-size":"58 MB","current-db-size-in-use-bytes":33873920,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-11T21:45:47.809261Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1791594138,"revision":17387,"compact-revision":14896} {"level":"info","ts":"2026-05-11T21:48:28.404723Z","caller":"traceutil/trace.go:171","msg":"trace[50931570] linearizableReadLoop","detail":"{readStateIndex:21546; appliedIndex:21545; }","duration":"276.625724ms","start":"2026-05-11T21:48:28.128066Z","end":"2026-05-11T21:48:28.404692Z","steps":["trace[50931570] 'read index received' (duration: 276.42746ms)","trace[50931570] 'applied index is now lower than readState.Index' (duration: 196.564µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:48:28.404877Z","caller":"traceutil/trace.go:171","msg":"trace[998795443] transaction","detail":"{read_only:false; response_revision:20831; number_of_response:1; }","duration":"283.797997ms","start":"2026-05-11T21:48:28.121019Z","end":"2026-05-11T21:48:28.404817Z","steps":["trace[998795443] 'process raft request' (duration: 283.464281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:48:28.405012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.91497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-11T21:48:28.405066Z","caller":"traceutil/trace.go:171","msg":"trace[691686216] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20831; }","duration":"277.008722ms","start":"2026-05-11T21:48:28.128043Z","end":"2026-05-11T21:48:28.405052Z","steps":["trace[691686216] 'agreement among raft nodes before linearized reading' (duration: 276.808367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:48:28.606196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.405669ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T21:48:28.606482Z","caller":"traceutil/trace.go:171","msg":"trace[64951226] transaction","detail":"{read_only:false; response_revision:20833; number_of_response:1; }","duration":"196.206321ms","start":"2026-05-11T21:48:28.410254Z","end":"2026-05-11T21:48:28.606461Z","steps":["trace[64951226] 'process raft request' (duration: 196.086989ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:48:28.60651Z","caller":"traceutil/trace.go:171","msg":"trace[1985042766] linearizableReadLoop","detail":"{readStateIndex:21547; appliedIndex:21546; }","duration":"201.66929ms","start":"2026-05-11T21:48:28.404813Z","end":"2026-05-11T21:48:28.606482Z","steps":["trace[1985042766] 'read index received' (duration: 59.870014ms)","trace[1985042766] 'applied index is now lower than readState.Index' (duration: 141.796546ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:48:28.606582Z","caller":"traceutil/trace.go:171","msg":"trace[1139176359] transaction","detail":"{read_only:false; response_revision:20832; number_of_response:1; }","duration":"319.599891ms","start":"2026-05-11T21:48:28.286785Z","end":"2026-05-11T21:48:28.606385Z","steps":["trace[1139176359] 'process raft request' (duration: 177.882077ms)","trace[1139176359] 'compare' (duration: 141.108462ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:48:28.606782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.541885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-11T21:48:28.606832Z","caller":"traceutil/trace.go:171","msg":"trace[1160419016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20833; }","duration":"388.655627ms","start":"2026-05-11T21:48:28.218164Z","end":"2026-05-11T21:48:28.606819Z","steps":["trace[1160419016] 'agreement among raft nodes before linearized reading' (duration: 388.425433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:48:28.606872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:48:28.218145Z","time spent":"388.717168ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T21:48:28.60712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:48:28.286747Z","time spent":"319.993769ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:48:28.607148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.364242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-11T21:48:28.607196Z","caller":"traceutil/trace.go:171","msg":"trace[1951893521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20833; }","duration":"383.417553ms","start":"2026-05-11T21:48:28.223767Z","end":"2026-05-11T21:48:28.607184Z","steps":["trace[1951893521] 'agreement among raft nodes before linearized reading' (duration: 383.249309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:48:28.607228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:48:28.223694Z","time spent":"383.526375ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-11T21:48:29.046244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.986331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T21:48:29.046704Z","caller":"traceutil/trace.go:171","msg":"trace[1150175681] transaction","detail":"{read_only:false; response_revision:20835; number_of_response:1; }","duration":"433.749246ms","start":"2026-05-11T21:48:28.612909Z","end":"2026-05-11T21:48:29.046658Z","steps":["trace[1150175681] 'process raft request' (duration: 433.452319ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:48:29.046974Z","caller":"traceutil/trace.go:171","msg":"trace[1717832943] transaction","detail":"{read_only:false; response_revision:20834; number_of_response:1; }","duration":"434.605324ms","start":"2026-05-11T21:48:28.612122Z","end":"2026-05-11T21:48:29.046728Z","steps":["trace[1717832943] 'process raft request' (duration: 156.024181ms)","trace[1717832943] 'compare' (duration: 277.675104ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:48:29.047071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:48:28.612876Z","time spent":"434.073192ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:48:29.047222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:48:28.6121Z","time spent":"435.018752ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:48:29.260806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.461622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:48:29.260951Z","caller":"traceutil/trace.go:171","msg":"trace[2074981893] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20835; }","duration":"103.683837ms","start":"2026-05-11T21:48:29.157234Z","end":"2026-05-11T21:48:29.260918Z","steps":["trace[2074981893] 'range keys from in-memory index tree' (duration: 103.33191ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:48:29.329131Z","caller":"traceutil/trace.go:171","msg":"trace[1767834114] transaction","detail":"{read_only:false; response_revision:20836; number_of_response:1; }","duration":"148.007549ms","start":"2026-05-11T21:48:29.181064Z","end":"2026-05-11T21:48:29.329071Z","steps":["trace[1767834114] 'process raft request' (duration: 147.697833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:48:29.474613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.090185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-05-11T21:48:29.474725Z","caller":"traceutil/trace.go:171","msg":"trace[1541838285] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:20837; }","duration":"112.280038ms","start":"2026-05-11T21:48:29.362419Z","end":"2026-05-11T21:48:29.474699Z","steps":["trace[1541838285] 'range keys from in-memory index tree' (duration: 111.87475ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:48:29.773708Z","caller":"traceutil/trace.go:171","msg":"trace[1783718167] transaction","detail":"{read_only:false; response_revision:20838; number_of_response:1; }","duration":"131.744336ms","start":"2026-05-11T21:48:29.641933Z","end":"2026-05-11T21:48:29.773677Z","steps":["trace[1783718167] 'process raft request' (duration: 131.512661ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:48:29.844159Z","caller":"traceutil/trace.go:171","msg":"trace[546281424] transaction","detail":"{read_only:false; response_revision:20839; number_of_response:1; }","duration":"183.949026ms","start":"2026-05-11T21:48:29.660164Z","end":"2026-05-11T21:48:29.844113Z","steps":["trace[546281424] 'process raft request' (duration: 152.98903ms)","trace[546281424] 'compare' (duration: 30.156311ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:50:47.721384Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19717} {"level":"info","ts":"2026-05-11T21:50:47.834333Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19717,"took":"108.350203ms","hash":213967289,"current-db-size-bytes":58478592,"current-db-size":"58 MB","current-db-size-in-use-bytes":29732864,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-11T21:50:47.834417Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":213967289,"revision":19717,"compact-revision":17387} {"level":"info","ts":"2026-05-11T21:52:27.151436Z","caller":"traceutil/trace.go:171","msg":"trace[1497071574] transaction","detail":"{read_only:false; response_revision:23312; number_of_response:1; }","duration":"229.502363ms","start":"2026-05-11T21:52:26.921889Z","end":"2026-05-11T21:52:27.151391Z","steps":["trace[1497071574] 'process raft request' (duration: 206.640746ms)","trace[1497071574] 'compare' (duration: 22.570441ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:55:47.727693Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21966} {"level":"info","ts":"2026-05-11T21:55:47.849744Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21966,"took":"112.955476ms","hash":728261928,"current-db-size-bytes":58478592,"current-db-size":"58 MB","current-db-size-in-use-bytes":43663360,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-05-11T21:55:47.849818Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":728261928,"revision":21966,"compact-revision":19717} {"level":"info","ts":"2026-05-11T21:55:57.317139Z","caller":"traceutil/trace.go:171","msg":"trace[1743007430] transaction","detail":"{read_only:false; response_revision:25427; number_of_response:1; }","duration":"118.808512ms","start":"2026-05-11T21:55:57.198299Z","end":"2026-05-11T21:55:57.317107Z","steps":["trace[1743007430] 'process raft request' (duration: 33.975908ms)","trace[1743007430] 'compare' (duration: 84.703231ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:55:58.536844Z","caller":"traceutil/trace.go:171","msg":"trace[1153178500] transaction","detail":"{read_only:false; response_revision:25436; number_of_response:1; }","duration":"122.798222ms","start":"2026-05-11T21:55:58.41402Z","end":"2026-05-11T21:55:58.536818Z","steps":["trace[1153178500] 'process raft request' (duration: 120.633389ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:55:58.788387Z","caller":"traceutil/trace.go:171","msg":"trace[1852373108] transaction","detail":"{read_only:false; response_revision:25437; number_of_response:1; }","duration":"176.965244ms","start":"2026-05-11T21:55:58.611378Z","end":"2026-05-11T21:55:58.788344Z","steps":["trace[1852373108] 'process raft request' (duration: 176.25819ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:02.501788Z","caller":"traceutil/trace.go:171","msg":"trace[1987998584] transaction","detail":"{read_only:false; response_revision:25471; number_of_response:1; }","duration":"163.76196ms","start":"2026-05-11T21:56:02.338003Z","end":"2026-05-11T21:56:02.501765Z","steps":["trace[1987998584] 'process raft request' (duration: 163.580447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:02.757713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.760562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T21:56:02.757977Z","caller":"traceutil/trace.go:171","msg":"trace[1934329832] transaction","detail":"{read_only:false; response_revision:25473; number_of_response:1; }","duration":"126.939885ms","start":"2026-05-11T21:56:02.631012Z","end":"2026-05-11T21:56:02.757952Z","steps":["trace[1934329832] 'process raft request' (duration: 126.839543ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:02.758158Z","caller":"traceutil/trace.go:171","msg":"trace[1970865873] transaction","detail":"{read_only:false; response_revision:25472; number_of_response:1; }","duration":"372.59032ms","start":"2026-05-11T21:56:02.385541Z","end":"2026-05-11T21:56:02.758132Z","steps":["trace[1970865873] 'process raft request' (duration: 258.297879ms)","trace[1970865873] 'compare' (duration: 113.596718ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:02.758276Z","caller":"traceutil/trace.go:171","msg":"trace[1679629899] linearizableReadLoop","detail":"{readStateIndex:26317; appliedIndex:26315; }","duration":"307.207924ms","start":"2026-05-11T21:56:02.451053Z","end":"2026-05-11T21:56:02.758261Z","steps":["trace[1679629899] 'read index received' (duration: 50.571959ms)","trace[1679629899] 'applied index is now lower than readState.Index' (duration: 256.634555ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:02.758404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:02.385508Z","time spent":"372.752414ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:02.758562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.549293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-cluster-operator\" ","response":"range_response_count:1 size:1083"} {"level":"info","ts":"2026-05-11T21:56:02.758672Z","caller":"traceutil/trace.go:171","msg":"trace[1494541983] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-cluster-operator; range_end:; response_count:1; response_revision:25473; }","duration":"306.735876ms","start":"2026-05-11T21:56:02.451907Z","end":"2026-05-11T21:56:02.758643Z","steps":["trace[1494541983] 'agreement among raft nodes before linearized reading' (duration: 306.500052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:02.758724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:02.451878Z","time spent":"306.837428ms","remote":"127.0.0.1:39486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":1105,"request content":"key:\"/registry/serviceaccounts/openstack/rabbitmq-cluster-operator\" "} {"level":"warn","ts":"2026-05-11T21:56:02.758739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.369962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-11T21:56:02.758762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.226948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T21:56:02.758804Z","caller":"traceutil/trace.go:171","msg":"trace[2131846743] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25473; }","duration":"168.478284ms","start":"2026-05-11T21:56:02.590304Z","end":"2026-05-11T21:56:02.758782Z","steps":["trace[2131846743] 'agreement among raft nodes before linearized reading' (duration: 168.354252ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:02.758859Z","caller":"traceutil/trace.go:171","msg":"trace[936841075] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25473; }","duration":"232.401081ms","start":"2026-05-11T21:56:02.526434Z","end":"2026-05-11T21:56:02.758835Z","steps":["trace[936841075] 'agreement among raft nodes before linearized reading' (duration: 232.187897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:02.758879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.731679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-11T21:56:02.758982Z","caller":"traceutil/trace.go:171","msg":"trace[1336678972] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25473; }","duration":"203.906032ms","start":"2026-05-11T21:56:02.555051Z","end":"2026-05-11T21:56:02.758957Z","steps":["trace[1336678972] 'agreement among raft nodes before linearized reading' (duration: 203.566376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:02.759161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.403909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:56:02.759213Z","caller":"traceutil/trace.go:171","msg":"trace[696399186] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:25473; }","duration":"308.219255ms","start":"2026-05-11T21:56:02.450979Z","end":"2026-05-11T21:56:02.759199Z","steps":["trace[696399186] 'agreement among raft nodes before linearized reading' (duration: 307.376329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:02.759253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:02.450951Z","time spent":"308.290616ms","remote":"127.0.0.1:59980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-05-11T21:56:03.033709Z","caller":"traceutil/trace.go:171","msg":"trace[574921811] linearizableReadLoop","detail":"{readStateIndex:26320; appliedIndex:26319; }","duration":"222.30254ms","start":"2026-05-11T21:56:02.811368Z","end":"2026-05-11T21:56:03.033671Z","steps":["trace[574921811] 'read index received' (duration: 201.063536ms)","trace[574921811] 'applied index is now lower than readState.Index' (duration: 21.237484ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:03.033799Z","caller":"traceutil/trace.go:171","msg":"trace[1681392215] transaction","detail":"{read_only:false; response_revision:25475; number_of_response:1; }","duration":"232.068125ms","start":"2026-05-11T21:56:02.801692Z","end":"2026-05-11T21:56:03.03376Z","steps":["trace[1681392215] 'process raft request' (duration: 210.727258ms)","trace[1681392215] 'compare' (duration: 20.919769ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:03.034029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.643386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-11T21:56:03.03409Z","caller":"traceutil/trace.go:171","msg":"trace[1872432317] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25475; }","duration":"222.772579ms","start":"2026-05-11T21:56:02.811299Z","end":"2026-05-11T21:56:03.034072Z","steps":["trace[1872432317] 'agreement among raft nodes before linearized reading' (duration: 222.558835ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:03.356873Z","caller":"traceutil/trace.go:171","msg":"trace[493838265] transaction","detail":"{read_only:false; response_revision:25477; number_of_response:1; }","duration":"278.347268ms","start":"2026-05-11T21:56:03.078501Z","end":"2026-05-11T21:56:03.356848Z","steps":["trace[493838265] 'process raft request' (duration: 278.171665ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:03.408535Z","caller":"traceutil/trace.go:171","msg":"trace[856200025] transaction","detail":"{read_only:false; response_revision:25478; number_of_response:1; }","duration":"229.673637ms","start":"2026-05-11T21:56:03.178836Z","end":"2026-05-11T21:56:03.40851Z","steps":["trace[856200025] 'process raft request' (duration: 228.955242ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:03.930918Z","caller":"traceutil/trace.go:171","msg":"trace[1471134659] linearizableReadLoop","detail":"{readStateIndex:26327; appliedIndex:26326; }","duration":"300.300527ms","start":"2026-05-11T21:56:03.630572Z","end":"2026-05-11T21:56:03.930872Z","steps":["trace[1471134659] 'read index received' (duration: 299.98318ms)","trace[1471134659] 'applied index is now lower than readState.Index' (duration: 314.766µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:03.930998Z","caller":"traceutil/trace.go:171","msg":"trace[1031810016] transaction","detail":"{read_only:false; response_revision:25482; number_of_response:1; }","duration":"320.268445ms","start":"2026-05-11T21:56:03.610678Z","end":"2026-05-11T21:56:03.930947Z","steps":["trace[1031810016] 'process raft request' (duration: 319.874957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:03.931207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:03.610659Z","time spent":"320.429119ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:03.931292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.682004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-11T21:56:03.931399Z","caller":"traceutil/trace.go:171","msg":"trace[1965811552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25482; }","duration":"300.831817ms","start":"2026-05-11T21:56:03.630545Z","end":"2026-05-11T21:56:03.931377Z","steps":["trace[1965811552] 'agreement among raft nodes before linearized reading' (duration: 300.481851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:03.931453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:03.630537Z","time spent":"300.908149ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-11T21:56:03.940882Z","caller":"traceutil/trace.go:171","msg":"trace[1984108863] transaction","detail":"{read_only:false; response_revision:25484; number_of_response:1; }","duration":"102.13415ms","start":"2026-05-11T21:56:03.838726Z","end":"2026-05-11T21:56:03.94086Z","steps":["trace[1984108863] 'process raft request' (duration: 102.084479ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:03.940968Z","caller":"traceutil/trace.go:171","msg":"trace[749992038] transaction","detail":"{read_only:false; response_revision:25483; number_of_response:1; }","duration":"207.750179ms","start":"2026-05-11T21:56:03.733187Z","end":"2026-05-11T21:56:03.940938Z","steps":["trace[749992038] 'process raft request' (duration: 207.473274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:04.084922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.767512ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T21:56:04.085134Z","caller":"traceutil/trace.go:171","msg":"trace[1552051863] transaction","detail":"{read_only:false; response_revision:25485; number_of_response:1; }","duration":"149.010286ms","start":"2026-05-11T21:56:03.936098Z","end":"2026-05-11T21:56:04.085108Z","steps":["trace[1552051863] 'process raft request' (duration: 25.877896ms)","trace[1552051863] 'compare' (duration: 122.590989ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:04.263184Z","caller":"traceutil/trace.go:171","msg":"trace[1561004336] linearizableReadLoop","detail":"{readStateIndex:26331; appliedIndex:26330; }","duration":"132.947826ms","start":"2026-05-11T21:56:04.130185Z","end":"2026-05-11T21:56:04.263132Z","steps":["trace[1561004336] 'read index received' (duration: 113.499507ms)","trace[1561004336] 'applied index is now lower than readState.Index' (duration: 19.446279ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:04.263275Z","caller":"traceutil/trace.go:171","msg":"trace[1677932987] transaction","detail":"{read_only:false; response_revision:25486; number_of_response:1; }","duration":"158.800392ms","start":"2026-05-11T21:56:04.104421Z","end":"2026-05-11T21:56:04.263221Z","steps":["trace[1677932987] 'process raft request' (duration: 139.251201ms)","trace[1677932987] 'compare' (duration: 19.192284ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:04.263487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.290352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418399"} {"level":"info","ts":"2026-05-11T21:56:04.263577Z","caller":"traceutil/trace.go:171","msg":"trace[511045525] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:25486; }","duration":"133.431335ms","start":"2026-05-11T21:56:04.130126Z","end":"2026-05-11T21:56:04.263557Z","steps":["trace[511045525] 'agreement among raft nodes before linearized reading' (duration: 133.16418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:04.53238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.297203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:56:04.532488Z","caller":"traceutil/trace.go:171","msg":"trace[485346628] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:25486; }","duration":"100.484917ms","start":"2026-05-11T21:56:04.431979Z","end":"2026-05-11T21:56:04.532464Z","steps":["trace[485346628] 'count revisions from in-memory index tree' (duration: 100.11212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:04.532602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.698038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-11T21:56:04.532725Z","caller":"traceutil/trace.go:171","msg":"trace[1712911994] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25486; }","duration":"154.912953ms","start":"2026-05-11T21:56:04.377787Z","end":"2026-05-11T21:56:04.5327Z","steps":["trace[1712911994] 'range keys from in-memory index tree' (duration: 154.358273ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:04.841482Z","caller":"traceutil/trace.go:171","msg":"trace[1776401208] transaction","detail":"{read_only:false; response_revision:25487; number_of_response:1; }","duration":"157.133678ms","start":"2026-05-11T21:56:04.684313Z","end":"2026-05-11T21:56:04.841447Z","steps":["trace[1776401208] 'process raft request' (duration: 156.696139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:05.162132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.487495ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T21:56:05.162262Z","caller":"traceutil/trace.go:171","msg":"trace[1507664040] linearizableReadLoop","detail":"{readStateIndex:26333; appliedIndex:26332; }","duration":"380.038329ms","start":"2026-05-11T21:56:04.782203Z","end":"2026-05-11T21:56:05.162242Z","steps":["trace[1507664040] 'read index received' (duration: 61.259313ms)","trace[1507664040] 'applied index is now lower than readState.Index' (duration: 318.777386ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:05.162352Z","caller":"traceutil/trace.go:171","msg":"trace[1134012554] transaction","detail":"{read_only:false; response_revision:25488; number_of_response:1; }","duration":"397.768663ms","start":"2026-05-11T21:56:04.764571Z","end":"2026-05-11T21:56:05.16234Z","steps":["trace[1134012554] 'process raft request' (duration: 251.929601ms)","trace[1134012554] 'compare' (duration: 145.074536ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:05.162407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:04.764542Z","time spent":"397.832494ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:05.1627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.478788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-11T21:56:05.162787Z","caller":"traceutil/trace.go:171","msg":"trace[705435875] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25488; }","duration":"380.615731ms","start":"2026-05-11T21:56:04.782148Z","end":"2026-05-11T21:56:05.162764Z","steps":["trace[705435875] 'agreement among raft nodes before linearized reading' (duration: 380.363046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:05.162838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:04.782133Z","time spent":"380.695662ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-11T21:56:05.170413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.617184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-11T21:56:05.170401Z","caller":"traceutil/trace.go:171","msg":"trace[488760163] transaction","detail":"{read_only:false; response_revision:25489; number_of_response:1; }","duration":"130.808172ms","start":"2026-05-11T21:56:05.039566Z","end":"2026-05-11T21:56:05.170374Z","steps":["trace[488760163] 'process raft request' (duration: 130.582597ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:05.170475Z","caller":"traceutil/trace.go:171","msg":"trace[1913002622] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25489; }","duration":"309.718536ms","start":"2026-05-11T21:56:04.860741Z","end":"2026-05-11T21:56:05.17046Z","steps":["trace[1913002622] 'agreement among raft nodes before linearized reading' (duration: 309.564363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:05.170501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:04.860719Z","time spent":"309.775577ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-05-11T21:56:05.170618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.319633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-11T21:56:05.170646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.356511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:56:05.170739Z","caller":"traceutil/trace.go:171","msg":"trace[842147215] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25489; }","duration":"120.506277ms","start":"2026-05-11T21:56:05.050207Z","end":"2026-05-11T21:56:05.170713Z","steps":["trace[842147215] 'agreement among raft nodes before linearized reading' (duration: 120.131969ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:05.170759Z","caller":"traceutil/trace.go:171","msg":"trace[2069871980] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25489; }","duration":"221.535844ms","start":"2026-05-11T21:56:04.949191Z","end":"2026-05-11T21:56:05.170726Z","steps":["trace[2069871980] 'agreement among raft nodes before linearized reading' (duration: 221.243598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:05.469644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.030527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-11T21:56:05.469754Z","caller":"traceutil/trace.go:171","msg":"trace[544776835] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25492; }","duration":"107.202731ms","start":"2026-05-11T21:56:05.362532Z","end":"2026-05-11T21:56:05.469735Z","steps":["trace[544776835] 'range keys from in-memory index tree' (duration: 106.831424ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:05.651832Z","caller":"traceutil/trace.go:171","msg":"trace[604509929] transaction","detail":"{read_only:false; response_revision:25493; number_of_response:1; }","duration":"238.061215ms","start":"2026-05-11T21:56:05.413725Z","end":"2026-05-11T21:56:05.651786Z","steps":["trace[604509929] 'process raft request' (duration: 237.882472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:05.912535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.903963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T21:56:05.912765Z","caller":"traceutil/trace.go:171","msg":"trace[399179536] transaction","detail":"{read_only:false; response_revision:25494; number_of_response:1; }","duration":"496.993297ms","start":"2026-05-11T21:56:05.415749Z","end":"2026-05-11T21:56:05.912742Z","steps":["trace[399179536] 'process raft request' (duration: 347.755526ms)","trace[399179536] 'compare' (duration: 148.76399ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:05.91284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:05.415727Z","time spent":"497.080988ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:56:05.913199Z","caller":"traceutil/trace.go:171","msg":"trace[719205799] transaction","detail":"{read_only:false; response_revision:25495; number_of_response:1; }","duration":"496.920665ms","start":"2026-05-11T21:56:05.416244Z","end":"2026-05-11T21:56:05.913164Z","steps":["trace[719205799] 'process raft request' (duration: 496.447506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:05.913267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:05.416215Z","time spent":"497.019907ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:56:05.913257Z","caller":"traceutil/trace.go:171","msg":"trace[968710143] linearizableReadLoop","detail":"{readStateIndex:26341; appliedIndex:26337; }","duration":"492.093828ms","start":"2026-05-11T21:56:05.421125Z","end":"2026-05-11T21:56:05.913219Z","steps":["trace[968710143] 'read index received' (duration: 230.524133ms)","trace[968710143] 'applied index is now lower than readState.Index' (duration: 261.568035ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:05.913354Z","caller":"traceutil/trace.go:171","msg":"trace[172193001] transaction","detail":"{read_only:false; response_revision:25497; number_of_response:1; }","duration":"440.457436ms","start":"2026-05-11T21:56:05.472887Z","end":"2026-05-11T21:56:05.913344Z","steps":["trace[172193001] 'process raft request' (duration: 440.208071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:05.913406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:05.47286Z","time spent":"440.517438ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:56:05.913411Z","caller":"traceutil/trace.go:171","msg":"trace[796230987] transaction","detail":"{read_only:false; response_revision:25496; number_of_response:1; }","duration":"495.829442ms","start":"2026-05-11T21:56:05.417562Z","end":"2026-05-11T21:56:05.913392Z","steps":["trace[796230987] 'process raft request' (duration: 495.413714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:05.913571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:05.417519Z","time spent":"495.934915ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:05.913599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.459646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18420"} {"level":"info","ts":"2026-05-11T21:56:05.913654Z","caller":"traceutil/trace.go:171","msg":"trace[455585657] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25497; }","duration":"492.545377ms","start":"2026-05-11T21:56:05.421092Z","end":"2026-05-11T21:56:05.913637Z","steps":["trace[455585657] 'agreement among raft nodes before linearized reading' (duration: 492.322603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:05.913702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:05.421078Z","time spent":"492.613428ms","remote":"127.0.0.1:39452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18442,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-11T21:56:05.913726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.498342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:56:05.913754Z","caller":"traceutil/trace.go:171","msg":"trace[588105179] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25497; }","duration":"323.563462ms","start":"2026-05-11T21:56:05.590182Z","end":"2026-05-11T21:56:05.913745Z","steps":["trace[588105179] 'agreement among raft nodes before linearized reading' (duration: 323.515841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:05.91378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:05.590165Z","time spent":"323.609494ms","remote":"127.0.0.1:39280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-11T21:56:05.913947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.82924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:56:05.913978Z","caller":"traceutil/trace.go:171","msg":"trace[1015790112] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:25497; }","duration":"231.918312ms","start":"2026-05-11T21:56:05.682049Z","end":"2026-05-11T21:56:05.913967Z","steps":["trace[1015790112] 'agreement among raft nodes before linearized reading' (duration: 231.865101ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:06.155438Z","caller":"traceutil/trace.go:171","msg":"trace[59619013] transaction","detail":"{read_only:false; response_revision:25499; number_of_response:1; }","duration":"208.163847ms","start":"2026-05-11T21:56:05.947251Z","end":"2026-05-11T21:56:06.155415Z","steps":["trace[59619013] 'process raft request' (duration: 208.028554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:06.336333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.700821ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-11T21:56:06.336634Z","caller":"traceutil/trace.go:171","msg":"trace[2136490007] linearizableReadLoop","detail":"{readStateIndex:26345; appliedIndex:26344; }","duration":"245.924402ms","start":"2026-05-11T21:56:06.090638Z","end":"2026-05-11T21:56:06.336563Z","steps":["trace[2136490007] 'read index received' (duration: 64.950277ms)","trace[2136490007] 'applied index is now lower than readState.Index' (duration: 180.969205ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:06.474735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.06811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-11T21:56:06.474854Z","caller":"traceutil/trace.go:171","msg":"trace[1011009294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25500; }","duration":"384.261484ms","start":"2026-05-11T21:56:06.090563Z","end":"2026-05-11T21:56:06.474825Z","steps":["trace[1011009294] 'agreement among raft nodes before linearized reading' (duration: 246.153006ms)","trace[1011009294] 'range keys from in-memory index tree' (duration: 137.778441ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:06.47491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:06.090518Z","time spent":"384.381606ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-11T21:56:06.597536Z","caller":"traceutil/trace.go:171","msg":"trace[1334345131] transaction","detail":"{read_only:false; response_revision:25501; number_of_response:1; }","duration":"118.862664ms","start":"2026-05-11T21:56:06.478625Z","end":"2026-05-11T21:56:06.597487Z","steps":["trace[1334345131] 'process raft request' (duration: 118.558988ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:07.415773Z","caller":"traceutil/trace.go:171","msg":"trace[1434635923] linearizableReadLoop","detail":"{readStateIndex:26349; appliedIndex:26347; }","duration":"239.351689ms","start":"2026-05-11T21:56:07.176387Z","end":"2026-05-11T21:56:07.415739Z","steps":["trace[1434635923] 'read index received' (duration: 43.338025ms)","trace[1434635923] 'applied index is now lower than readState.Index' (duration: 196.011984ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:07.415989Z","caller":"traceutil/trace.go:171","msg":"trace[700895588] transaction","detail":"{read_only:false; response_revision:25503; number_of_response:1; }","duration":"239.851739ms","start":"2026-05-11T21:56:07.176099Z","end":"2026-05-11T21:56:07.415951Z","steps":["trace[700895588] 'process raft request' (duration: 195.166147ms)","trace[700895588] 'compare' (duration: 44.312075ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:07.41629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.875609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-11T21:56:07.41634Z","caller":"traceutil/trace.go:171","msg":"trace[2045553781] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25503; }","duration":"239.958441ms","start":"2026-05-11T21:56:07.176365Z","end":"2026-05-11T21:56:07.416323Z","steps":["trace[2045553781] 'agreement among raft nodes before linearized reading' (duration: 239.692596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:07.458888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.232817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-11T21:56:07.459029Z","caller":"traceutil/trace.go:171","msg":"trace[1313528870] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25504; }","duration":"249.425162ms","start":"2026-05-11T21:56:07.209578Z","end":"2026-05-11T21:56:07.459003Z","steps":["trace[1313528870] 'agreement among raft nodes before linearized reading' (duration: 249.066864ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:07.459398Z","caller":"traceutil/trace.go:171","msg":"trace[379792070] transaction","detail":"{read_only:false; response_revision:25504; number_of_response:1; }","duration":"127.837493ms","start":"2026-05-11T21:56:07.331542Z","end":"2026-05-11T21:56:07.459379Z","steps":["trace[379792070] 'process raft request' (duration: 126.893263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:07.816896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.582664ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T21:56:07.817186Z","caller":"traceutil/trace.go:171","msg":"trace[1290503608] transaction","detail":"{read_only:false; response_revision:25508; number_of_response:1; }","duration":"158.335061ms","start":"2026-05-11T21:56:07.65883Z","end":"2026-05-11T21:56:07.817165Z","steps":["trace[1290503608] 'process raft request' (duration: 158.291811ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:07.817427Z","caller":"traceutil/trace.go:171","msg":"trace[1983791371] transaction","detail":"{read_only:false; response_revision:25505; number_of_response:1; }","duration":"396.900775ms","start":"2026-05-11T21:56:07.420513Z","end":"2026-05-11T21:56:07.817413Z","steps":["trace[1983791371] 'process raft request' (duration: 102.690809ms)","trace[1983791371] 'compare' (duration: 293.437521ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:07.817497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:07.420491Z","time spent":"396.972007ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:56:07.81764Z","caller":"traceutil/trace.go:171","msg":"trace[2066275237] linearizableReadLoop","detail":"{readStateIndex:26353; appliedIndex:26350; }","duration":"228.7484ms","start":"2026-05-11T21:56:07.588883Z","end":"2026-05-11T21:56:07.817631Z","steps":["trace[2066275237] 'read index received' (duration: 78.562µs)","trace[2066275237] 'applied index is now lower than readState.Index' (duration: 228.668698ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:07.817737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.864542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:56:07.817767Z","caller":"traceutil/trace.go:171","msg":"trace[607797590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25508; }","duration":"228.939573ms","start":"2026-05-11T21:56:07.588819Z","end":"2026-05-11T21:56:07.817758Z","steps":["trace[607797590] 'agreement among raft nodes before linearized reading' (duration: 228.851501ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:07.81791Z","caller":"traceutil/trace.go:171","msg":"trace[216540516] transaction","detail":"{read_only:false; response_revision:25506; number_of_response:1; }","duration":"353.291555ms","start":"2026-05-11T21:56:07.464578Z","end":"2026-05-11T21:56:07.81787Z","steps":["trace[216540516] 'process raft request' (duration: 352.439438ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:07.818219Z","caller":"traceutil/trace.go:171","msg":"trace[504739708] transaction","detail":"{read_only:false; response_revision:25507; number_of_response:1; }","duration":"311.269078ms","start":"2026-05-11T21:56:07.50629Z","end":"2026-05-11T21:56:07.81756Z","steps":["trace[504739708] 'process raft request' (duration: 310.781928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:07.818691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:07.506256Z","time spent":"312.052823ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:07.820364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:07.464541Z","time spent":"353.46214ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:08.143648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.901192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-11T21:56:08.143744Z","caller":"traceutil/trace.go:171","msg":"trace[1765052347] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25508; }","duration":"226.056325ms","start":"2026-05-11T21:56:07.917664Z","end":"2026-05-11T21:56:08.143721Z","steps":["trace[1765052347] 'range keys from in-memory index tree' (duration: 225.665077ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:08.154172Z","caller":"traceutil/trace.go:171","msg":"trace[1918603778] transaction","detail":"{read_only:false; response_revision:25509; number_of_response:1; }","duration":"234.773089ms","start":"2026-05-11T21:56:07.919375Z","end":"2026-05-11T21:56:08.154148Z","steps":["trace[1918603778] 'process raft request' (duration: 234.467943ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:08.154447Z","caller":"traceutil/trace.go:171","msg":"trace[1472724119] transaction","detail":"{read_only:false; response_revision:25510; number_of_response:1; }","duration":"235.060674ms","start":"2026-05-11T21:56:07.919372Z","end":"2026-05-11T21:56:08.154433Z","steps":["trace[1472724119] 'process raft request' (duration: 234.707708ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:08.154505Z","caller":"traceutil/trace.go:171","msg":"trace[670064753] linearizableReadLoop","detail":"{readStateIndex:26357; appliedIndex:26354; }","duration":"139.378884ms","start":"2026-05-11T21:56:08.015102Z","end":"2026-05-11T21:56:08.154481Z","steps":["trace[670064753] 'read index received' (duration: 138.736842ms)","trace[670064753] 'applied index is now lower than readState.Index' (duration: 640.902µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:08.154595Z","caller":"traceutil/trace.go:171","msg":"trace[1113818146] transaction","detail":"{read_only:false; response_revision:25511; number_of_response:1; }","duration":"235.147607ms","start":"2026-05-11T21:56:07.919355Z","end":"2026-05-11T21:56:08.154503Z","steps":["trace[1113818146] 'process raft request' (duration: 234.975893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:08.154735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.611509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T21:56:08.154815Z","caller":"traceutil/trace.go:171","msg":"trace[1503678355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25511; }","duration":"139.742542ms","start":"2026-05-11T21:56:08.015056Z","end":"2026-05-11T21:56:08.154798Z","steps":["trace[1503678355] 'agreement among raft nodes before linearized reading' (duration: 139.540488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:08.202917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.415704ms","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:6"} {"level":"info","ts":"2026-05-11T21:56:08.202975Z","caller":"traceutil/trace.go:171","msg":"trace[1120587729] 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:25512; }","duration":"130.542448ms","start":"2026-05-11T21:56:08.072416Z","end":"2026-05-11T21:56:08.202959Z","steps":["trace[1120587729] 'agreement among raft nodes before linearized reading' (duration: 130.396775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:08.50905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.955152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:56:08.50915Z","caller":"traceutil/trace.go:171","msg":"trace[1606875355] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25514; }","duration":"226.079124ms","start":"2026-05-11T21:56:08.283043Z","end":"2026-05-11T21:56:08.509122Z","steps":["trace[1606875355] 'count revisions from in-memory index tree' (duration: 225.824159ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:08.626451Z","caller":"traceutil/trace.go:171","msg":"trace[1395445922] linearizableReadLoop","detail":"{readStateIndex:26361; appliedIndex:26360; }","duration":"226.385781ms","start":"2026-05-11T21:56:08.400041Z","end":"2026-05-11T21:56:08.626427Z","steps":["trace[1395445922] 'read index received' (duration: 226.172897ms)","trace[1395445922] 'applied index is now lower than readState.Index' (duration: 211.514µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:08.626588Z","caller":"traceutil/trace.go:171","msg":"trace[700467577] transaction","detail":"{read_only:false; response_revision:25515; number_of_response:1; }","duration":"283.703315ms","start":"2026-05-11T21:56:08.342838Z","end":"2026-05-11T21:56:08.626542Z","steps":["trace[700467577] 'process raft request' (duration: 283.4048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:08.626693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.605385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T21:56:08.62681Z","caller":"traceutil/trace.go:171","msg":"trace[991697048] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25515; }","duration":"226.757808ms","start":"2026-05-11T21:56:08.40003Z","end":"2026-05-11T21:56:08.626788Z","steps":["trace[991697048] 'agreement among raft nodes before linearized reading' (duration: 226.529764ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:09.541992Z","caller":"traceutil/trace.go:171","msg":"trace[1613317411] transaction","detail":"{read_only:false; response_revision:25518; number_of_response:1; }","duration":"117.774242ms","start":"2026-05-11T21:56:09.424187Z","end":"2026-05-11T21:56:09.541961Z","steps":["trace[1613317411] 'process raft request' (duration: 117.601719ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:13.124379Z","caller":"traceutil/trace.go:171","msg":"trace[790677904] linearizableReadLoop","detail":"{readStateIndex:26389; appliedIndex:26388; }","duration":"458.397554ms","start":"2026-05-11T21:56:12.665948Z","end":"2026-05-11T21:56:13.124345Z","steps":["trace[790677904] 'read index received' (duration: 458.119249ms)","trace[790677904] 'applied index is now lower than readState.Index' (duration: 276.685µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:13.124466Z","caller":"traceutil/trace.go:171","msg":"trace[767794420] transaction","detail":"{read_only:false; response_revision:25542; number_of_response:1; }","duration":"485.537466ms","start":"2026-05-11T21:56:12.638886Z","end":"2026-05-11T21:56:13.124423Z","steps":["trace[767794420] 'process raft request' (duration: 485.25025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:13.124689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:12.63886Z","time spent":"485.691858ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:13.124807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.789812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-11T21:56:13.124898Z","caller":"traceutil/trace.go:171","msg":"trace[1693547333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25542; }","duration":"458.936305ms","start":"2026-05-11T21:56:12.665912Z","end":"2026-05-11T21:56:13.124848Z","steps":["trace[1693547333] 'agreement among raft nodes before linearized reading' (duration: 458.569228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:13.124983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:12.665897Z","time spent":"459.072347ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-11T21:56:13.316337Z","caller":"traceutil/trace.go:171","msg":"trace[697210343] transaction","detail":"{read_only:false; response_revision:25543; number_of_response:1; }","duration":"491.410833ms","start":"2026-05-11T21:56:12.824897Z","end":"2026-05-11T21:56:13.316307Z","steps":["trace[697210343] 'process raft request' (duration: 490.844661ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:13.316572Z","caller":"traceutil/trace.go:171","msg":"trace[67764142] transaction","detail":"{read_only:false; response_revision:25544; number_of_response:1; }","duration":"206.229858ms","start":"2026-05-11T21:56:13.11029Z","end":"2026-05-11T21:56:13.31652Z","steps":["trace[67764142] 'process raft request' (duration: 205.929752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:13.316564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:12.82487Z","time spent":"491.543536ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:56:13.316915Z","caller":"traceutil/trace.go:171","msg":"trace[1298490592] transaction","detail":"{read_only:false; response_revision:25545; number_of_response:1; }","duration":"186.404152ms","start":"2026-05-11T21:56:13.130491Z","end":"2026-05-11T21:56:13.316895Z","steps":["trace[1298490592] 'process raft request' (duration: 186.072085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:13.702878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.059113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/default\" ","response":"range_response_count:1 size:176"} {"level":"info","ts":"2026-05-11T21:56:13.70307Z","caller":"traceutil/trace.go:171","msg":"trace[1418187189] range","detail":"{range_begin:/registry/serviceaccounts/openstack/default; range_end:; response_count:1; response_revision:25546; }","duration":"277.276377ms","start":"2026-05-11T21:56:13.425768Z","end":"2026-05-11T21:56:13.703044Z","steps":["trace[1418187189] 'range keys from in-memory index tree' (duration: 276.277637ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:13.703503Z","caller":"traceutil/trace.go:171","msg":"trace[1874444460] transaction","detail":"{read_only:false; response_revision:25547; number_of_response:1; }","duration":"142.451195ms","start":"2026-05-11T21:56:13.561021Z","end":"2026-05-11T21:56:13.703472Z","steps":["trace[1874444460] 'process raft request' (duration: 141.947104ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:23.629664Z","caller":"traceutil/trace.go:171","msg":"trace[1462612363] linearizableReadLoop","detail":"{readStateIndex:26527; appliedIndex:26526; }","duration":"259.87597ms","start":"2026-05-11T21:56:23.369759Z","end":"2026-05-11T21:56:23.629635Z","steps":["trace[1462612363] 'read index received' (duration: 181.107947ms)","trace[1462612363] 'applied index is now lower than readState.Index' (duration: 78.766233ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:23.629854Z","caller":"traceutil/trace.go:171","msg":"trace[1468635080] transaction","detail":"{read_only:false; response_revision:25677; number_of_response:1; }","duration":"268.358109ms","start":"2026-05-11T21:56:23.361474Z","end":"2026-05-11T21:56:23.629832Z","steps":["trace[1468635080] 'process raft request' (duration: 189.24704ms)","trace[1468635080] 'compare' (duration: 78.707611ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:23.630419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.355679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-05-11T21:56:23.630574Z","caller":"traceutil/trace.go:171","msg":"trace[1191906462] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:25677; }","duration":"260.795997ms","start":"2026-05-11T21:56:23.369712Z","end":"2026-05-11T21:56:23.630508Z","steps":["trace[1191906462] 'agreement among raft nodes before linearized reading' (duration: 260.184565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:23.634928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.086363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-11T21:56:23.634905Z","caller":"traceutil/trace.go:171","msg":"trace[1102128820] transaction","detail":"{read_only:false; response_revision:25678; number_of_response:1; }","duration":"173.332251ms","start":"2026-05-11T21:56:23.461512Z","end":"2026-05-11T21:56:23.634844Z","steps":["trace[1102128820] 'process raft request' (duration: 173.059766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:23.634971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.842354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4d90d8a2-fff9-4cd9-b5fa-f3ebb69713bc\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-11T21:56:23.635001Z","caller":"traceutil/trace.go:171","msg":"trace[158199755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25678; }","duration":"263.184445ms","start":"2026-05-11T21:56:23.371801Z","end":"2026-05-11T21:56:23.634985Z","steps":["trace[158199755] 'agreement among raft nodes before linearized reading' (duration: 262.975371ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:23.635044Z","caller":"traceutil/trace.go:171","msg":"trace[710966319] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4d90d8a2-fff9-4cd9-b5fa-f3ebb69713bc; range_end:; response_count:1; response_revision:25678; }","duration":"110.965846ms","start":"2026-05-11T21:56:23.524059Z","end":"2026-05-11T21:56:23.635025Z","steps":["trace[710966319] 'agreement among raft nodes before linearized reading' (duration: 110.719831ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:23.838797Z","caller":"traceutil/trace.go:171","msg":"trace[161087893] linearizableReadLoop","detail":"{readStateIndex:26530; appliedIndex:26529; }","duration":"175.047876ms","start":"2026-05-11T21:56:23.663712Z","end":"2026-05-11T21:56:23.838759Z","steps":["trace[161087893] 'read index received' (duration: 174.949584ms)","trace[161087893] 'applied index is now lower than readState.Index' (duration: 96.342µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:23.838866Z","caller":"traceutil/trace.go:171","msg":"trace[1048308534] transaction","detail":"{read_only:false; response_revision:25680; number_of_response:1; }","duration":"195.519535ms","start":"2026-05-11T21:56:23.643297Z","end":"2026-05-11T21:56:23.838817Z","steps":["trace[1048308534] 'process raft request' (duration: 195.26807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:23.839115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.375483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"info","ts":"2026-05-11T21:56:23.839221Z","caller":"traceutil/trace.go:171","msg":"trace[1876627710] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:25680; }","duration":"175.518885ms","start":"2026-05-11T21:56:23.663678Z","end":"2026-05-11T21:56:23.839197Z","steps":["trace[1876627710] 'agreement among raft nodes before linearized reading' (duration: 175.24344ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:23.957048Z","caller":"traceutil/trace.go:171","msg":"trace[177755709] linearizableReadLoop","detail":"{readStateIndex:26531; appliedIndex:26530; }","duration":"112.830783ms","start":"2026-05-11T21:56:23.844186Z","end":"2026-05-11T21:56:23.957016Z","steps":["trace[177755709] 'read index received' (duration: 111.118818ms)","trace[177755709] 'applied index is now lower than readState.Index' (duration: 1.710835ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:23.957265Z","caller":"traceutil/trace.go:171","msg":"trace[1864438026] transaction","detail":"{read_only:false; response_revision:25681; number_of_response:1; }","duration":"210.684867ms","start":"2026-05-11T21:56:23.746529Z","end":"2026-05-11T21:56:23.957214Z","steps":["trace[1864438026] 'process raft request' (duration: 208.628996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:23.957475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.030217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1446"} {"level":"info","ts":"2026-05-11T21:56:23.957529Z","caller":"traceutil/trace.go:171","msg":"trace[1638262173] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25682; }","duration":"111.15496ms","start":"2026-05-11T21:56:23.846357Z","end":"2026-05-11T21:56:23.957512Z","steps":["trace[1638262173] 'agreement among raft nodes before linearized reading' (duration: 110.916755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:23.957582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.397274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18aea19910c6201d\" ","response":"range_response_count:1 size:742"} {"level":"info","ts":"2026-05-11T21:56:23.957703Z","caller":"traceutil/trace.go:171","msg":"trace[105963221] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18aea19910c6201d; range_end:; response_count:1; response_revision:25682; }","duration":"113.557308ms","start":"2026-05-11T21:56:23.844123Z","end":"2026-05-11T21:56:23.95768Z","steps":["trace[105963221] 'agreement among raft nodes before linearized reading' (duration: 113.080289ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:24.098737Z","caller":"traceutil/trace.go:171","msg":"trace[1252282294] transaction","detail":"{read_only:false; response_revision:25683; number_of_response:1; }","duration":"137.135449ms","start":"2026-05-11T21:56:23.961561Z","end":"2026-05-11T21:56:24.098697Z","steps":["trace[1252282294] 'process raft request' (duration: 136.912004ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:24.109809Z","caller":"traceutil/trace.go:171","msg":"trace[2123507705] linearizableReadLoop","detail":"{readStateIndex:26534; appliedIndex:26532; }","duration":"145.786121ms","start":"2026-05-11T21:56:23.963999Z","end":"2026-05-11T21:56:24.109785Z","steps":["trace[2123507705] 'read index received' (duration: 134.432654ms)","trace[2123507705] 'applied index is now lower than readState.Index' (duration: 11.352207ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:24.109858Z","caller":"traceutil/trace.go:171","msg":"trace[791148933] transaction","detail":"{read_only:false; response_revision:25684; number_of_response:1; }","duration":"145.991475ms","start":"2026-05-11T21:56:23.963846Z","end":"2026-05-11T21:56:24.109837Z","steps":["trace[791148933] 'process raft request' (duration: 145.758191ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:24.109945Z","caller":"traceutil/trace.go:171","msg":"trace[618696180] transaction","detail":"{read_only:false; response_revision:25685; number_of_response:1; }","duration":"145.673809ms","start":"2026-05-11T21:56:23.964209Z","end":"2026-05-11T21:56:24.109883Z","steps":["trace[618696180] 'process raft request' (duration: 145.506656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:24.110071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.039746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-05-11T21:56:24.110125Z","caller":"traceutil/trace.go:171","msg":"trace[799963862] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25685; }","duration":"146.134289ms","start":"2026-05-11T21:56:23.963978Z","end":"2026-05-11T21:56:24.110113Z","steps":["trace[799963862] 'agreement among raft nodes before linearized reading' (duration: 145.922394ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:24.330732Z","caller":"traceutil/trace.go:171","msg":"trace[322382465] linearizableReadLoop","detail":"{readStateIndex:26536; appliedIndex:26535; }","duration":"216.621676ms","start":"2026-05-11T21:56:24.114079Z","end":"2026-05-11T21:56:24.3307Z","steps":["trace[322382465] 'read index received' (duration: 128.720091ms)","trace[322382465] 'applied index is now lower than readState.Index' (duration: 87.900495ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:24.330852Z","caller":"traceutil/trace.go:171","msg":"trace[306920962] transaction","detail":"{read_only:false; response_revision:25686; number_of_response:1; }","duration":"218.135885ms","start":"2026-05-11T21:56:24.112704Z","end":"2026-05-11T21:56:24.33084Z","steps":["trace[306920962] 'process raft request' (duration: 130.070817ms)","trace[306920962] 'compare' (duration: 87.468827ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:24.331135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.040214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1446"} {"level":"info","ts":"2026-05-11T21:56:24.331209Z","caller":"traceutil/trace.go:171","msg":"trace[819451826] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25686; }","duration":"217.128495ms","start":"2026-05-11T21:56:24.114059Z","end":"2026-05-11T21:56:24.331188Z","steps":["trace[819451826] 'agreement among raft nodes before linearized reading' (duration: 216.920281ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:24.56347Z","caller":"traceutil/trace.go:171","msg":"trace[450022856] transaction","detail":"{read_only:false; response_revision:25689; number_of_response:1; }","duration":"324.287645ms","start":"2026-05-11T21:56:24.239153Z","end":"2026-05-11T21:56:24.563441Z","steps":["trace[450022856] 'process raft request' (duration: 324.225024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:24.563649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:24.239145Z","time spent":"324.409798ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:56:24.564057Z","caller":"traceutil/trace.go:171","msg":"trace[1885999357] transaction","detail":"{read_only:false; response_revision:25687; number_of_response:1; }","duration":"357.411576ms","start":"2026-05-11T21:56:24.206634Z","end":"2026-05-11T21:56:24.564046Z","steps":["trace[1885999357] 'process raft request' (duration: 356.483858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:24.564118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:24.206619Z","time spent":"357.470268ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:56:24.564276Z","caller":"traceutil/trace.go:171","msg":"trace[73765945] transaction","detail":"{read_only:false; response_revision:25688; number_of_response:1; }","duration":"325.507411ms","start":"2026-05-11T21:56:24.238748Z","end":"2026-05-11T21:56:24.564256Z","steps":["trace[73765945] 'process raft request' (duration: 324.569042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:24.564343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:24.238736Z","time spent":"325.569772ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:56:24.564311Z","caller":"traceutil/trace.go:171","msg":"trace[296249336] linearizableReadLoop","detail":"{readStateIndex:26540; appliedIndex:26539; }","duration":"233.472833ms","start":"2026-05-11T21:56:24.330804Z","end":"2026-05-11T21:56:24.564277Z","steps":["trace[296249336] 'read index received' (duration: 233.237627ms)","trace[296249336] 'applied index is now lower than readState.Index' (duration: 234.026µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:24.564443Z","caller":"traceutil/trace.go:171","msg":"trace[396005224] transaction","detail":"{read_only:false; response_revision:25690; number_of_response:1; }","duration":"301.390919ms","start":"2026-05-11T21:56:24.263037Z","end":"2026-05-11T21:56:24.564428Z","steps":["trace[396005224] 'process raft request' (duration: 301.079673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:24.564498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:24.263023Z","time spent":"301.443821ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:24.564522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.973349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T21:56:24.564568Z","caller":"traceutil/trace.go:171","msg":"trace[38578654] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25690; }","duration":"371.104771ms","start":"2026-05-11T21:56:24.193447Z","end":"2026-05-11T21:56:24.564552Z","steps":["trace[38578654] 'agreement among raft nodes before linearized reading' (duration: 370.984579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:24.564609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.323518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-11T21:56:24.564694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.755209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"warn","ts":"2026-05-11T21:56:24.564685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.555713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-11T21:56:24.564775Z","caller":"traceutil/trace.go:171","msg":"trace[1662988855] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25690; }","duration":"217.883081ms","start":"2026-05-11T21:56:24.346871Z","end":"2026-05-11T21:56:24.564754Z","steps":["trace[1662988855] 'agreement among raft nodes before linearized reading' (duration: 217.611576ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:24.564795Z","caller":"traceutil/trace.go:171","msg":"trace[731169291] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25690; }","duration":"276.721187ms","start":"2026-05-11T21:56:24.288049Z","end":"2026-05-11T21:56:24.56477Z","steps":["trace[731169291] 'agreement among raft nodes before linearized reading' (duration: 276.341839ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:24.564717Z","caller":"traceutil/trace.go:171","msg":"trace[1738462423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25690; }","duration":"275.461981ms","start":"2026-05-11T21:56:24.289233Z","end":"2026-05-11T21:56:24.564695Z","steps":["trace[1738462423] 'agreement among raft nodes before linearized reading' (duration: 275.060893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:24.564652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:24.193426Z","time spent":"371.213223ms","remote":"127.0.0.1:54646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-05-11T21:56:31.354671Z","caller":"traceutil/trace.go:171","msg":"trace[1664234344] transaction","detail":"{read_only:false; response_revision:25761; number_of_response:1; }","duration":"134.304812ms","start":"2026-05-11T21:56:31.220337Z","end":"2026-05-11T21:56:31.354642Z","steps":["trace[1664234344] 'process raft request' (duration: 134.095357ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:31.374933Z","caller":"traceutil/trace.go:171","msg":"trace[708254841] transaction","detail":"{read_only:false; response_revision:25762; number_of_response:1; }","duration":"141.368183ms","start":"2026-05-11T21:56:31.233533Z","end":"2026-05-11T21:56:31.374901Z","steps":["trace[708254841] 'process raft request' (duration: 141.170989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:31.938569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.116808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T21:56:31.938682Z","caller":"traceutil/trace.go:171","msg":"trace[363138763] linearizableReadLoop","detail":"{readStateIndex:26616; appliedIndex:26615; }","duration":"136.220961ms","start":"2026-05-11T21:56:31.802443Z","end":"2026-05-11T21:56:31.938664Z","steps":["trace[363138763] 'read index received' (duration: 45.941µs)","trace[363138763] 'applied index is now lower than readState.Index' (duration: 136.17369ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:31.938801Z","caller":"traceutil/trace.go:171","msg":"trace[1928157777] transaction","detail":"{read_only:false; response_revision:25764; number_of_response:1; }","duration":"239.81679ms","start":"2026-05-11T21:56:31.698917Z","end":"2026-05-11T21:56:31.938734Z","steps":["trace[1928157777] 'process raft request' (duration: 101.430026ms)","trace[1928157777] 'compare' (duration: 137.864283ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:31.938867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.431275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-11T21:56:31.939097Z","caller":"traceutil/trace.go:171","msg":"trace[1458695203] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25764; }","duration":"136.657139ms","start":"2026-05-11T21:56:31.802402Z","end":"2026-05-11T21:56:31.939059Z","steps":["trace[1458695203] 'agreement among raft nodes before linearized reading' (duration: 136.308832ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:32.182691Z","caller":"traceutil/trace.go:171","msg":"trace[144368536] transaction","detail":"{read_only:false; response_revision:25765; number_of_response:1; }","duration":"192.512454ms","start":"2026-05-11T21:56:31.990139Z","end":"2026-05-11T21:56:32.182651Z","steps":["trace[144368536] 'process raft request' (duration: 123.275761ms)","trace[144368536] 'compare' (duration: 69.06888ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:32.191512Z","caller":"traceutil/trace.go:171","msg":"trace[969134431] transaction","detail":"{read_only:false; response_revision:25766; number_of_response:1; }","duration":"200.34005ms","start":"2026-05-11T21:56:31.991123Z","end":"2026-05-11T21:56:32.191463Z","steps":["trace[969134431] 'process raft request' (duration: 200.097025ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:38.418706Z","caller":"traceutil/trace.go:171","msg":"trace[101189665] transaction","detail":"{read_only:false; response_revision:25818; number_of_response:1; }","duration":"217.489433ms","start":"2026-05-11T21:56:38.201186Z","end":"2026-05-11T21:56:38.418675Z","steps":["trace[101189665] 'process raft request' (duration: 217.33689ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:38.441876Z","caller":"traceutil/trace.go:171","msg":"trace[1117002164] linearizableReadLoop","detail":"{readStateIndex:26672; appliedIndex:26670; }","duration":"219.197287ms","start":"2026-05-11T21:56:38.222648Z","end":"2026-05-11T21:56:38.441846Z","steps":["trace[1117002164] 'read index received' (duration: 195.853331ms)","trace[1117002164] 'applied index is now lower than readState.Index' (duration: 23.342566ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:38.441951Z","caller":"traceutil/trace.go:171","msg":"trace[1347613974] transaction","detail":"{read_only:false; response_revision:25819; number_of_response:1; }","duration":"233.577984ms","start":"2026-05-11T21:56:38.20832Z","end":"2026-05-11T21:56:38.441898Z","steps":["trace[1347613974] 'process raft request' (duration: 233.269318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:38.442262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.716438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-11T21:56:38.442303Z","caller":"traceutil/trace.go:171","msg":"trace[1258214919] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25820; }","duration":"216.78372ms","start":"2026-05-11T21:56:38.225507Z","end":"2026-05-11T21:56:38.442291Z","steps":["trace[1258214919] 'agreement among raft nodes before linearized reading' (duration: 216.521434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:38.442339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.665137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-11T21:56:38.442411Z","caller":"traceutil/trace.go:171","msg":"trace[773893108] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25820; }","duration":"219.818531ms","start":"2026-05-11T21:56:38.222574Z","end":"2026-05-11T21:56:38.442393Z","steps":["trace[773893108] 'agreement among raft nodes before linearized reading' (duration: 219.420502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:38.595836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.122008ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T21:56:38.596173Z","caller":"traceutil/trace.go:171","msg":"trace[282130409] transaction","detail":"{read_only:false; response_revision:25821; number_of_response:1; }","duration":"148.535497ms","start":"2026-05-11T21:56:38.447607Z","end":"2026-05-11T21:56:38.596143Z","steps":["trace[282130409] 'process raft request' (duration: 32.00451ms)","trace[282130409] 'compare' (duration: 116.008736ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:38.596205Z","caller":"traceutil/trace.go:171","msg":"trace[864862683] transaction","detail":"{read_only:false; response_revision:25822; number_of_response:1; }","duration":"148.555488ms","start":"2026-05-11T21:56:38.447611Z","end":"2026-05-11T21:56:38.596167Z","steps":["trace[864862683] 'process raft request' (duration: 148.339992ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:38.919385Z","caller":"traceutil/trace.go:171","msg":"trace[732836171] transaction","detail":"{read_only:false; response_revision:25823; number_of_response:1; }","duration":"305.9858ms","start":"2026-05-11T21:56:38.613364Z","end":"2026-05-11T21:56:38.91935Z","steps":["trace[732836171] 'process raft request' (duration: 305.774736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:38.919618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:38.613333Z","time spent":"306.163104ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:56:38.931783Z","caller":"traceutil/trace.go:171","msg":"trace[918357242] transaction","detail":"{read_only:false; response_revision:25824; number_of_response:1; }","duration":"315.05338ms","start":"2026-05-11T21:56:38.616704Z","end":"2026-05-11T21:56:38.931757Z","steps":["trace[918357242] 'process raft request' (duration: 314.764625ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:38.931805Z","caller":"traceutil/trace.go:171","msg":"trace[1244526264] linearizableReadLoop","detail":"{readStateIndex:26679; appliedIndex:26675; }","duration":"301.898028ms","start":"2026-05-11T21:56:38.629874Z","end":"2026-05-11T21:56:38.931772Z","steps":["trace[1244526264] 'read index received' (duration: 289.363018ms)","trace[1244526264] 'applied index is now lower than readState.Index' (duration: 12.53338ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:38.931891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:38.616676Z","time spent":"315.158603ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T21:56:38.931884Z","caller":"traceutil/trace.go:171","msg":"trace[1206011932] transaction","detail":"{read_only:false; response_revision:25825; number_of_response:1; }","duration":"313.96785ms","start":"2026-05-11T21:56:38.617865Z","end":"2026-05-11T21:56:38.931832Z","steps":["trace[1206011932] 'process raft request' (duration: 313.803157ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:38.932099Z","caller":"traceutil/trace.go:171","msg":"trace[1560385359] transaction","detail":"{read_only:false; response_revision:25826; number_of_response:1; }","duration":"309.820457ms","start":"2026-05-11T21:56:38.622212Z","end":"2026-05-11T21:56:38.932033Z","steps":["trace[1560385359] 'process raft request' (duration: 309.49763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:38.932723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:38.622182Z","time spent":"310.427929ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:38.932185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.286676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T21:56:38.934065Z","caller":"traceutil/trace.go:171","msg":"trace[359407146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25826; }","duration":"304.215714ms","start":"2026-05-11T21:56:38.629832Z","end":"2026-05-11T21:56:38.934047Z","steps":["trace[359407146] 'agreement among raft nodes before linearized reading' (duration: 302.116562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:38.934194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:38.629821Z","time spent":"304.349297ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-11T21:56:38.932195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:38.617843Z","time spent":"314.160633ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T21:56:38.932215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.567652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-11T21:56:38.934834Z","caller":"traceutil/trace.go:171","msg":"trace[1210127100] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25826; }","duration":"301.226925ms","start":"2026-05-11T21:56:38.633584Z","end":"2026-05-11T21:56:38.934811Z","steps":["trace[1210127100] 'agreement among raft nodes before linearized reading' (duration: 298.423419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:38.934918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T21:56:38.63357Z","time spent":"301.330177ms","remote":"127.0.0.1:39526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-11T21:56:40.968686Z","caller":"traceutil/trace.go:171","msg":"trace[1775436208] transaction","detail":"{read_only:false; response_revision:25839; number_of_response:1; }","duration":"111.445545ms","start":"2026-05-11T21:56:40.857203Z","end":"2026-05-11T21:56:40.968649Z","steps":["trace[1775436208] 'process raft request' (duration: 49.474437ms)","trace[1775436208] 'compare' (duration: 61.659122ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:41.23903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.876454ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T21:56:41.239253Z","caller":"traceutil/trace.go:171","msg":"trace[412280660] linearizableReadLoop","detail":"{readStateIndex:26699; appliedIndex:26697; }","duration":"171.721378ms","start":"2026-05-11T21:56:41.067508Z","end":"2026-05-11T21:56:41.23923Z","steps":["trace[412280660] 'read index received' (duration: 63.560748ms)","trace[412280660] 'applied index is now lower than readState.Index' (duration: 108.15942ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:41.239349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.869091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T21:56:41.239377Z","caller":"traceutil/trace.go:171","msg":"trace[212220493] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25845; }","duration":"171.991864ms","start":"2026-05-11T21:56:41.067375Z","end":"2026-05-11T21:56:41.239367Z","steps":["trace[212220493] 'agreement among raft nodes before linearized reading' (duration: 171.912822ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:41.239594Z","caller":"traceutil/trace.go:171","msg":"trace[2032557738] transaction","detail":"{read_only:false; response_revision:25844; number_of_response:1; }","duration":"250.219796ms","start":"2026-05-11T21:56:40.989352Z","end":"2026-05-11T21:56:41.239572Z","steps":["trace[2032557738] 'process raft request' (duration: 141.696719ms)","trace[2032557738] 'compare' (duration: 107.564008ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:41.239516Z","caller":"traceutil/trace.go:171","msg":"trace[758365964] transaction","detail":"{read_only:false; response_revision:25845; number_of_response:1; }","duration":"249.491843ms","start":"2026-05-11T21:56:40.990004Z","end":"2026-05-11T21:56:41.239496Z","steps":["trace[758365964] 'process raft request' (duration: 249.149126ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:46.235966Z","caller":"traceutil/trace.go:171","msg":"trace[105692886] transaction","detail":"{read_only:false; response_revision:25879; number_of_response:1; }","duration":"133.053128ms","start":"2026-05-11T21:56:46.102884Z","end":"2026-05-11T21:56:46.235937Z","steps":["trace[105692886] 'process raft request' (duration: 132.886865ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:46.546629Z","caller":"traceutil/trace.go:171","msg":"trace[313009383] transaction","detail":"{read_only:false; response_revision:25880; number_of_response:1; }","duration":"130.469646ms","start":"2026-05-11T21:56:46.416133Z","end":"2026-05-11T21:56:46.546602Z","steps":["trace[313009383] 'process raft request' (duration: 130.24316ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:46.581436Z","caller":"traceutil/trace.go:171","msg":"trace[28823447] transaction","detail":"{read_only:false; response_revision:25881; number_of_response:1; }","duration":"130.790802ms","start":"2026-05-11T21:56:46.450599Z","end":"2026-05-11T21:56:46.58139Z","steps":["trace[28823447] 'process raft request' (duration: 130.499366ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:46.581615Z","caller":"traceutil/trace.go:171","msg":"trace[750793867] transaction","detail":"{read_only:false; response_revision:25882; number_of_response:1; }","duration":"110.838134ms","start":"2026-05-11T21:56:46.470734Z","end":"2026-05-11T21:56:46.581572Z","steps":["trace[750793867] 'process raft request' (duration: 110.547598ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:54.400925Z","caller":"traceutil/trace.go:171","msg":"trace[904931550] transaction","detail":"{read_only:false; response_revision:25949; number_of_response:1; }","duration":"122.268122ms","start":"2026-05-11T21:56:54.278623Z","end":"2026-05-11T21:56:54.400891Z","steps":["trace[904931550] 'process raft request' (duration: 121.998846ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:54.884306Z","caller":"traceutil/trace.go:171","msg":"trace[1297538888] linearizableReadLoop","detail":"{readStateIndex:26807; appliedIndex:26806; }","duration":"231.224378ms","start":"2026-05-11T21:56:54.65305Z","end":"2026-05-11T21:56:54.884275Z","steps":["trace[1297538888] 'read index received' (duration: 231.066875ms)","trace[1297538888] 'applied index is now lower than readState.Index' (duration: 156.053µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:54.884365Z","caller":"traceutil/trace.go:171","msg":"trace[2024911347] transaction","detail":"{read_only:false; response_revision:25951; number_of_response:1; }","duration":"264.636646ms","start":"2026-05-11T21:56:54.619691Z","end":"2026-05-11T21:56:54.884328Z","steps":["trace[2024911347] 'process raft request' (duration: 264.410311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:54.884663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.439845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-11T21:56:54.884726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.492476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-11T21:56:54.884748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.651526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-05-11T21:56:54.884795Z","caller":"traceutil/trace.go:171","msg":"trace[1477801248] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25951; }","duration":"140.633658ms","start":"2026-05-11T21:56:54.744149Z","end":"2026-05-11T21:56:54.884783Z","steps":["trace[1477801248] 'agreement among raft nodes before linearized reading' (duration: 140.310291ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:54.88483Z","caller":"traceutil/trace.go:171","msg":"trace[724226532] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25951; }","duration":"140.656928ms","start":"2026-05-11T21:56:54.744149Z","end":"2026-05-11T21:56:54.884806Z","steps":["trace[724226532] 'agreement among raft nodes before linearized reading' (duration: 140.346082ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:54.884852Z","caller":"traceutil/trace.go:171","msg":"trace[254778667] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25951; }","duration":"231.81041ms","start":"2026-05-11T21:56:54.653016Z","end":"2026-05-11T21:56:54.884826Z","steps":["trace[254778667] 'agreement among raft nodes before linearized reading' (duration: 231.398981ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:57.251918Z","caller":"traceutil/trace.go:171","msg":"trace[1475122758] transaction","detail":"{read_only:false; response_revision:25969; number_of_response:1; }","duration":"150.490215ms","start":"2026-05-11T21:56:57.101393Z","end":"2026-05-11T21:56:57.251883Z","steps":["trace[1475122758] 'process raft request' (duration: 103.64864ms)","trace[1475122758] 'compare' (duration: 46.60171ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:57.251974Z","caller":"traceutil/trace.go:171","msg":"trace[1719017283] transaction","detail":"{read_only:false; response_revision:25971; number_of_response:1; }","duration":"145.429684ms","start":"2026-05-11T21:56:57.106505Z","end":"2026-05-11T21:56:57.251934Z","steps":["trace[1719017283] 'process raft request' (duration: 145.372053ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:56:57.252002Z","caller":"traceutil/trace.go:171","msg":"trace[1841685111] transaction","detail":"{read_only:false; response_revision:25970; number_of_response:1; }","duration":"150.405643ms","start":"2026-05-11T21:56:57.101544Z","end":"2026-05-11T21:56:57.25195Z","steps":["trace[1841685111] 'process raft request' (duration: 150.25302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:57.484523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.65687ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T21:56:57.484692Z","caller":"traceutil/trace.go:171","msg":"trace[1552785478] linearizableReadLoop","detail":"{readStateIndex:26829; appliedIndex:26828; }","duration":"154.048246ms","start":"2026-05-11T21:56:57.330623Z","end":"2026-05-11T21:56:57.484671Z","steps":["trace[1552785478] 'read index received' (duration: 39.154362ms)","trace[1552785478] 'applied index is now lower than readState.Index' (duration: 114.892304ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:56:57.484755Z","caller":"traceutil/trace.go:171","msg":"trace[294916432] transaction","detail":"{read_only:false; response_revision:25972; number_of_response:1; }","duration":"154.274301ms","start":"2026-05-11T21:56:57.330424Z","end":"2026-05-11T21:56:57.484698Z","steps":["trace[294916432] 'process raft request' (duration: 39.309625ms)","trace[294916432] 'compare' (duration: 114.513017ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:56:57.484918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.310301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T21:56:57.484959Z","caller":"traceutil/trace.go:171","msg":"trace[859177475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25972; }","duration":"154.371763ms","start":"2026-05-11T21:56:57.330576Z","end":"2026-05-11T21:56:57.484947Z","steps":["trace[859177475] 'agreement among raft nodes before linearized reading' (duration: 154.22407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T21:56:57.484936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.134578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-11T21:56:57.485039Z","caller":"traceutil/trace.go:171","msg":"trace[1591860618] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25972; }","duration":"148.304251ms","start":"2026-05-11T21:56:57.336709Z","end":"2026-05-11T21:56:57.485013Z","steps":["trace[1591860618] 'agreement among raft nodes before linearized reading' (duration: 148.135058ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:07.867825Z","caller":"traceutil/trace.go:171","msg":"trace[61942098] transaction","detail":"{read_only:false; response_revision:26061; number_of_response:1; }","duration":"196.907153ms","start":"2026-05-11T21:57:07.670816Z","end":"2026-05-11T21:57:07.867723Z","steps":["trace[61942098] 'process raft request' (duration: 119.292413ms)","trace[61942098] 'compare' (duration: 76.967526ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T21:57:07.940257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.566508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-11T21:57:07.940355Z","caller":"traceutil/trace.go:171","msg":"trace[1456221181] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:26062; }","duration":"104.70606ms","start":"2026-05-11T21:57:07.835627Z","end":"2026-05-11T21:57:07.940333Z","steps":["trace[1456221181] 'agreement among raft nodes before linearized reading' (duration: 104.540387ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:08.701789Z","caller":"traceutil/trace.go:171","msg":"trace[1162202959] transaction","detail":"{read_only:false; response_revision:26064; number_of_response:1; }","duration":"159.792571ms","start":"2026-05-11T21:57:08.541967Z","end":"2026-05-11T21:57:08.70176Z","steps":["trace[1162202959] 'process raft request' (duration: 159.481664ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:13.875374Z","caller":"traceutil/trace.go:171","msg":"trace[1124161724] transaction","detail":"{read_only:false; response_revision:26101; number_of_response:1; }","duration":"167.776181ms","start":"2026-05-11T21:57:13.707565Z","end":"2026-05-11T21:57:13.875341Z","steps":["trace[1124161724] 'process raft request' (duration: 167.573987ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T21:57:13.987806Z","caller":"traceutil/trace.go:171","msg":"trace[1592675781] transaction","detail":"{read_only:false; response_revision:26102; number_of_response:1; }","duration":"250.95317ms","start":"2026-05-11T21:57:13.736809Z","end":"2026-05-11T21:57:13.987762Z","steps":["trace[1592675781] 'process raft request' (duration: 168.325921ms)","trace[1592675781] 'compare' (duration: 82.307963ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T21:58:04.52597Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006b85.wal"} {"level":"info","ts":"2026-05-11T22:00:47.734397Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25324} {"level":"info","ts":"2026-05-11T22:00:47.880377Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25324,"took":"138.459702ms","hash":1058213033,"current-db-size-bytes":58900480,"current-db-size":"59 MB","current-db-size-in-use-bytes":38699008,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-05-11T22:00:47.880465Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1058213033,"revision":25324,"compact-revision":21966} {"level":"info","ts":"2026-05-11T22:02:16.497829Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T22:02:16.50213Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-11T22:02:16.502194Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-11T22:05:47.740111Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28354} {"level":"info","ts":"2026-05-11T22:05:47.881912Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28354,"took":"135.459452ms","hash":1257433910,"current-db-size-bytes":58900480,"current-db-size":"59 MB","current-db-size-in-use-bytes":32178176,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-11T22:05:47.881995Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1257433910,"revision":28354,"compact-revision":25324} {"level":"info","ts":"2026-05-11T22:10:47.747668Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30681} {"level":"info","ts":"2026-05-11T22:10:47.88079Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30681,"took":"127.650517ms","hash":277911927,"current-db-size-bytes":58900480,"current-db-size":"59 MB","current-db-size-in-use-bytes":31174656,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-11T22:10:47.880882Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":277911927,"revision":30681,"compact-revision":28354} {"level":"info","ts":"2026-05-11T22:15:47.757347Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32939} {"level":"info","ts":"2026-05-11T22:15:47.912841Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32939,"took":"150.27056ms","hash":3779864358,"current-db-size-bytes":59944960,"current-db-size":"60 MB","current-db-size-in-use-bytes":31379456,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-11T22:15:47.912927Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3779864358,"revision":32939,"compact-revision":30681} {"level":"info","ts":"2026-05-11T22:20:47.771717Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35244} {"level":"info","ts":"2026-05-11T22:20:47.923856Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35244,"took":"147.686127ms","hash":1443400150,"current-db-size-bytes":61267968,"current-db-size":"61 MB","current-db-size-in-use-bytes":31518720,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-11T22:20:47.923938Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1443400150,"revision":35244,"compact-revision":32939} {"level":"info","ts":"2026-05-11T22:23:41.467455Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-11T22:23:41.475261Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-11T22:23:41.475453Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-11T22:25:47.77926Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37471} {"level":"info","ts":"2026-05-11T22:25:47.901772Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37471,"took":"117.972121ms","hash":2626688246,"current-db-size-bytes":61698048,"current-db-size":"62 MB","current-db-size-in-use-bytes":32391168,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-11T22:25:47.901843Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2626688246,"revision":37471,"compact-revision":35244}