{"level":"warn","ts":"2026-06-16T03:57:39.049167Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-16T03:57:39.04925Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227: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.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227: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-06-16T03:57:39.049317Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-16T03:57:39.04933Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-06-16T03:57:39.049346Z","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-06-16T03:57:39.049903Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-06-16T03:57:39.049984Z","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.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227: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-06-16T03:57:39.053893Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.619613ms"} {"level":"info","ts":"2026-06-16T03:57:39.059506Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-06-16T03:57:39.059583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-06-16T03:57:39.059604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-06-16T03:57:39.059614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-16T03:57:39.059629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-06-16T03:57:39.059663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-06-16T03:57:39.066333Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-16T03:57:39.069317Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-16T03:57:39.070672Z","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-06-16T03:57:39.073659Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-16T03:57:39.073817Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-16T03:57:39.073931Z","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-06-16T03:57:39.07588Z","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-06-16T03:57:39.075973Z","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-06-16T03:57:39.077364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-06-16T03:57:39.077608Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-06-16T03:57:39.079049Z","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-06-16T03:57:39.07918Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-06-16T03:57:39.079239Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-06-16T03:57:39.079339Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-16T03:57:39.079372Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-16T03:57:40.060701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-06-16T03:57:40.060763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-16T03:57:40.060797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-06-16T03:57:40.060818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-06-16T03:57:40.060829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-06-16T03:57:40.060845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-06-16T03:57:40.060858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-06-16T03:57:40.061783Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T03:57:40.062711Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-06-16T03:57:40.062734Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T03:57:40.062867Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T03:57:40.063327Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T03:57:40.063462Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-16T03:57:40.063482Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T03:57:40.063504Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-16T03:57:40.063524Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T03:57:40.066532Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-16T03:57:40.068806Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"info","ts":"2026-06-16T03:57:53.537204Z","caller":"traceutil/trace.go:171","msg":"trace[1797427644] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"101.534099ms","start":"2026-06-16T03:57:53.435624Z","end":"2026-06-16T03:57:53.537159Z","steps":["trace[1797427644] 'process raft request' (duration: 101.303834ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T03:58:16.075894Z","caller":"traceutil/trace.go:171","msg":"trace[513064656] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"107.104808ms","start":"2026-06-16T03:58:15.968768Z","end":"2026-06-16T03:58:16.075873Z","steps":["trace[513064656] 'process raft request' (duration: 106.965355ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T03:58:16.140123Z","caller":"traceutil/trace.go:171","msg":"trace[1802509437] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"164.868175ms","start":"2026-06-16T03:58:15.975163Z","end":"2026-06-16T03:58:16.140031Z","steps":["trace[1802509437] 'process raft request' (duration: 163.783003ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T03:58:16.140352Z","caller":"traceutil/trace.go:171","msg":"trace[1679955364] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"164.842705ms","start":"2026-06-16T03:58:15.97522Z","end":"2026-06-16T03:58:16.140063Z","steps":["trace[1679955364] 'process raft request' (duration: 163.877885ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T03:58:16.140925Z","caller":"traceutil/trace.go:171","msg":"trace[618699619] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"159.066534ms","start":"2026-06-16T03:58:15.981658Z","end":"2026-06-16T03:58:16.140724Z","steps":["trace[618699619] 'process raft request' (duration: 157.48916ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T03:58:16.141422Z","caller":"traceutil/trace.go:171","msg":"trace[459548904] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"158.332808ms","start":"2026-06-16T03:58:15.982835Z","end":"2026-06-16T03:58:16.141167Z","steps":["trace[459548904] 'process raft request' (duration: 156.351966ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T03:58:16.142283Z","caller":"traceutil/trace.go:171","msg":"trace[1887555465] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"159.022613ms","start":"2026-06-16T03:58:15.983225Z","end":"2026-06-16T03:58:16.142247Z","steps":["trace[1887555465] 'process raft request' (duration: 156.625391ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T03:58:16.143671Z","caller":"traceutil/trace.go:171","msg":"trace[1610451150] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"157.904029ms","start":"2026-06-16T03:58:15.985745Z","end":"2026-06-16T03:58:16.143649Z","steps":["trace[1610451150] 'process raft request' (duration: 157.786617ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T03:58:16.143893Z","caller":"traceutil/trace.go:171","msg":"trace[573848251] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"159.753648ms","start":"2026-06-16T03:58:15.984124Z","end":"2026-06-16T03:58:16.143877Z","steps":["trace[573848251] 'process raft request' (duration: 158.534031ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T03:58:16.144169Z","caller":"traceutil/trace.go:171","msg":"trace[1525582710] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"150.550983ms","start":"2026-06-16T03:58:15.993603Z","end":"2026-06-16T03:58:16.144154Z","steps":["trace[1525582710] 'process raft request' (duration: 150.023222ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T03:58:16.144356Z","caller":"traceutil/trace.go:171","msg":"trace[685810690] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"148.624311ms","start":"2026-06-16T03:58:15.995709Z","end":"2026-06-16T03:58:16.144333Z","steps":["trace[685810690] 'process raft request' (duration: 148.295205ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T03:58:16.144434Z","caller":"traceutil/trace.go:171","msg":"trace[2145566168] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"115.986167ms","start":"2026-06-16T03:58:16.028433Z","end":"2026-06-16T03:58:16.144419Z","steps":["trace[2145566168] 'process raft request' (duration: 115.780033ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T03:58:16.14477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.214934ms","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-06-16T03:58:16.144869Z","caller":"traceutil/trace.go:171","msg":"trace[211778848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:535; }","duration":"125.400998ms","start":"2026-06-16T03:58:16.019441Z","end":"2026-06-16T03:58:16.144842Z","steps":["trace[211778848] 'agreement among raft nodes before linearized reading' (duration: 125.080941ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T03:58:16.144445Z","caller":"traceutil/trace.go:171","msg":"trace[1226075303] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:541; }","duration":"124.878257ms","start":"2026-06-16T03:58:16.019469Z","end":"2026-06-16T03:58:16.144348Z","steps":["trace[1226075303] 'read index received' (duration: 56.38219ms)","trace[1226075303] 'applied index is now lower than readState.Index' (duration: 68.493697ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T03:58:16.289259Z","caller":"traceutil/trace.go:171","msg":"trace[179028992] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"100.834945ms","start":"2026-06-16T03:58:16.188375Z","end":"2026-06-16T03:58:16.289209Z","steps":["trace[179028992] 'process raft request' (duration: 45.977378ms)","trace[179028992] 'compare' (duration: 54.687203ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T03:58:16.313402Z","caller":"traceutil/trace.go:171","msg":"trace[1331213482] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"117.668433ms","start":"2026-06-16T03:58:16.19572Z","end":"2026-06-16T03:58:16.313389Z","steps":["trace[1331213482] 'process raft request' (duration: 117.298625ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T03:58:16.313461Z","caller":"traceutil/trace.go:171","msg":"trace[772928886] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"114.829933ms","start":"2026-06-16T03:58:16.198605Z","end":"2026-06-16T03:58:16.313435Z","steps":["trace[772928886] 'process raft request' (duration: 114.645049ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T03:58:16.313685Z","caller":"traceutil/trace.go:171","msg":"trace[1889271031] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"103.863029ms","start":"2026-06-16T03:58:16.209802Z","end":"2026-06-16T03:58:16.313665Z","steps":["trace[1889271031] 'process raft request' (duration: 103.790638ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T03:58:16.313766Z","caller":"traceutil/trace.go:171","msg":"trace[1046209817] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"113.511964ms","start":"2026-06-16T03:58:16.200203Z","end":"2026-06-16T03:58:16.313715Z","steps":["trace[1046209817] 'process raft request' (duration: 113.179897ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T03:58:16.611489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.552652ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T03:58:16.611766Z","caller":"traceutil/trace.go:171","msg":"trace[1519469674] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"371.43823ms","start":"2026-06-16T03:58:16.240273Z","end":"2026-06-16T03:58:16.611711Z","steps":["trace[1519469674] 'process raft request' (duration: 259.088421ms)","trace[1519469674] 'compare' (duration: 111.307776ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T03:58:16.612023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T03:58:16.240256Z","time spent":"371.643685ms","remote":"127.0.0.1:46390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T03:58:16.612151Z","caller":"traceutil/trace.go:171","msg":"trace[838952055] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"285.092034ms","start":"2026-06-16T03:58:16.327037Z","end":"2026-06-16T03:58:16.612129Z","steps":["trace[838952055] 'process raft request' (duration: 284.665205ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T03:58:16.612153Z","caller":"traceutil/trace.go:171","msg":"trace[1561978000] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"287.757921ms","start":"2026-06-16T03:58:16.324368Z","end":"2026-06-16T03:58:16.612126Z","steps":["trace[1561978000] 'process raft request' (duration: 287.24427ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T03:58:16.613801Z","caller":"traceutil/trace.go:171","msg":"trace[1793013477] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"228.496251ms","start":"2026-06-16T03:58:16.385257Z","end":"2026-06-16T03:58:16.613754Z","steps":["trace[1793013477] 'process raft request' (duration: 228.176593ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T03:58:16.613854Z","caller":"traceutil/trace.go:171","msg":"trace[1126133034] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"283.968379ms","start":"2026-06-16T03:58:16.329508Z","end":"2026-06-16T03:58:16.613476Z","steps":["trace[1126133034] 'process raft request' (duration: 282.326245ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T03:58:22.271882Z","caller":"traceutil/trace.go:171","msg":"trace[225427393] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"133.340416ms","start":"2026-06-16T03:58:22.138506Z","end":"2026-06-16T03:58:22.271846Z","steps":["trace[225427393] 'process raft request' (duration: 127.379699ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T03:58:22.81179Z","caller":"traceutil/trace.go:171","msg":"trace[297805472] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"254.640436ms","start":"2026-06-16T03:58:22.557127Z","end":"2026-06-16T03:58:22.811767Z","steps":["trace[297805472] 'process raft request' (duration: 254.441842ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T03:59:02.236502Z","caller":"traceutil/trace.go:171","msg":"trace[268342617] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"117.314045ms","start":"2026-06-16T03:59:02.11916Z","end":"2026-06-16T03:59:02.236474Z","steps":["trace[268342617] 'process raft request' (duration: 24.635897ms)","trace[268342617] 'compare' (duration: 92.512344ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T03:59:24.44426Z","caller":"traceutil/trace.go:171","msg":"trace[278844453] transaction","detail":"{read_only:false; response_revision:1441; number_of_response:1; }","duration":"105.90621ms","start":"2026-06-16T03:59:24.338326Z","end":"2026-06-16T03:59:24.444232Z","steps":["trace[278844453] 'process raft request' (duration: 105.703806ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T03:59:48.857038Z","caller":"traceutil/trace.go:171","msg":"trace[1284856444] linearizableReadLoop","detail":"{readStateIndex:1924; appliedIndex:1923; }","duration":"223.94347ms","start":"2026-06-16T03:59:48.633068Z","end":"2026-06-16T03:59:48.857011Z","steps":["trace[1284856444] 'read index received' (duration: 223.787467ms)","trace[1284856444] 'applied index is now lower than readState.Index' (duration: 154.773µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T03:59:48.857181Z","caller":"traceutil/trace.go:171","msg":"trace[954023051] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"251.170932ms","start":"2026-06-16T03:59:48.605998Z","end":"2026-06-16T03:59:48.857169Z","steps":["trace[954023051] 'process raft request' (duration: 250.839944ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T03:59:48.857453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.379509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6dc78809-bf72-4509-8c97-67466fb02f9c\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-16T03:59:48.857536Z","caller":"traceutil/trace.go:171","msg":"trace[1197523611] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6dc78809-bf72-4509-8c97-67466fb02f9c; range_end:; response_count:1; response_revision:1857; }","duration":"224.508473ms","start":"2026-06-16T03:59:48.633008Z","end":"2026-06-16T03:59:48.857516Z","steps":["trace[1197523611] 'agreement among raft nodes before linearized reading' (duration: 224.36741ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:00:39.263033Z","caller":"traceutil/trace.go:171","msg":"trace[623881836] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"106.573004ms","start":"2026-06-16T04:00:39.156415Z","end":"2026-06-16T04:00:39.262988Z","steps":["trace[623881836] 'process raft request' (duration: 106.39882ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:00:39.537395Z","caller":"traceutil/trace.go:171","msg":"trace[214346175] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"270.527119ms","start":"2026-06-16T04:00:39.266829Z","end":"2026-06-16T04:00:39.537356Z","steps":["trace[214346175] 'process raft request' (duration: 222.318607ms)","trace[214346175] 'compare' (duration: 48.06962ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:02:08.798419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.668711ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T04:02:08.798729Z","caller":"traceutil/trace.go:171","msg":"trace[1317435164] transaction","detail":"{read_only:false; response_revision:2853; number_of_response:1; }","duration":"324.385562ms","start":"2026-06-16T04:02:08.474286Z","end":"2026-06-16T04:02:08.798671Z","steps":["trace[1317435164] 'process raft request' (duration: 52.305223ms)","trace[1317435164] 'compare' (duration: 271.381545ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:02:08.798894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:02:08.47426Z","time spent":"324.555825ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T04:05:58.14953Z","caller":"traceutil/trace.go:171","msg":"trace[43198052] linearizableReadLoop","detail":"{readStateIndex:5050; appliedIndex:5049; }","duration":"105.042074ms","start":"2026-06-16T04:05:58.044464Z","end":"2026-06-16T04:05:58.149506Z","steps":["trace[43198052] 'read index received' (duration: 104.872871ms)","trace[43198052] 'applied index is now lower than readState.Index' (duration: 167.973µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:05:58.149836Z","caller":"traceutil/trace.go:171","msg":"trace[1272319132] transaction","detail":"{read_only:false; response_revision:4869; number_of_response:1; }","duration":"131.695471ms","start":"2026-06-16T04:05:58.018088Z","end":"2026-06-16T04:05:58.149783Z","steps":["trace[1272319132] 'process raft request' (duration: 131.266672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:05:58.149999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.493723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-06-16T04:05:58.150161Z","caller":"traceutil/trace.go:171","msg":"trace[910862217] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4869; }","duration":"105.708687ms","start":"2026-06-16T04:05:58.0444Z","end":"2026-06-16T04:05:58.150109Z","steps":["trace[910862217] 'agreement among raft nodes before linearized reading' (duration: 105.321119ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:06:02.371182Z","caller":"traceutil/trace.go:171","msg":"trace[644857075] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"122.651955ms","start":"2026-06-16T04:06:02.248495Z","end":"2026-06-16T04:06:02.371147Z","steps":["trace[644857075] 'process raft request' (duration: 122.186636ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:06:23.397408Z","caller":"traceutil/trace.go:171","msg":"trace[1740023770] linearizableReadLoop","detail":"{readStateIndex:5632; appliedIndex:5631; }","duration":"135.981649ms","start":"2026-06-16T04:06:23.261393Z","end":"2026-06-16T04:06:23.397375Z","steps":["trace[1740023770] 'read index received' (duration: 56.131131ms)","trace[1740023770] 'applied index is now lower than readState.Index' (duration: 79.848648ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:06:23.397615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.188683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-16T04:06:23.397622Z","caller":"traceutil/trace.go:171","msg":"trace[367660761] transaction","detail":"{read_only:false; response_revision:5435; number_of_response:1; }","duration":"137.722974ms","start":"2026-06-16T04:06:23.259875Z","end":"2026-06-16T04:06:23.397598Z","steps":["trace[367660761] 'process raft request' (duration: 57.632211ms)","trace[367660761] 'compare' (duration: 79.702576ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:06:23.397664Z","caller":"traceutil/trace.go:171","msg":"trace[321133279] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:5435; }","duration":"136.301075ms","start":"2026-06-16T04:06:23.261349Z","end":"2026-06-16T04:06:23.39765Z","steps":["trace[321133279] 'agreement among raft nodes before linearized reading' (duration: 136.183803ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:06:23.467911Z","caller":"traceutil/trace.go:171","msg":"trace[846836309] transaction","detail":"{read_only:false; response_revision:5436; number_of_response:1; }","duration":"196.010259ms","start":"2026-06-16T04:06:23.271868Z","end":"2026-06-16T04:06:23.467878Z","steps":["trace[846836309] 'process raft request' (duration: 195.817705ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:06:23.615697Z","caller":"traceutil/trace.go:171","msg":"trace[800354439] transaction","detail":"{read_only:false; response_revision:5437; number_of_response:1; }","duration":"114.290704ms","start":"2026-06-16T04:06:23.50136Z","end":"2026-06-16T04:06:23.615651Z","steps":["trace[800354439] 'process raft request' (duration: 25.34749ms)","trace[800354439] 'compare' (duration: 88.74167ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:06:23.754866Z","caller":"traceutil/trace.go:171","msg":"trace[157305121] linearizableReadLoop","detail":"{readStateIndex:5635; appliedIndex:5634; }","duration":"127.24529ms","start":"2026-06-16T04:06:23.627584Z","end":"2026-06-16T04:06:23.754829Z","steps":["trace[157305121] 'read index received' (duration: 88.947355ms)","trace[157305121] 'applied index is now lower than readState.Index' (duration: 38.296075ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:06:23.754976Z","caller":"traceutil/trace.go:171","msg":"trace[31878818] transaction","detail":"{read_only:false; response_revision:5438; number_of_response:1; }","duration":"131.272802ms","start":"2026-06-16T04:06:23.623651Z","end":"2026-06-16T04:06:23.754924Z","steps":["trace[31878818] 'process raft request' (duration: 92.903325ms)","trace[31878818] 'compare' (duration: 38.07925ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:06:23.755195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.592537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-06-16T04:06:23.755238Z","caller":"traceutil/trace.go:171","msg":"trace[2005433210] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5438; }","duration":"127.710659ms","start":"2026-06-16T04:06:23.627516Z","end":"2026-06-16T04:06:23.755226Z","steps":["trace[2005433210] 'agreement among raft nodes before linearized reading' (duration: 127.508625ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:06:23.755941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.296082ms","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-06-16T04:06:23.756066Z","caller":"traceutil/trace.go:171","msg":"trace[1372835313] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5438; }","duration":"109.480175ms","start":"2026-06-16T04:06:23.646558Z","end":"2026-06-16T04:06:23.756039Z","steps":["trace[1372835313] 'agreement among raft nodes before linearized reading' (duration: 108.506036ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:07:40.088348Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3026} {"level":"info","ts":"2026-06-16T04:07:40.169052Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3026,"took":"79.151394ms","hash":1243406940,"current-db-size-bytes":58789888,"current-db-size":"59 MB","current-db-size-in-use-bytes":31944704,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-16T04:07:40.169119Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1243406940,"revision":3026,"compact-revision":-1} {"level":"warn","ts":"2026-06-16T04:12:35.690172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.749295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T04:12:35.690291Z","caller":"traceutil/trace.go:171","msg":"trace[851290105] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:8300; }","duration":"156.930699ms","start":"2026-06-16T04:12:35.533329Z","end":"2026-06-16T04:12:35.69026Z","steps":["trace[851290105] 'count revisions from in-memory index tree' (duration: 156.570442ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:12:35.690453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.271725ms","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-06-16T04:12:35.690556Z","caller":"traceutil/trace.go:171","msg":"trace[1508685646] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8300; }","duration":"118.419718ms","start":"2026-06-16T04:12:35.572115Z","end":"2026-06-16T04:12:35.690535Z","steps":["trace[1508685646] 'range keys from in-memory index tree' (duration: 118.105852ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:12:40.094049Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6137} {"level":"info","ts":"2026-06-16T04:12:40.187055Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6137,"took":"90.908442ms","hash":279061700,"current-db-size-bytes":58789888,"current-db-size":"59 MB","current-db-size-in-use-bytes":23429120,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-06-16T04:12:40.187206Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":279061700,"revision":6137,"compact-revision":3026} {"level":"info","ts":"2026-06-16T04:12:46.60613Z","caller":"traceutil/trace.go:171","msg":"trace[1409245089] linearizableReadLoop","detail":"{readStateIndex:8648; appliedIndex:8647; }","duration":"198.241274ms","start":"2026-06-16T04:12:46.407854Z","end":"2026-06-16T04:12:46.606095Z","steps":["trace[1409245089] 'read index received' (duration: 102.968237ms)","trace[1409245089] 'applied index is now lower than readState.Index' (duration: 95.271357ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:12:46.606197Z","caller":"traceutil/trace.go:171","msg":"trace[2060672024] transaction","detail":"{read_only:false; response_revision:8357; number_of_response:1; }","duration":"207.675278ms","start":"2026-06-16T04:12:46.398492Z","end":"2026-06-16T04:12:46.606167Z","steps":["trace[2060672024] 'process raft request' (duration: 112.417591ms)","trace[2060672024] 'compare' (duration: 94.996172ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:12:46.606318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.421538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T04:12:46.606427Z","caller":"traceutil/trace.go:171","msg":"trace[1530304639] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:8357; }","duration":"198.56233ms","start":"2026-06-16T04:12:46.407829Z","end":"2026-06-16T04:12:46.606391Z","steps":["trace[1530304639] 'agreement among raft nodes before linearized reading' (duration: 198.375417ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:13:16.648897Z","caller":"traceutil/trace.go:171","msg":"trace[1187684495] transaction","detail":"{read_only:false; response_revision:8572; number_of_response:1; }","duration":"111.233798ms","start":"2026-06-16T04:13:16.537637Z","end":"2026-06-16T04:13:16.648871Z","steps":["trace[1187684495] 'process raft request' (duration: 110.993024ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:13:31.102815Z","caller":"traceutil/trace.go:171","msg":"trace[719741997] linearizableReadLoop","detail":"{readStateIndex:9002; appliedIndex:9001; }","duration":"234.159603ms","start":"2026-06-16T04:13:30.868625Z","end":"2026-06-16T04:13:31.102785Z","steps":["trace[719741997] 'read index received' (duration: 221.941756ms)","trace[719741997] 'applied index is now lower than readState.Index' (duration: 12.216357ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:13:31.102977Z","caller":"traceutil/trace.go:171","msg":"trace[1000669803] transaction","detail":"{read_only:false; response_revision:8695; number_of_response:1; }","duration":"237.166462ms","start":"2026-06-16T04:13:30.865743Z","end":"2026-06-16T04:13:31.10291Z","steps":["trace[1000669803] 'process raft request' (duration: 224.908213ms)","trace[1000669803] 'compare' (duration: 11.633227ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:13:31.110603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.932585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-config-override\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-06-16T04:13:31.110869Z","caller":"traceutil/trace.go:171","msg":"trace[1398581260] range","detail":"{range_begin:/registry/configmaps/openstack/rook-config-override; range_end:; response_count:1; response_revision:8695; }","duration":"242.18071ms","start":"2026-06-16T04:13:30.868587Z","end":"2026-06-16T04:13:31.110768Z","steps":["trace[1398581260] 'agreement among raft nodes before linearized reading' (duration: 234.309296ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:14:05.971022Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002488.wal"} {"level":"warn","ts":"2026-06-16T04:14:41.655029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.353195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-16T04:14:41.655125Z","caller":"traceutil/trace.go:171","msg":"trace[1319393497] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9412; }","duration":"100.502118ms","start":"2026-06-16T04:14:41.554604Z","end":"2026-06-16T04:14:41.655107Z","steps":["trace[1319393497] 'range keys from in-memory index tree' (duration: 100.162241ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:14:41.797475Z","caller":"traceutil/trace.go:171","msg":"trace[110997426] transaction","detail":"{read_only:false; response_revision:9415; number_of_response:1; }","duration":"138.467825ms","start":"2026-06-16T04:14:41.65898Z","end":"2026-06-16T04:14:41.797448Z","steps":["trace[110997426] 'process raft request' (duration: 138.364623ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:14:41.797583Z","caller":"traceutil/trace.go:171","msg":"trace[1984180991] linearizableReadLoop","detail":"{readStateIndex:9741; appliedIndex:9738; }","duration":"110.093347ms","start":"2026-06-16T04:14:41.687481Z","end":"2026-06-16T04:14:41.797575Z","steps":["trace[1984180991] 'read index received' (duration: 48.565666ms)","trace[1984180991] 'applied index is now lower than readState.Index' (duration: 61.526171ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:14:41.797674Z","caller":"traceutil/trace.go:171","msg":"trace[65251271] transaction","detail":"{read_only:false; response_revision:9416; number_of_response:1; }","duration":"109.586266ms","start":"2026-06-16T04:14:41.688063Z","end":"2026-06-16T04:14:41.797649Z","steps":["trace[65251271] 'process raft request' (duration: 109.336611ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:14:41.797482Z","caller":"traceutil/trace.go:171","msg":"trace[2090038278] transaction","detail":"{read_only:false; response_revision:9414; number_of_response:1; }","duration":"138.669758ms","start":"2026-06-16T04:14:41.658778Z","end":"2026-06-16T04:14:41.797448Z","steps":["trace[2090038278] 'process raft request' (duration: 130.360634ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:14:41.798144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.649287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-26hd2\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-06-16T04:14:41.798187Z","caller":"traceutil/trace.go:171","msg":"trace[1024442423] range","detail":"{range_begin:/registry/pods/openstack/keepalived-26hd2; range_end:; response_count:1; response_revision:9416; }","duration":"110.742279ms","start":"2026-06-16T04:14:41.687432Z","end":"2026-06-16T04:14:41.798175Z","steps":["trace[1024442423] 'agreement among raft nodes before linearized reading' (duration: 110.226079ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:15:15.820441Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"572db18442eba1c2","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-16T04:15:15.828811Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-16T04:15:15.828905Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-16T04:17:40.10011Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8322} {"level":"info","ts":"2026-06-16T04:17:40.177597Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8322,"took":"75.123735ms","hash":3102817247,"current-db-size-bytes":58789888,"current-db-size":"59 MB","current-db-size-in-use-bytes":26599424,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-16T04:17:40.177667Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3102817247,"revision":8322,"compact-revision":6137} {"level":"info","ts":"2026-06-16T04:22:40.10676Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10816} {"level":"info","ts":"2026-06-16T04:22:40.186434Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10816,"took":"77.235232ms","hash":3169000787,"current-db-size-bytes":58789888,"current-db-size":"59 MB","current-db-size-in-use-bytes":27488256,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-16T04:22:40.186514Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3169000787,"revision":10816,"compact-revision":8322} {"level":"info","ts":"2026-06-16T04:26:44.622799Z","caller":"traceutil/trace.go:171","msg":"trace[822331128] linearizableReadLoop","detail":"{readStateIndex:15647; appliedIndex:15646; }","duration":"179.582071ms","start":"2026-06-16T04:26:44.443191Z","end":"2026-06-16T04:26:44.622773Z","steps":["trace[822331128] 'read index received' (duration: 178.501509ms)","trace[822331128] 'applied index is now lower than readState.Index' (duration: 1.079502ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:26:44.623189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.736476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T04:26:44.623252Z","caller":"traceutil/trace.go:171","msg":"trace[1389337829] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15138; }","duration":"184.836588ms","start":"2026-06-16T04:26:44.4384Z","end":"2026-06-16T04:26:44.623237Z","steps":["trace[1389337829] 'agreement among raft nodes before linearized reading' (duration: 184.690235ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:26:44.623292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.49278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T04:26:44.623428Z","caller":"traceutil/trace.go:171","msg":"trace[1534168456] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15138; }","duration":"184.707164ms","start":"2026-06-16T04:26:44.438694Z","end":"2026-06-16T04:26:44.623401Z","steps":["trace[1534168456] 'agreement among raft nodes before linearized reading' (duration: 184.47628ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:26:44.623428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.690046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-06-16T04:26:44.623528Z","caller":"traceutil/trace.go:171","msg":"trace[1211457876] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15138; }","duration":"192.84509ms","start":"2026-06-16T04:26:44.430656Z","end":"2026-06-16T04:26:44.623501Z","steps":["trace[1211457876] 'agreement among raft nodes before linearized reading' (duration: 192.537513ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:26:44.623584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.015423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T04:26:44.623661Z","caller":"traceutil/trace.go:171","msg":"trace[373181532] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15138; }","duration":"185.111054ms","start":"2026-06-16T04:26:44.438538Z","end":"2026-06-16T04:26:44.623649Z","steps":["trace[373181532] 'agreement among raft nodes before linearized reading' (duration: 184.981772ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:26:44.623751Z","caller":"traceutil/trace.go:171","msg":"trace[2077828245] transaction","detail":"{read_only:false; response_revision:15138; number_of_response:1; }","duration":"192.245179ms","start":"2026-06-16T04:26:44.431485Z","end":"2026-06-16T04:26:44.62373Z","steps":["trace[2077828245] 'process raft request' (duration: 190.126436ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:26:44.623451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.786899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T04:26:44.623843Z","caller":"traceutil/trace.go:171","msg":"trace[643931681] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15138; }","duration":"196.26949ms","start":"2026-06-16T04:26:44.427561Z","end":"2026-06-16T04:26:44.623831Z","steps":["trace[643931681] 'agreement among raft nodes before linearized reading' (duration: 195.654637ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:26:44.625677Z","caller":"traceutil/trace.go:171","msg":"trace[1144306339] transaction","detail":"{read_only:false; response_revision:15139; number_of_response:1; }","duration":"175.191962ms","start":"2026-06-16T04:26:44.450457Z","end":"2026-06-16T04:26:44.625649Z","steps":["trace[1144306339] 'process raft request' (duration: 174.881186ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:26:44.626345Z","caller":"traceutil/trace.go:171","msg":"trace[1007339674] transaction","detail":"{read_only:false; response_revision:15140; number_of_response:1; }","duration":"171.04737ms","start":"2026-06-16T04:26:44.455273Z","end":"2026-06-16T04:26:44.62632Z","steps":["trace[1007339674] 'process raft request' (duration: 170.189941ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:26:44.62779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.091771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-06-16T04:26:44.627852Z","caller":"traceutil/trace.go:171","msg":"trace[1706716654] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15140; }","duration":"177.175533ms","start":"2026-06-16T04:26:44.450666Z","end":"2026-06-16T04:26:44.627841Z","steps":["trace[1706716654] 'agreement among raft nodes before linearized reading' (duration: 175.110121ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:27:40.113681Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12930} {"level":"info","ts":"2026-06-16T04:27:40.18913Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12930,"took":"71.444249ms","hash":3704498665,"current-db-size-bytes":58789888,"current-db-size":"59 MB","current-db-size-in-use-bytes":30863360,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-16T04:27:40.189288Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3704498665,"revision":12930,"compact-revision":10816} {"level":"info","ts":"2026-06-16T04:32:34.029224Z","caller":"traceutil/trace.go:171","msg":"trace[1532935737] linearizableReadLoop","detail":"{readStateIndex:18362; appliedIndex:18361; }","duration":"205.96651ms","start":"2026-06-16T04:32:33.823221Z","end":"2026-06-16T04:32:34.029187Z","steps":["trace[1532935737] 'read index received' (duration: 205.913389ms)","trace[1532935737] 'applied index is now lower than readState.Index' (duration: 51.321µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:32:34.029368Z","caller":"traceutil/trace.go:171","msg":"trace[1611107526] transaction","detail":"{read_only:false; response_revision:17768; number_of_response:1; }","duration":"207.988991ms","start":"2026-06-16T04:32:33.821314Z","end":"2026-06-16T04:32:34.029303Z","steps":["trace[1611107526] 'process raft request' (duration: 207.684375ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:32:34.029527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.285447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-16T04:32:34.029655Z","caller":"traceutil/trace.go:171","msg":"trace[118299754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17768; }","duration":"206.490131ms","start":"2026-06-16T04:32:33.823136Z","end":"2026-06-16T04:32:34.029626Z","steps":["trace[118299754] 'agreement among raft nodes before linearized reading' (duration: 206.184524ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:32:40.136035Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15486} {"level":"info","ts":"2026-06-16T04:32:40.23881Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15486,"took":"96.769939ms","hash":371516637,"current-db-size-bytes":58789888,"current-db-size":"59 MB","current-db-size-in-use-bytes":32403456,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-16T04:32:40.238904Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":371516637,"revision":15486,"compact-revision":12930} {"level":"info","ts":"2026-06-16T04:35:52.99189Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"572db18442eba1c2","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-16T04:35:53.000445Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-16T04:35:53.001242Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-16T04:36:43.29301Z","caller":"traceutil/trace.go:171","msg":"trace[384618368] transaction","detail":"{read_only:false; response_revision:19772; number_of_response:1; }","duration":"104.894863ms","start":"2026-06-16T04:36:43.188056Z","end":"2026-06-16T04:36:43.292951Z","steps":["trace[384618368] 'process raft request' (duration: 95.123355ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:36:43.818794Z","caller":"traceutil/trace.go:171","msg":"trace[1760275562] transaction","detail":"{read_only:false; response_revision:19777; number_of_response:1; }","duration":"145.56165ms","start":"2026-06-16T04:36:43.673177Z","end":"2026-06-16T04:36:43.818739Z","steps":["trace[1760275562] 'process raft request' (duration: 145.317395ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:37:40.142116Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17846} {"level":"info","ts":"2026-06-16T04:37:40.241376Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17846,"took":"96.375242ms","hash":3344531072,"current-db-size-bytes":58789888,"current-db-size":"59 MB","current-db-size-in-use-bytes":34516992,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-16T04:37:40.241443Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3344531072,"revision":17846,"compact-revision":15486} {"level":"info","ts":"2026-06-16T04:38:04.462149Z","caller":"traceutil/trace.go:171","msg":"trace[1679731123] transaction","detail":"{read_only:false; response_revision:20314; number_of_response:1; }","duration":"131.441066ms","start":"2026-06-16T04:38:04.330687Z","end":"2026-06-16T04:38:04.462129Z","steps":["trace[1679731123] 'process raft request' (duration: 131.306314ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:38:04.505994Z","caller":"traceutil/trace.go:171","msg":"trace[828033779] transaction","detail":"{read_only:false; response_revision:20315; number_of_response:1; }","duration":"173.79949ms","start":"2026-06-16T04:38:04.332167Z","end":"2026-06-16T04:38:04.505966Z","steps":["trace[828033779] 'process raft request' (duration: 173.587276ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:38:20.813813Z","caller":"traceutil/trace.go:171","msg":"trace[1222603289] linearizableReadLoop","detail":"{readStateIndex:21079; appliedIndex:21078; }","duration":"241.375181ms","start":"2026-06-16T04:38:20.572412Z","end":"2026-06-16T04:38:20.813787Z","steps":["trace[1222603289] 'read index received' (duration: 241.111236ms)","trace[1222603289] 'applied index is now lower than readState.Index' (duration: 263.055µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:38:20.813926Z","caller":"traceutil/trace.go:171","msg":"trace[280598011] transaction","detail":"{read_only:false; response_revision:20397; number_of_response:1; }","duration":"268.323974ms","start":"2026-06-16T04:38:20.545555Z","end":"2026-06-16T04:38:20.813879Z","steps":["trace[280598011] 'process raft request' (duration: 268.037108ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:38:20.814063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.646506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-16T04:38:20.814145Z","caller":"traceutil/trace.go:171","msg":"trace[930443411] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20397; }","duration":"241.773199ms","start":"2026-06-16T04:38:20.572344Z","end":"2026-06-16T04:38:20.814117Z","steps":["trace[930443411] 'agreement among raft nodes before linearized reading' (duration: 241.543775ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:38:20.979326Z","caller":"traceutil/trace.go:171","msg":"trace[901366416] transaction","detail":"{read_only:false; response_revision:20398; number_of_response:1; }","duration":"161.36144ms","start":"2026-06-16T04:38:20.817932Z","end":"2026-06-16T04:38:20.979294Z","steps":["trace[901366416] 'process raft request' (duration: 106.637237ms)","trace[901366416] 'compare' (duration: 54.58826ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:38:21.216276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.293749ms","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-06-16T04:38:21.216367Z","caller":"traceutil/trace.go:171","msg":"trace[408820268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20398; }","duration":"105.407242ms","start":"2026-06-16T04:38:21.110939Z","end":"2026-06-16T04:38:21.216346Z","steps":["trace[408820268] 'range keys from in-memory index tree' (duration: 105.037385ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:38:21.3822Z","caller":"traceutil/trace.go:171","msg":"trace[1753149948] transaction","detail":"{read_only:false; response_revision:20400; number_of_response:1; }","duration":"161.742947ms","start":"2026-06-16T04:38:21.220416Z","end":"2026-06-16T04:38:21.382159Z","steps":["trace[1753149948] 'process raft request' (duration: 149.531861ms)","trace[1753149948] 'compare' (duration: 11.506881ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:38:21.38184Z","caller":"traceutil/trace.go:171","msg":"trace[852667553] linearizableReadLoop","detail":"{readStateIndex:21082; appliedIndex:21080; }","duration":"157.626893ms","start":"2026-06-16T04:38:21.224188Z","end":"2026-06-16T04:38:21.381815Z","steps":["trace[852667553] 'read index received' (duration: 8.677444ms)","trace[852667553] 'applied index is now lower than readState.Index' (duration: 148.948219ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:38:21.38256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.35837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-16T04:38:21.382613Z","caller":"traceutil/trace.go:171","msg":"trace[1499624101] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20400; }","duration":"158.444961ms","start":"2026-06-16T04:38:21.224154Z","end":"2026-06-16T04:38:21.382599Z","steps":["trace[1499624101] 'agreement among raft nodes before linearized reading' (duration: 158.250917ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:42:14.528926Z","caller":"traceutil/trace.go:171","msg":"trace[1943905114] transaction","detail":"{read_only:false; response_revision:22835; number_of_response:1; }","duration":"285.82807ms","start":"2026-06-16T04:42:14.243078Z","end":"2026-06-16T04:42:14.528906Z","steps":["trace[1943905114] 'process raft request' (duration: 285.699717ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:42:14.577711Z","caller":"traceutil/trace.go:171","msg":"trace[1359607438] transaction","detail":"{read_only:false; response_revision:22836; number_of_response:1; }","duration":"287.683008ms","start":"2026-06-16T04:42:14.290011Z","end":"2026-06-16T04:42:14.577694Z","steps":["trace[1359607438] 'process raft request' (duration: 287.557705ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:42:40.152497Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20189} {"level":"info","ts":"2026-06-16T04:42:40.269974Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20189,"took":"113.820394ms","hash":1073194167,"current-db-size-bytes":58789888,"current-db-size":"59 MB","current-db-size-in-use-bytes":42377216,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-06-16T04:42:40.270058Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1073194167,"revision":20189,"compact-revision":17846} {"level":"info","ts":"2026-06-16T04:45:51.598439Z","caller":"traceutil/trace.go:171","msg":"trace[1956859643] transaction","detail":"{read_only:false; response_revision:25023; number_of_response:1; }","duration":"208.774327ms","start":"2026-06-16T04:45:51.389631Z","end":"2026-06-16T04:45:51.598405Z","steps":["trace[1956859643] 'process raft request' (duration: 185.045579ms)","trace[1956859643] 'compare' (duration: 23.588555ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:45:51.598761Z","caller":"traceutil/trace.go:171","msg":"trace[2040711102] transaction","detail":"{read_only:false; response_revision:25025; number_of_response:1; }","duration":"204.319747ms","start":"2026-06-16T04:45:51.39439Z","end":"2026-06-16T04:45:51.59871Z","steps":["trace[2040711102] 'process raft request' (duration: 204.126323ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:45:51.598556Z","caller":"traceutil/trace.go:171","msg":"trace[1915577894] transaction","detail":"{read_only:false; response_revision:25024; number_of_response:1; }","duration":"204.361028ms","start":"2026-06-16T04:45:51.394186Z","end":"2026-06-16T04:45:51.598547Z","steps":["trace[1915577894] 'process raft request' (duration: 204.193355ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:45:51.598694Z","caller":"traceutil/trace.go:171","msg":"trace[43695016] linearizableReadLoop","detail":"{readStateIndex:25835; appliedIndex:25831; }","duration":"118.797455ms","start":"2026-06-16T04:45:51.47989Z","end":"2026-06-16T04:45:51.598688Z","steps":["trace[43695016] 'read index received' (duration: 94.810141ms)","trace[43695016] 'applied index is now lower than readState.Index' (duration: 23.986664ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:45:51.598739Z","caller":"traceutil/trace.go:171","msg":"trace[962628837] transaction","detail":"{read_only:false; response_revision:25026; number_of_response:1; }","duration":"202.753826ms","start":"2026-06-16T04:45:51.395951Z","end":"2026-06-16T04:45:51.598705Z","steps":["trace[962628837] 'process raft request' (duration: 202.671584ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:45:51.598779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.881886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-16T04:45:51.599057Z","caller":"traceutil/trace.go:171","msg":"trace[2002206305] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:25026; }","duration":"119.183772ms","start":"2026-06-16T04:45:51.479839Z","end":"2026-06-16T04:45:51.599023Z","steps":["trace[2002206305] 'agreement among raft nodes before linearized reading' (duration: 118.865426ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:45:51.777292Z","caller":"traceutil/trace.go:171","msg":"trace[96372765] transaction","detail":"{read_only:false; response_revision:25027; number_of_response:1; }","duration":"254.112551ms","start":"2026-06-16T04:45:51.523147Z","end":"2026-06-16T04:45:51.777259Z","steps":["trace[96372765] 'process raft request' (duration: 236.678179ms)","trace[96372765] 'compare' (duration: 16.979442ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:45:51.777727Z","caller":"traceutil/trace.go:171","msg":"trace[1856693693] transaction","detail":"{read_only:false; response_revision:25028; number_of_response:1; }","duration":"174.910685ms","start":"2026-06-16T04:45:51.602803Z","end":"2026-06-16T04:45:51.777714Z","steps":["trace[1856693693] 'process raft request' (duration: 174.245131ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:45:51.778117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.004792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T04:45:51.778169Z","caller":"traceutil/trace.go:171","msg":"trace[1997966244] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:25028; }","duration":"145.120624ms","start":"2026-06-16T04:45:51.633035Z","end":"2026-06-16T04:45:51.778155Z","steps":["trace[1997966244] 'agreement among raft nodes before linearized reading' (duration: 145.005732ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:45:51.777723Z","caller":"traceutil/trace.go:171","msg":"trace[1736877759] linearizableReadLoop","detail":"{readStateIndex:25837; appliedIndex:25835; }","duration":"144.268407ms","start":"2026-06-16T04:45:51.633092Z","end":"2026-06-16T04:45:51.77736Z","steps":["trace[1736877759] 'read index received' (duration: 126.889666ms)","trace[1736877759] 'applied index is now lower than readState.Index' (duration: 17.376601ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:45:51.778983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.117191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-16T04:45:51.779061Z","caller":"traceutil/trace.go:171","msg":"trace[319881754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25028; }","duration":"130.265665ms","start":"2026-06-16T04:45:51.648776Z","end":"2026-06-16T04:45:51.779042Z","steps":["trace[319881754] 'agreement among raft nodes before linearized reading' (duration: 129.932098ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:45:52.062182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.063312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T04:45:52.062351Z","caller":"traceutil/trace.go:171","msg":"trace[107063658] transaction","detail":"{read_only:false; response_revision:25029; number_of_response:1; }","duration":"279.574593ms","start":"2026-06-16T04:45:51.782754Z","end":"2026-06-16T04:45:52.062328Z","steps":["trace[107063658] 'process raft request' (duration: 139.283636ms)","trace[107063658] 'compare' (duration: 139.912559ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:45:52.062601Z","caller":"traceutil/trace.go:171","msg":"trace[723437867] linearizableReadLoop","detail":"{readStateIndex:25840; appliedIndex:25837; }","duration":"202.064292ms","start":"2026-06-16T04:45:51.860519Z","end":"2026-06-16T04:45:52.062583Z","steps":["trace[723437867] 'read index received' (duration: 61.738795ms)","trace[723437867] 'applied index is now lower than readState.Index' (duration: 140.324497ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:45:52.062796Z","caller":"traceutil/trace.go:171","msg":"trace[248558526] transaction","detail":"{read_only:false; response_revision:25030; number_of_response:1; }","duration":"279.116874ms","start":"2026-06-16T04:45:51.78367Z","end":"2026-06-16T04:45:52.062787Z","steps":["trace[248558526] 'process raft request' (duration: 278.680795ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:45:52.062839Z","caller":"traceutil/trace.go:171","msg":"trace[1082681532] transaction","detail":"{read_only:false; response_revision:25031; number_of_response:1; }","duration":"278.939331ms","start":"2026-06-16T04:45:51.783885Z","end":"2026-06-16T04:45:52.062824Z","steps":["trace[1082681532] 'process raft request' (duration: 278.642235ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:45:52.063154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.636254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-06-16T04:45:52.063242Z","caller":"traceutil/trace.go:171","msg":"trace[1294212184] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:25031; }","duration":"202.762846ms","start":"2026-06-16T04:45:51.86043Z","end":"2026-06-16T04:45:52.063193Z","steps":["trace[1294212184] 'agreement among raft nodes before linearized reading' (duration: 202.216394ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:45:52.409514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.487486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-16T04:45:52.409538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.596717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T04:45:52.409602Z","caller":"traceutil/trace.go:171","msg":"trace[1705939297] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:25031; }","duration":"112.603748ms","start":"2026-06-16T04:45:52.29698Z","end":"2026-06-16T04:45:52.409584Z","steps":["trace[1705939297] 'count revisions from in-memory index tree' (duration: 112.401635ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:45:52.409625Z","caller":"traceutil/trace.go:171","msg":"trace[1091854556] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:25031; }","duration":"101.71835ms","start":"2026-06-16T04:45:52.30788Z","end":"2026-06-16T04:45:52.409599Z","steps":["trace[1091854556] 'count revisions from in-memory index tree' (duration: 101.493145ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:45:52.644301Z","caller":"traceutil/trace.go:171","msg":"trace[1329110543] linearizableReadLoop","detail":"{readStateIndex:25841; appliedIndex:25840; }","duration":"158.203597ms","start":"2026-06-16T04:45:52.486072Z","end":"2026-06-16T04:45:52.644276Z","steps":["trace[1329110543] 'read index received' (duration: 157.995133ms)","trace[1329110543] 'applied index is now lower than readState.Index' (duration: 207.544µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:45:52.644425Z","caller":"traceutil/trace.go:171","msg":"trace[845307066] transaction","detail":"{read_only:false; response_revision:25032; number_of_response:1; }","duration":"176.065648ms","start":"2026-06-16T04:45:52.468337Z","end":"2026-06-16T04:45:52.644402Z","steps":["trace[845307066] 'process raft request' (duration: 175.766342ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:45:52.644476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.376941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T04:45:52.644535Z","caller":"traceutil/trace.go:171","msg":"trace[112935108] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25032; }","duration":"158.478712ms","start":"2026-06-16T04:45:52.48604Z","end":"2026-06-16T04:45:52.644518Z","steps":["trace[112935108] 'agreement among raft nodes before linearized reading' (duration: 158.333849ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:45:55.037375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.707556ms","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-06-16T04:45:55.037602Z","caller":"traceutil/trace.go:171","msg":"trace[1562904094] 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:25047; }","duration":"211.845178ms","start":"2026-06-16T04:45:54.825584Z","end":"2026-06-16T04:45:55.03743Z","steps":["trace[1562904094] 'range keys from in-memory index tree' (duration: 211.595653ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:45:55.037681Z","caller":"traceutil/trace.go:171","msg":"trace[11444143] transaction","detail":"{read_only:false; response_revision:25048; number_of_response:1; }","duration":"116.917966ms","start":"2026-06-16T04:45:54.920745Z","end":"2026-06-16T04:45:55.037663Z","steps":["trace[11444143] 'process raft request' (duration: 79.862149ms)","trace[11444143] 'compare' (duration: 36.726951ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:45:55.390243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.903168ms","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-06-16T04:45:55.390372Z","caller":"traceutil/trace.go:171","msg":"trace[718833152] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25048; }","duration":"136.109521ms","start":"2026-06-16T04:45:55.254232Z","end":"2026-06-16T04:45:55.390341Z","steps":["trace[718833152] 'range keys from in-memory index tree' (duration: 135.578441ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:45:55.52844Z","caller":"traceutil/trace.go:171","msg":"trace[1126057143] transaction","detail":"{read_only:false; response_revision:25049; number_of_response:1; }","duration":"132.173024ms","start":"2026-06-16T04:45:55.396224Z","end":"2026-06-16T04:45:55.528397Z","steps":["trace[1126057143] 'process raft request' (duration: 131.930788ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:45:55.867358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.922479ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T04:45:55.867468Z","caller":"traceutil/trace.go:171","msg":"trace[2089851318] transaction","detail":"{read_only:false; response_revision:25051; number_of_response:1; }","duration":"254.624401ms","start":"2026-06-16T04:45:55.612824Z","end":"2026-06-16T04:45:55.867449Z","steps":["trace[2089851318] 'process raft request' (duration: 119.524528ms)","trace[2089851318] 'compare' (duration: 134.690414ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:45:56.220741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.190994ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T04:45:56.220953Z","caller":"traceutil/trace.go:171","msg":"trace[1444283654] linearizableReadLoop","detail":"{readStateIndex:25864; appliedIndex:25860; }","duration":"546.842409ms","start":"2026-06-16T04:45:55.674098Z","end":"2026-06-16T04:45:56.22094Z","steps":["trace[1444283654] 'read index received' (duration: 58.355505ms)","trace[1444283654] 'applied index is now lower than readState.Index' (duration: 488.486134ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:45:56.221016Z","caller":"traceutil/trace.go:171","msg":"trace[1194339787] transaction","detail":"{read_only:false; response_revision:25054; number_of_response:1; }","duration":"607.241126ms","start":"2026-06-16T04:45:55.613767Z","end":"2026-06-16T04:45:56.221008Z","steps":["trace[1194339787] 'process raft request' (duration: 607.107634ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:45:56.220985Z","caller":"traceutil/trace.go:171","msg":"trace[753356408] transaction","detail":"{read_only:false; response_revision:25053; number_of_response:1; }","duration":"607.358338ms","start":"2026-06-16T04:45:55.613589Z","end":"2026-06-16T04:45:56.220948Z","steps":["trace[753356408] 'process raft request' (duration: 607.229965ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:45:56.221065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:45:55.613738Z","time spent":"607.302008ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T04:45:56.221122Z","caller":"traceutil/trace.go:171","msg":"trace[317591334] transaction","detail":"{read_only:false; response_revision:25052; number_of_response:1; }","duration":"607.709516ms","start":"2026-06-16T04:45:55.613385Z","end":"2026-06-16T04:45:56.221094Z","steps":["trace[317591334] 'process raft request' (duration: 476.800718ms)","trace[317591334] 'compare' (duration: 130.097822ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:45:56.221145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:45:55.613571Z","time spent":"607.486151ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T04:45:56.221244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:45:55.613361Z","time spent":"607.818038ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T04:45:56.221419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.329168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-16T04:45:56.221425Z","caller":"traceutil/trace.go:171","msg":"trace[1080150830] transaction","detail":"{read_only:false; response_revision:25056; number_of_response:1; }","duration":"429.357851ms","start":"2026-06-16T04:45:55.792056Z","end":"2026-06-16T04:45:56.221414Z","steps":["trace[1080150830] 'process raft request' (duration: 429.259089ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:45:56.221448Z","caller":"traceutil/trace.go:171","msg":"trace[902354533] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25055; }","duration":"547.441911ms","start":"2026-06-16T04:45:55.673999Z","end":"2026-06-16T04:45:56.221441Z","steps":["trace[902354533] 'agreement among raft nodes before linearized reading' (duration: 547.200736ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:45:56.221468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:45:55.673979Z","time spent":"547.485111ms","remote":"127.0.0.1:46520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-16T04:45:56.22148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:45:55.792047Z","time spent":"429.403462ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T04:45:56.221496Z","caller":"traceutil/trace.go:171","msg":"trace[713069426] transaction","detail":"{read_only:false; response_revision:25055; number_of_response:1; }","duration":"433.390603ms","start":"2026-06-16T04:45:55.788083Z","end":"2026-06-16T04:45:56.221474Z","steps":["trace[713069426] 'process raft request' (duration: 432.831502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:45:56.2216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:45:55.788061Z","time spent":"433.489905ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T04:45:56.501825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.060168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T04:45:56.501963Z","caller":"traceutil/trace.go:171","msg":"trace[852173597] linearizableReadLoop","detail":"{readStateIndex:25867; appliedIndex:25866; }","duration":"280.957851ms","start":"2026-06-16T04:45:56.220984Z","end":"2026-06-16T04:45:56.501941Z","steps":["trace[852173597] 'read index received' (duration: 99.593586ms)","trace[852173597] 'applied index is now lower than readState.Index' (duration: 181.362315ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:45:56.502003Z","caller":"traceutil/trace.go:171","msg":"trace[1895484497] transaction","detail":"{read_only:false; response_revision:25057; number_of_response:1; }","duration":"356.31346ms","start":"2026-06-16T04:45:56.145664Z","end":"2026-06-16T04:45:56.501977Z","steps":["trace[1895484497] 'process raft request' (duration: 175.017316ms)","trace[1895484497] 'compare' (duration: 180.879605ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:45:56.502065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.540625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-16T04:45:56.5021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.3838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-06-16T04:45:56.50212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:45:56.145644Z","time spent":"356.417482ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T04:45:56.502146Z","caller":"traceutil/trace.go:171","msg":"trace[1593089337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25057; }","duration":"294.670708ms","start":"2026-06-16T04:45:56.207459Z","end":"2026-06-16T04:45:56.502129Z","steps":["trace[1593089337] 'agreement among raft nodes before linearized reading' (duration: 294.538095ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:45:56.502159Z","caller":"traceutil/trace.go:171","msg":"trace[415688088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25057; }","duration":"424.474053ms","start":"2026-06-16T04:45:56.077672Z","end":"2026-06-16T04:45:56.502146Z","steps":["trace[415688088] 'agreement among raft nodes before linearized reading' (duration: 424.288699ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:45:56.502195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:45:56.077662Z","time spent":"424.523894ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-16T04:45:56.502201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.82563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-16T04:45:56.502326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.949963ms","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-06-16T04:45:56.502336Z","caller":"traceutil/trace.go:171","msg":"trace[4413151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25057; }","duration":"424.974292ms","start":"2026-06-16T04:45:56.077338Z","end":"2026-06-16T04:45:56.502313Z","steps":["trace[4413151] 'agreement among raft nodes before linearized reading' (duration: 424.716377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:45:56.502387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.133685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-06-16T04:45:56.502398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:45:56.077327Z","time spent":"425.050773ms","remote":"127.0.0.1:46610","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":"info","ts":"2026-06-16T04:45:56.50241Z","caller":"traceutil/trace.go:171","msg":"trace[416496184] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25057; }","duration":"277.194566ms","start":"2026-06-16T04:45:56.225209Z","end":"2026-06-16T04:45:56.502403Z","steps":["trace[416496184] 'agreement among raft nodes before linearized reading' (duration: 277.109244ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:45:56.502377Z","caller":"traceutil/trace.go:171","msg":"trace[1651957835] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25057; }","duration":"426.044724ms","start":"2026-06-16T04:45:56.076316Z","end":"2026-06-16T04:45:56.502361Z","steps":["trace[1651957835] 'agreement among raft nodes before linearized reading' (duration: 425.914722ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:45:56.502422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.143587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"warn","ts":"2026-06-16T04:45:56.502502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:45:56.076295Z","time spent":"426.165337ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-16T04:45:56.502524Z","caller":"traceutil/trace.go:171","msg":"trace[452214648] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:25057; }","duration":"628.28627ms","start":"2026-06-16T04:45:55.874221Z","end":"2026-06-16T04:45:56.502507Z","steps":["trace[452214648] 'agreement among raft nodes before linearized reading' (duration: 628.037045ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:45:56.502558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:45:55.874199Z","time spent":"628.349811ms","remote":"127.0.0.1:46502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-06-16T04:45:56.502606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.562769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-06-16T04:45:56.50269Z","caller":"traceutil/trace.go:171","msg":"trace[697089670] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25057; }","duration":"524.691132ms","start":"2026-06-16T04:45:55.977973Z","end":"2026-06-16T04:45:56.502665Z","steps":["trace[697089670] 'agreement among raft nodes before linearized reading' (duration: 524.387086ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:45:56.50276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:45:55.977958Z","time spent":"524.775084ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":585,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-06-16T04:46:00.180942Z","caller":"traceutil/trace.go:171","msg":"trace[547666199] transaction","detail":"{read_only:false; response_revision:25082; number_of_response:1; }","duration":"106.889063ms","start":"2026-06-16T04:46:00.074019Z","end":"2026-06-16T04:46:00.180908Z","steps":["trace[547666199] 'process raft request' (duration: 106.039596ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:00.343432Z","caller":"traceutil/trace.go:171","msg":"trace[316261177] transaction","detail":"{read_only:false; response_revision:25083; number_of_response:1; }","duration":"110.922455ms","start":"2026-06-16T04:46:00.232483Z","end":"2026-06-16T04:46:00.343405Z","steps":["trace[316261177] 'process raft request' (duration: 108.390444ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:00.670565Z","caller":"traceutil/trace.go:171","msg":"trace[1752855508] transaction","detail":"{read_only:false; response_revision:25084; number_of_response:1; }","duration":"435.964584ms","start":"2026-06-16T04:46:00.234525Z","end":"2026-06-16T04:46:00.67049Z","steps":["trace[1752855508] 'process raft request' (duration: 413.018141ms)","trace[1752855508] 'compare' (duration: 22.450373ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:46:00.670931Z","caller":"traceutil/trace.go:171","msg":"trace[1810610415] linearizableReadLoop","detail":"{readStateIndex:25898; appliedIndex:25894; }","duration":"280.001072ms","start":"2026-06-16T04:46:00.390913Z","end":"2026-06-16T04:46:00.670914Z","steps":["trace[1810610415] 'read index received' (duration: 256.56597ms)","trace[1810610415] 'applied index is now lower than readState.Index' (duration: 23.430182ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:46:00.670934Z","caller":"traceutil/trace.go:171","msg":"trace[627135907] transaction","detail":"{read_only:false; response_revision:25085; number_of_response:1; }","duration":"435.573537ms","start":"2026-06-16T04:46:00.235323Z","end":"2026-06-16T04:46:00.670897Z","steps":["trace[627135907] 'process raft request' (duration: 434.921804ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:00.670987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:00.234489Z","time spent":"436.221449ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T04:46:00.671073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.144425ms","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-06-16T04:46:00.671134Z","caller":"traceutil/trace.go:171","msg":"trace[611822086] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25087; }","duration":"280.264967ms","start":"2026-06-16T04:46:00.390856Z","end":"2026-06-16T04:46:00.671121Z","steps":["trace[611822086] 'agreement among raft nodes before linearized reading' (duration: 280.132784ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:00.671136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.720196ms","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-06-16T04:46:00.671149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.9373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-06-16T04:46:00.671119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:00.235297Z","time spent":"435.722509ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T04:46:00.671164Z","caller":"traceutil/trace.go:171","msg":"trace[1296545664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25087; }","duration":"146.770357ms","start":"2026-06-16T04:46:00.524387Z","end":"2026-06-16T04:46:00.671157Z","steps":["trace[1296545664] 'agreement among raft nodes before linearized reading' (duration: 146.658525ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:00.671174Z","caller":"traceutil/trace.go:171","msg":"trace[2035835409] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25087; }","duration":"150.017302ms","start":"2026-06-16T04:46:00.52115Z","end":"2026-06-16T04:46:00.671167Z","steps":["trace[2035835409] 'agreement among raft nodes before linearized reading' (duration: 149.886809ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:00.670996Z","caller":"traceutil/trace.go:171","msg":"trace[180049361] transaction","detail":"{read_only:false; response_revision:25087; number_of_response:1; }","duration":"435.451504ms","start":"2026-06-16T04:46:00.235523Z","end":"2026-06-16T04:46:00.670975Z","steps":["trace[180049361] 'process raft request' (duration: 435.338202ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:00.671242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:00.235507Z","time spent":"435.694579ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T04:46:00.671267Z","caller":"traceutil/trace.go:171","msg":"trace[1401271704] transaction","detail":"{read_only:false; response_revision:25086; number_of_response:1; }","duration":"435.76972ms","start":"2026-06-16T04:46:00.235488Z","end":"2026-06-16T04:46:00.671258Z","steps":["trace[1401271704] 'process raft request' (duration: 435.26109ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:00.671257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.184676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-16T04:46:00.671305Z","caller":"traceutil/trace.go:171","msg":"trace[1071569717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25087; }","duration":"149.299658ms","start":"2026-06-16T04:46:00.521989Z","end":"2026-06-16T04:46:00.671289Z","steps":["trace[1071569717] 'agreement among raft nodes before linearized reading' (duration: 149.142375ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:00.671324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:00.235455Z","time spent":"435.828191ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T04:46:01.101566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.632691ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T04:46:01.10168Z","caller":"traceutil/trace.go:171","msg":"trace[266661975] transaction","detail":"{read_only:false; response_revision:25091; number_of_response:1; }","duration":"339.726806ms","start":"2026-06-16T04:46:00.761926Z","end":"2026-06-16T04:46:01.101652Z","steps":["trace[266661975] 'process raft request' (duration: 142.925951ms)","trace[266661975] 'compare' (duration: 196.436498ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:46:01.101756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:00.761898Z","time spent":"339.819517ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T04:46:01.149492Z","caller":"traceutil/trace.go:171","msg":"trace[1022723079] transaction","detail":"{read_only:false; response_revision:25092; number_of_response:1; }","duration":"251.331874ms","start":"2026-06-16T04:46:00.898138Z","end":"2026-06-16T04:46:01.14947Z","steps":["trace[1022723079] 'process raft request' (duration: 251.202152ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:01.253077Z","caller":"traceutil/trace.go:171","msg":"trace[1880909536] linearizableReadLoop","detail":"{readStateIndex:25904; appliedIndex:25902; }","duration":"120.929796ms","start":"2026-06-16T04:46:01.132113Z","end":"2026-06-16T04:46:01.253043Z","steps":["trace[1880909536] 'read index received' (duration: 17.228757ms)","trace[1880909536] 'applied index is now lower than readState.Index' (duration: 103.698009ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:46:01.253079Z","caller":"traceutil/trace.go:171","msg":"trace[667605520] transaction","detail":"{read_only:false; response_revision:25093; number_of_response:1; }","duration":"198.560741ms","start":"2026-06-16T04:46:01.054487Z","end":"2026-06-16T04:46:01.253047Z","steps":["trace[667605520] 'process raft request' (duration: 195.806026ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:01.253305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.171042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T04:46:01.25349Z","caller":"traceutil/trace.go:171","msg":"trace[40640332] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:25093; }","duration":"121.403846ms","start":"2026-06-16T04:46:01.132061Z","end":"2026-06-16T04:46:01.253465Z","steps":["trace[40640332] 'agreement among raft nodes before linearized reading' (duration: 121.154111ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:01.731465Z","caller":"traceutil/trace.go:171","msg":"trace[1168288989] linearizableReadLoop","detail":"{readStateIndex:25907; appliedIndex:25905; }","duration":"141.948911ms","start":"2026-06-16T04:46:01.589491Z","end":"2026-06-16T04:46:01.731439Z","steps":["trace[1168288989] 'read index received' (duration: 11.179625ms)","trace[1168288989] 'applied index is now lower than readState.Index' (duration: 130.768226ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:46:01.731524Z","caller":"traceutil/trace.go:171","msg":"trace[1776495343] transaction","detail":"{read_only:false; response_revision:25096; number_of_response:1; }","duration":"153.073945ms","start":"2026-06-16T04:46:01.578422Z","end":"2026-06-16T04:46:01.731496Z","steps":["trace[1776495343] 'process raft request' (duration: 151.288579ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:01.731649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.142744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-16T04:46:01.731712Z","caller":"traceutil/trace.go:171","msg":"trace[1551727582] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25096; }","duration":"142.240527ms","start":"2026-06-16T04:46:01.589459Z","end":"2026-06-16T04:46:01.731699Z","steps":["trace[1551727582] 'agreement among raft nodes before linearized reading' (duration: 142.097074ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:02.16903Z","caller":"traceutil/trace.go:171","msg":"trace[1696059628] transaction","detail":"{read_only:false; response_revision:25097; number_of_response:1; }","duration":"283.600214ms","start":"2026-06-16T04:46:01.885391Z","end":"2026-06-16T04:46:02.168991Z","steps":["trace[1696059628] 'process raft request' (duration: 283.358189ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:02.509639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.116182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T04:46:02.509753Z","caller":"traceutil/trace.go:171","msg":"trace[1105052160] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:25097; }","duration":"195.269565ms","start":"2026-06-16T04:46:02.314455Z","end":"2026-06-16T04:46:02.509725Z","steps":["trace[1105052160] 'count revisions from in-memory index tree' (duration: 194.894048ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:02.510178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.198722ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T04:46:02.510448Z","caller":"traceutil/trace.go:171","msg":"trace[1447956365] transaction","detail":"{read_only:false; response_revision:25098; number_of_response:1; }","duration":"159.263669ms","start":"2026-06-16T04:46:02.351155Z","end":"2026-06-16T04:46:02.510419Z","steps":["trace[1447956365] 'process raft request' (duration: 37.69445ms)","trace[1447956365] 'compare' (duration: 120.625261ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:46:03.115587Z","caller":"traceutil/trace.go:171","msg":"trace[1062258603] transaction","detail":"{read_only:false; response_revision:25100; number_of_response:1; }","duration":"439.271362ms","start":"2026-06-16T04:46:02.676285Z","end":"2026-06-16T04:46:03.115557Z","steps":["trace[1062258603] 'process raft request' (duration: 410.789848ms)","trace[1062258603] 'compare' (duration: 28.262679ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:46:03.115645Z","caller":"traceutil/trace.go:171","msg":"trace[98217951] transaction","detail":"{read_only:false; response_revision:25101; number_of_response:1; }","duration":"439.143179ms","start":"2026-06-16T04:46:02.67649Z","end":"2026-06-16T04:46:03.115633Z","steps":["trace[98217951] 'process raft request' (duration: 439.029037ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:03.115723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:02.676479Z","time spent":"439.19335ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T04:46:03.115728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:02.676262Z","time spent":"439.396994ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T04:46:03.115724Z","caller":"traceutil/trace.go:171","msg":"trace[10490852] linearizableReadLoop","detail":"{readStateIndex:25913; appliedIndex:25909; }","duration":"391.946558ms","start":"2026-06-16T04:46:02.723729Z","end":"2026-06-16T04:46:03.115676Z","steps":["trace[10490852] 'read index received' (duration: 44.241082ms)","trace[10490852] 'applied index is now lower than readState.Index' (duration: 347.704046ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:46:03.115856Z","caller":"traceutil/trace.go:171","msg":"trace[1056677925] transaction","detail":"{read_only:false; response_revision:25102; number_of_response:1; }","duration":"439.131428ms","start":"2026-06-16T04:46:02.676713Z","end":"2026-06-16T04:46:03.115845Z","steps":["trace[1056677925] 'process raft request' (duration: 438.890594ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:03.115916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:02.676703Z","time spent":"439.184599ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T04:46:03.116104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.349326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-16T04:46:03.116169Z","caller":"traceutil/trace.go:171","msg":"trace[893675876] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25102; }","duration":"392.472309ms","start":"2026-06-16T04:46:02.723679Z","end":"2026-06-16T04:46:03.116151Z","steps":["trace[893675876] 'agreement among raft nodes before linearized reading' (duration: 392.183773ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:03.116246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.254713ms","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-06-16T04:46:03.116129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.648571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-16T04:46:03.116315Z","caller":"traceutil/trace.go:171","msg":"trace[1397364373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25102; }","duration":"390.856086ms","start":"2026-06-16T04:46:02.725445Z","end":"2026-06-16T04:46:03.116301Z","steps":["trace[1397364373] 'agreement among raft nodes before linearized reading' (duration: 390.607501ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:03.116398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:02.725429Z","time spent":"390.941517ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-16T04:46:03.116242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:02.723658Z","time spent":"392.55166ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-16T04:46:03.116277Z","caller":"traceutil/trace.go:171","msg":"trace[1872680446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25102; }","duration":"390.323305ms","start":"2026-06-16T04:46:02.725946Z","end":"2026-06-16T04:46:03.116269Z","steps":["trace[1872680446] 'agreement among raft nodes before linearized reading' (duration: 390.268404ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:03.116679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:02.725922Z","time spent":"390.683222ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-16T04:46:03.467224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.232329ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-06-16T04:46:03.467492Z","caller":"traceutil/trace.go:171","msg":"trace[1243278871] transaction","detail":"{read_only:false; response_revision:25107; number_of_response:1; }","duration":"207.457381ms","start":"2026-06-16T04:46:03.260014Z","end":"2026-06-16T04:46:03.467472Z","steps":["trace[1243278871] 'process raft request' (duration: 207.324338ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:03.467625Z","caller":"traceutil/trace.go:171","msg":"trace[2133725310] linearizableReadLoop","detail":"{readStateIndex:25918; appliedIndex:25917; }","duration":"256.231344ms","start":"2026-06-16T04:46:03.211351Z","end":"2026-06-16T04:46:03.467583Z","steps":["trace[2133725310] 'read index received' (duration: 91.650617ms)","trace[2133725310] 'applied index is now lower than readState.Index' (duration: 164.576957ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:46:03.46809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.728443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T04:46:03.468171Z","caller":"traceutil/trace.go:171","msg":"trace[1399906988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25107; }","duration":"256.853536ms","start":"2026-06-16T04:46:03.2113Z","end":"2026-06-16T04:46:03.468153Z","steps":["trace[1399906988] 'agreement among raft nodes before linearized reading' (duration: 256.706973ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:03.468258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.1038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T04:46:03.468346Z","caller":"traceutil/trace.go:171","msg":"trace[1029460019] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:25107; }","duration":"187.258493ms","start":"2026-06-16T04:46:03.281068Z","end":"2026-06-16T04:46:03.468327Z","steps":["trace[1029460019] 'agreement among raft nodes before linearized reading' (duration: 187.113351ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:03.823709Z","caller":"traceutil/trace.go:171","msg":"trace[1403107275] linearizableReadLoop","detail":"{readStateIndex:25920; appliedIndex:25919; }","duration":"354.488543ms","start":"2026-06-16T04:46:03.469193Z","end":"2026-06-16T04:46:03.823682Z","steps":["trace[1403107275] 'read index received' (duration: 328.136221ms)","trace[1403107275] 'applied index is now lower than readState.Index' (duration: 26.351392ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:46:03.823771Z","caller":"traceutil/trace.go:171","msg":"trace[1305115230] transaction","detail":"{read_only:false; response_revision:25108; number_of_response:1; }","duration":"355.256819ms","start":"2026-06-16T04:46:03.468447Z","end":"2026-06-16T04:46:03.823704Z","steps":["trace[1305115230] 'process raft request' (duration: 328.865916ms)","trace[1305115230] 'compare' (duration: 26.222409ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:46:03.823945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.730369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T04:46:03.823994Z","caller":"traceutil/trace.go:171","msg":"trace[929072091] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25108; }","duration":"354.82836ms","start":"2026-06-16T04:46:03.469152Z","end":"2026-06-16T04:46:03.82398Z","steps":["trace[929072091] 'agreement among raft nodes before linearized reading' (duration: 354.678437ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:03.824033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:03.469137Z","time spent":"354.887272ms","remote":"127.0.0.1:46334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T04:46:03.82403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:03.468424Z","time spent":"355.482414ms","remote":"127.0.0.1:46370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T04:46:03.844486Z","caller":"traceutil/trace.go:171","msg":"trace[64586602] transaction","detail":"{read_only:false; response_revision:25109; number_of_response:1; }","duration":"107.611607ms","start":"2026-06-16T04:46:03.736853Z","end":"2026-06-16T04:46:03.844465Z","steps":["trace[64586602] 'process raft request' (duration: 107.303541ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:03.84455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.288392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-16T04:46:03.844583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.659414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T04:46:03.844632Z","caller":"traceutil/trace.go:171","msg":"trace[1479055839] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25109; }","duration":"178.441075ms","start":"2026-06-16T04:46:03.66617Z","end":"2026-06-16T04:46:03.844611Z","steps":["trace[1479055839] 'agreement among raft nodes before linearized reading' (duration: 178.269982ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:03.844637Z","caller":"traceutil/trace.go:171","msg":"trace[738910537] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:25109; }","duration":"204.743836ms","start":"2026-06-16T04:46:03.639879Z","end":"2026-06-16T04:46:03.844622Z","steps":["trace[738910537] 'agreement among raft nodes before linearized reading' (duration: 204.627913ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:03.844633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.369683ms","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-06-16T04:46:03.84463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.553494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-16T04:46:03.844714Z","caller":"traceutil/trace.go:171","msg":"trace[558582434] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25109; }","duration":"239.461035ms","start":"2026-06-16T04:46:03.605229Z","end":"2026-06-16T04:46:03.84469Z","steps":["trace[558582434] 'agreement among raft nodes before linearized reading' (duration: 239.18831ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:03.844721Z","caller":"traceutil/trace.go:171","msg":"trace[475217407] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:25109; }","duration":"354.690576ms","start":"2026-06-16T04:46:03.490014Z","end":"2026-06-16T04:46:03.844705Z","steps":["trace[475217407] 'agreement among raft nodes before linearized reading' (duration: 354.523513ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:03.844778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:03.490001Z","time spent":"354.766238ms","remote":"127.0.0.1:58706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":1,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true "} {"level":"warn","ts":"2026-06-16T04:46:03.844663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.747441ms","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-06-16T04:46:03.844876Z","caller":"traceutil/trace.go:171","msg":"trace[1663256313] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:25109; }","duration":"245.964097ms","start":"2026-06-16T04:46:03.598877Z","end":"2026-06-16T04:46:03.844841Z","steps":["trace[1663256313] 'agreement among raft nodes before linearized reading' (duration: 245.750993ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:04.138982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.132207ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T04:46:04.139099Z","caller":"traceutil/trace.go:171","msg":"trace[915875989] transaction","detail":"{read_only:false; response_revision:25110; number_of_response:1; }","duration":"289.737308ms","start":"2026-06-16T04:46:03.849341Z","end":"2026-06-16T04:46:04.139078Z","steps":["trace[915875989] 'process raft request' (duration: 126.396957ms)","trace[915875989] 'compare' (duration: 163.001424ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:46:04.600488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.705086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T04:46:04.600591Z","caller":"traceutil/trace.go:171","msg":"trace[893372287] transaction","detail":"{read_only:false; response_revision:25111; number_of_response:1; }","duration":"426.288379ms","start":"2026-06-16T04:46:04.174285Z","end":"2026-06-16T04:46:04.600573Z","steps":["trace[893372287] 'process raft request' (duration: 284.41887ms)","trace[893372287] 'compare' (duration: 141.597644ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:46:04.600656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:04.17426Z","time spent":"426.365761ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T04:46:05.322253Z","caller":"traceutil/trace.go:171","msg":"trace[1787736587] transaction","detail":"{read_only:false; response_revision:25114; number_of_response:1; }","duration":"200.244346ms","start":"2026-06-16T04:46:05.121983Z","end":"2026-06-16T04:46:05.322227Z","steps":["trace[1787736587] 'process raft request' (duration: 200.053692ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:05.556683Z","caller":"traceutil/trace.go:171","msg":"trace[139716206] transaction","detail":"{read_only:false; response_revision:25115; number_of_response:1; }","duration":"433.934935ms","start":"2026-06-16T04:46:05.122722Z","end":"2026-06-16T04:46:05.556657Z","steps":["trace[139716206] 'process raft request' (duration: 386.747815ms)","trace[139716206] 'compare' (duration: 46.819702ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:46:05.556852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:05.122701Z","time spent":"434.064237ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T04:46:05.556927Z","caller":"traceutil/trace.go:171","msg":"trace[1113233214] transaction","detail":"{read_only:false; response_revision:25116; number_of_response:1; }","duration":"434.142968ms","start":"2026-06-16T04:46:05.122756Z","end":"2026-06-16T04:46:05.556899Z","steps":["trace[1113233214] 'process raft request' (duration: 433.816892ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:05.556992Z","caller":"traceutil/trace.go:171","msg":"trace[734276203] linearizableReadLoop","detail":"{readStateIndex:25928; appliedIndex:25925; }","duration":"416.075054ms","start":"2026-06-16T04:46:05.140898Z","end":"2026-06-16T04:46:05.556973Z","steps":["trace[734276203] 'read index received' (duration: 181.142051ms)","trace[734276203] 'applied index is now lower than readState.Index' (duration: 234.931103ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:46:05.557055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:05.122741Z","time spent":"434.24545ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T04:46:05.557124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.171066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T04:46:05.55715Z","caller":"traceutil/trace.go:171","msg":"trace[1059670647] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25117; }","duration":"415.221927ms","start":"2026-06-16T04:46:05.141918Z","end":"2026-06-16T04:46:05.55714Z","steps":["trace[1059670647] 'agreement among raft nodes before linearized reading' (duration: 415.157506ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:05.557175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:05.14191Z","time spent":"415.259399ms","remote":"127.0.0.1:46530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-06-16T04:46:05.557186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.275639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-16T04:46:05.557252Z","caller":"traceutil/trace.go:171","msg":"trace[814689649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25117; }","duration":"416.37924ms","start":"2026-06-16T04:46:05.140856Z","end":"2026-06-16T04:46:05.557235Z","steps":["trace[814689649] 'agreement among raft nodes before linearized reading' (duration: 416.197595ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:05.557255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.582431ms","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-06-16T04:46:05.557269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.842056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-06-16T04:46:05.557298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:05.140837Z","time spent":"416.446462ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-16T04:46:05.557287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.35302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-06-16T04:46:05.557315Z","caller":"traceutil/trace.go:171","msg":"trace[1088526534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25117; }","duration":"389.679883ms","start":"2026-06-16T04:46:05.167619Z","end":"2026-06-16T04:46:05.557298Z","steps":["trace[1088526534] 'agreement among raft nodes before linearized reading' (duration: 389.51701ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:05.557342Z","caller":"traceutil/trace.go:171","msg":"trace[62742275] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25117; }","duration":"389.983149ms","start":"2026-06-16T04:46:05.167338Z","end":"2026-06-16T04:46:05.557321Z","steps":["trace[62742275] 'agreement among raft nodes before linearized reading' (duration: 389.809245ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:05.557337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.362157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-16T04:46:05.557358Z","caller":"traceutil/trace.go:171","msg":"trace[1024921367] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25117; }","duration":"346.446861ms","start":"2026-06-16T04:46:05.210893Z","end":"2026-06-16T04:46:05.55734Z","steps":["trace[1024921367] 'agreement among raft nodes before linearized reading' (duration: 346.227257ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:05.557367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:05.1676Z","time spent":"389.751474ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-16T04:46:05.557381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:05.167325Z","time spent":"390.047811ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-16T04:46:05.557397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:05.210869Z","time spent":"346.521073ms","remote":"127.0.0.1:46432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":380,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"info","ts":"2026-06-16T04:46:05.557397Z","caller":"traceutil/trace.go:171","msg":"trace[1036067281] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25117; }","duration":"390.468959ms","start":"2026-06-16T04:46:05.166913Z","end":"2026-06-16T04:46:05.557382Z","steps":["trace[1036067281] 'agreement among raft nodes before linearized reading' (duration: 390.306996ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:05.557441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:05.166893Z","time spent":"390.5357ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-16T04:46:05.557307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.111115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T04:46:05.5577Z","caller":"traceutil/trace.go:171","msg":"trace[594951930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25117; }","duration":"348.580955ms","start":"2026-06-16T04:46:05.209097Z","end":"2026-06-16T04:46:05.557678Z","steps":["trace[594951930] 'agreement among raft nodes before linearized reading' (duration: 348.156586ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:05.557768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:05.209052Z","time spent":"348.699547ms","remote":"127.0.0.1:46340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-16T04:46:05.811422Z","caller":"traceutil/trace.go:171","msg":"trace[1416450325] transaction","detail":"{read_only:false; response_revision:25119; number_of_response:1; }","duration":"249.440957ms","start":"2026-06-16T04:46:05.561955Z","end":"2026-06-16T04:46:05.811396Z","steps":["trace[1416450325] 'process raft request' (duration: 174.018097ms)","trace[1416450325] 'compare' (duration: 75.207756ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:46:05.811833Z","caller":"traceutil/trace.go:171","msg":"trace[808178897] linearizableReadLoop","detail":"{readStateIndex:25932; appliedIndex:25929; }","duration":"249.029527ms","start":"2026-06-16T04:46:05.562754Z","end":"2026-06-16T04:46:05.811784Z","steps":["trace[808178897] 'read index received' (duration: 27.241868ms)","trace[808178897] 'applied index is now lower than readState.Index' (duration: 221.786439ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:46:05.811918Z","caller":"traceutil/trace.go:171","msg":"trace[1220605354] transaction","detail":"{read_only:false; response_revision:25120; number_of_response:1; }","duration":"249.532078ms","start":"2026-06-16T04:46:05.562378Z","end":"2026-06-16T04:46:05.81191Z","steps":["trace[1220605354] 'process raft request' (duration: 248.970307ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:05.811862Z","caller":"traceutil/trace.go:171","msg":"trace[1357544288] transaction","detail":"{read_only:false; response_revision:25121; number_of_response:1; }","duration":"248.891985ms","start":"2026-06-16T04:46:05.562952Z","end":"2026-06-16T04:46:05.811844Z","steps":["trace[1357544288] 'process raft request' (duration: 248.744552ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:05.812053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.284273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T04:46:05.812092Z","caller":"traceutil/trace.go:171","msg":"trace[1939872624] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25121; }","duration":"249.362345ms","start":"2026-06-16T04:46:05.562718Z","end":"2026-06-16T04:46:05.81208Z","steps":["trace[1939872624] 'agreement among raft nodes before linearized reading' (duration: 249.219352ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:05.812083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.376255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T04:46:05.812158Z","caller":"traceutil/trace.go:171","msg":"trace[1254441411] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:25121; }","duration":"245.481218ms","start":"2026-06-16T04:46:05.566658Z","end":"2026-06-16T04:46:05.812139Z","steps":["trace[1254441411] 'agreement among raft nodes before linearized reading' (duration: 245.354875ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:06.411378Z","caller":"traceutil/trace.go:171","msg":"trace[5935924] linearizableReadLoop","detail":"{readStateIndex:25935; appliedIndex:25934; }","duration":"428.248408ms","start":"2026-06-16T04:46:05.983103Z","end":"2026-06-16T04:46:06.411352Z","steps":["trace[5935924] 'read index received' (duration: 427.705897ms)","trace[5935924] 'applied index is now lower than readState.Index' (duration: 531.511µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:46:06.411521Z","caller":"traceutil/trace.go:171","msg":"trace[402290050] transaction","detail":"{read_only:false; response_revision:25123; number_of_response:1; }","duration":"482.156197ms","start":"2026-06-16T04:46:05.929337Z","end":"2026-06-16T04:46:06.411493Z","steps":["trace[402290050] 'process raft request' (duration: 481.637275ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:06.411679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:05.929308Z","time spent":"482.276218ms","remote":"127.0.0.1:48086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418362,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T04:46:06.411737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.606957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-16T04:46:06.411841Z","caller":"traceutil/trace.go:171","msg":"trace[94279067] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:25123; }","duration":"428.74357ms","start":"2026-06-16T04:46:05.983047Z","end":"2026-06-16T04:46:06.411791Z","steps":["trace[94279067] 'agreement among raft nodes before linearized reading' (duration: 428.429733ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:06.411888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:05.983022Z","time spent":"428.853451ms","remote":"127.0.0.1:46658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":184,"response size":32,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-06-16T04:46:06.412119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.122043ms","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-06-16T04:46:06.412202Z","caller":"traceutil/trace.go:171","msg":"trace[2003017676] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25123; }","duration":"269.259146ms","start":"2026-06-16T04:46:06.142917Z","end":"2026-06-16T04:46:06.412176Z","steps":["trace[2003017676] 'agreement among raft nodes before linearized reading' (duration: 268.999661ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:06.412297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.565315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-06-16T04:46:06.412346Z","caller":"traceutil/trace.go:171","msg":"trace[1777938637] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25123; }","duration":"226.629657ms","start":"2026-06-16T04:46:06.185696Z","end":"2026-06-16T04:46:06.412325Z","steps":["trace[1777938637] 'agreement among raft nodes before linearized reading' (duration: 226.496804ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:06.818357Z","caller":"traceutil/trace.go:171","msg":"trace[1463934017] transaction","detail":"{read_only:false; response_revision:25125; number_of_response:1; }","duration":"245.829643ms","start":"2026-06-16T04:46:06.572496Z","end":"2026-06-16T04:46:06.818325Z","steps":["trace[1463934017] 'process raft request' (duration: 245.630749ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:06.862425Z","caller":"traceutil/trace.go:171","msg":"trace[1717415286] linearizableReadLoop","detail":"{readStateIndex:25939; appliedIndex:25936; }","duration":"229.352191ms","start":"2026-06-16T04:46:06.633047Z","end":"2026-06-16T04:46:06.862399Z","steps":["trace[1717415286] 'read index received' (duration: 185.004068ms)","trace[1717415286] 'applied index is now lower than readState.Index' (duration: 44.346513ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:46:06.862483Z","caller":"traceutil/trace.go:171","msg":"trace[1273436219] transaction","detail":"{read_only:false; response_revision:25126; number_of_response:1; }","duration":"256.492978ms","start":"2026-06-16T04:46:06.605951Z","end":"2026-06-16T04:46:06.862444Z","steps":["trace[1273436219] 'process raft request' (duration: 256.12831ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:06.86255Z","caller":"traceutil/trace.go:171","msg":"trace[1691868112] transaction","detail":"{read_only:false; response_revision:25127; number_of_response:1; }","duration":"242.765213ms","start":"2026-06-16T04:46:06.61974Z","end":"2026-06-16T04:46:06.862505Z","steps":["trace[1691868112] 'process raft request' (duration: 242.579148ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:06.862696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.586176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T04:46:06.862786Z","caller":"traceutil/trace.go:171","msg":"trace[134086543] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:25127; }","duration":"229.727679ms","start":"2026-06-16T04:46:06.633002Z","end":"2026-06-16T04:46:06.86273Z","steps":["trace[134086543] 'agreement among raft nodes before linearized reading' (duration: 229.538095ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:06.995024Z","caller":"traceutil/trace.go:171","msg":"trace[1829943431] linearizableReadLoop","detail":"{readStateIndex:25940; appliedIndex:25939; }","duration":"132.489689ms","start":"2026-06-16T04:46:06.862515Z","end":"2026-06-16T04:46:06.995005Z","steps":["trace[1829943431] 'read index received' (duration: 131.812786ms)","trace[1829943431] 'applied index is now lower than readState.Index' (duration: 676.123µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:46:06.995072Z","caller":"traceutil/trace.go:171","msg":"trace[770508110] transaction","detail":"{read_only:false; response_revision:25128; number_of_response:1; }","duration":"159.470813ms","start":"2026-06-16T04:46:06.835568Z","end":"2026-06-16T04:46:06.995039Z","steps":["trace[770508110] 'process raft request' (duration: 158.755399ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:06.995171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.761772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-16T04:46:06.995193Z","caller":"traceutil/trace.go:171","msg":"trace[1791794121] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25128; }","duration":"175.842934ms","start":"2026-06-16T04:46:06.819343Z","end":"2026-06-16T04:46:06.995186Z","steps":["trace[1791794121] 'agreement among raft nodes before linearized reading' (duration: 175.733132ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:16.918514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.426555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1315f400-718e-439e-a816-edd81f5fc95a\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-06-16T04:46:16.918588Z","caller":"traceutil/trace.go:171","msg":"trace[170526426] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1315f400-718e-439e-a816-edd81f5fc95a; range_end:; response_count:1; response_revision:25257; }","duration":"245.542967ms","start":"2026-06-16T04:46:16.673028Z","end":"2026-06-16T04:46:16.918571Z","steps":["trace[170526426] 'range keys from in-memory index tree' (duration: 245.266352ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:16.918547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.882882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1315f400-718e-439e-a816-edd81f5fc95a\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-06-16T04:46:16.918772Z","caller":"traceutil/trace.go:171","msg":"trace[528493327] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1315f400-718e-439e-a816-edd81f5fc95a; range_end:; response_count:1; response_revision:25257; }","duration":"224.111746ms","start":"2026-06-16T04:46:16.694653Z","end":"2026-06-16T04:46:16.918765Z","steps":["trace[528493327] 'range keys from in-memory index tree' (duration: 223.785069ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:17.142253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.20106ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T04:46:17.142387Z","caller":"traceutil/trace.go:171","msg":"trace[1432104119] transaction","detail":"{read_only:false; response_revision:25259; number_of_response:1; }","duration":"236.174039ms","start":"2026-06-16T04:46:16.906193Z","end":"2026-06-16T04:46:17.142367Z","steps":["trace[1432104119] 'process raft request' (duration: 125.758935ms)","trace[1432104119] 'compare' (duration: 110.050707ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:46:17.424293Z","caller":"traceutil/trace.go:171","msg":"trace[239888705] transaction","detail":"{read_only:false; response_revision:25260; number_of_response:1; }","duration":"498.032106ms","start":"2026-06-16T04:46:16.926231Z","end":"2026-06-16T04:46:17.424263Z","steps":["trace[239888705] 'process raft request' (duration: 474.832258ms)","trace[239888705] 'compare' (duration: 22.929963ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:46:17.424294Z","caller":"traceutil/trace.go:171","msg":"trace[1608777248] linearizableReadLoop","detail":"{readStateIndex:26075; appliedIndex:26072; }","duration":"423.258499ms","start":"2026-06-16T04:46:17.001008Z","end":"2026-06-16T04:46:17.424266Z","steps":["trace[1608777248] 'read index received' (duration: 30.954954ms)","trace[1608777248] 'applied index is now lower than readState.Index' (duration: 392.302705ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:46:17.4243Z","caller":"traceutil/trace.go:171","msg":"trace[413906013] transaction","detail":"{read_only:false; response_revision:25261; number_of_response:1; }","duration":"478.099014ms","start":"2026-06-16T04:46:16.946163Z","end":"2026-06-16T04:46:17.424262Z","steps":["trace[413906013] 'process raft request' (duration: 477.975041ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:17.424406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:16.926213Z","time spent":"498.135859ms","remote":"127.0.0.1:46520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3746,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T04:46:17.424424Z","caller":"traceutil/trace.go:171","msg":"trace[1995389647] transaction","detail":"{read_only:false; response_revision:25262; number_of_response:1; }","duration":"398.00211ms","start":"2026-06-16T04:46:17.02641Z","end":"2026-06-16T04:46:17.424412Z","steps":["trace[1995389647] 'process raft request' (duration: 397.783376ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:17.424458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:16.946141Z","time spent":"478.234667ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T04:46:17.424525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:17.02639Z","time spent":"398.060331ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T04:46:17.424769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.954759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-16T04:46:17.424796Z","caller":"traceutil/trace.go:171","msg":"trace[1167329668] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25264; }","duration":"259.02471ms","start":"2026-06-16T04:46:17.165765Z","end":"2026-06-16T04:46:17.424789Z","steps":["trace[1167329668] 'agreement among raft nodes before linearized reading' (duration: 258.925668ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:17.4249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.882411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:186"} {"level":"info","ts":"2026-06-16T04:46:17.424981Z","caller":"traceutil/trace.go:171","msg":"trace[902239123] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:25264; }","duration":"423.995564ms","start":"2026-06-16T04:46:17.000966Z","end":"2026-06-16T04:46:17.424962Z","steps":["trace[902239123] 'agreement among raft nodes before linearized reading' (duration: 423.620626ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:17.42503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:17.000951Z","time spent":"424.067405ms","remote":"127.0.0.1:46544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":209,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"info","ts":"2026-06-16T04:46:17.730306Z","caller":"traceutil/trace.go:171","msg":"trace[1978187221] transaction","detail":"{read_only:false; response_revision:25265; number_of_response:1; }","duration":"128.04638ms","start":"2026-06-16T04:46:17.60223Z","end":"2026-06-16T04:46:17.730277Z","steps":["trace[1978187221] 'process raft request' (duration: 127.857956ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:18.167436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.123777ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T04:46:18.167863Z","caller":"traceutil/trace.go:171","msg":"trace[2100182540] transaction","detail":"{read_only:false; response_revision:25268; number_of_response:1; }","duration":"255.685322ms","start":"2026-06-16T04:46:17.912159Z","end":"2026-06-16T04:46:18.167844Z","steps":["trace[2100182540] 'process raft request' (duration: 255.611761ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:18.168041Z","caller":"traceutil/trace.go:171","msg":"trace[2125338911] transaction","detail":"{read_only:false; response_revision:25267; number_of_response:1; }","duration":"562.893422ms","start":"2026-06-16T04:46:17.605107Z","end":"2026-06-16T04:46:18.168Z","steps":["trace[2125338911] 'process raft request' (duration: 562.553265ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:18.168089Z","caller":"traceutil/trace.go:171","msg":"trace[485365550] transaction","detail":"{read_only:false; response_revision:25266; number_of_response:1; }","duration":"563.514905ms","start":"2026-06-16T04:46:17.60454Z","end":"2026-06-16T04:46:18.168055Z","steps":["trace[485365550] 'process raft request' (duration: 336.667364ms)","trace[485365550] 'compare' (duration: 225.693388ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:46:18.168237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:17.604528Z","time spent":"563.629897ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T04:46:18.168242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:17.605084Z","time spent":"563.060696ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T04:46:18.168453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.7229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-16T04:46:18.168541Z","caller":"traceutil/trace.go:171","msg":"trace[1859095686] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25268; }","duration":"502.836973ms","start":"2026-06-16T04:46:17.665689Z","end":"2026-06-16T04:46:18.168526Z","steps":["trace[1859095686] 'agreement among raft nodes before linearized reading' (duration: 502.649279ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:18.168613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:17.665672Z","time spent":"502.927894ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-16T04:46:18.168038Z","caller":"traceutil/trace.go:171","msg":"trace[1010597151] linearizableReadLoop","detail":"{readStateIndex:26081; appliedIndex:26078; }","duration":"502.266091ms","start":"2026-06-16T04:46:17.665732Z","end":"2026-06-16T04:46:18.167998Z","steps":["trace[1010597151] 'read index received' (duration: 64.426739ms)","trace[1010597151] 'applied index is now lower than readState.Index' (duration: 437.835712ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:46:18.169133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.266359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-06-16T04:46:18.169184Z","caller":"traceutil/trace.go:171","msg":"trace[958990665] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25268; }","duration":"490.3273ms","start":"2026-06-16T04:46:17.67884Z","end":"2026-06-16T04:46:18.169168Z","steps":["trace[958990665] 'agreement among raft nodes before linearized reading' (duration: 490.150197ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:18.170185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:17.678829Z","time spent":"491.334241ms","remote":"127.0.0.1:55762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3332,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-06-16T04:46:18.169458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.343074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1315f400-718e-439e-a816-edd81f5fc95a\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-06-16T04:46:18.171035Z","caller":"traceutil/trace.go:171","msg":"trace[1710326829] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1315f400-718e-439e-a816-edd81f5fc95a; range_end:; response_count:1; response_revision:25268; }","duration":"245.912385ms","start":"2026-06-16T04:46:17.925072Z","end":"2026-06-16T04:46:18.170984Z","steps":["trace[1710326829] 'agreement among raft nodes before linearized reading' (duration: 244.14569ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:18.169547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.747882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-16T04:46:18.171244Z","caller":"traceutil/trace.go:171","msg":"trace[1623486238] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:25268; }","duration":"113.428525ms","start":"2026-06-16T04:46:18.057758Z","end":"2026-06-16T04:46:18.171186Z","steps":["trace[1623486238] 'agreement among raft nodes before linearized reading' (duration: 111.751662ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:18.169646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.356483ms","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-06-16T04:46:18.171332Z","caller":"traceutil/trace.go:171","msg":"trace[133707533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25268; }","duration":"300.062017ms","start":"2026-06-16T04:46:17.871245Z","end":"2026-06-16T04:46:18.171307Z","steps":["trace[133707533] 'agreement among raft nodes before linearized reading' (duration: 298.336562ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:18.171404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:17.871225Z","time spent":"300.158989ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-16T04:46:18.169666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.076856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-16T04:46:18.171562Z","caller":"traceutil/trace.go:171","msg":"trace[410277025] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25268; }","duration":"295.993304ms","start":"2026-06-16T04:46:17.875548Z","end":"2026-06-16T04:46:18.171541Z","steps":["trace[410277025] 'agreement among raft nodes before linearized reading' (duration: 293.848192ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:18.169791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.664408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-16T04:46:18.171728Z","caller":"traceutil/trace.go:171","msg":"trace[581758604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25268; }","duration":"302.632639ms","start":"2026-06-16T04:46:17.86908Z","end":"2026-06-16T04:46:18.171713Z","steps":["trace[581758604] 'agreement among raft nodes before linearized reading' (duration: 300.544536ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:18.171789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:17.869066Z","time spent":"302.70396ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-16T04:46:18.498167Z","caller":"traceutil/trace.go:171","msg":"trace[1908528790] transaction","detail":"{read_only:false; response_revision:25270; number_of_response:1; }","duration":"323.225503ms","start":"2026-06-16T04:46:18.17492Z","end":"2026-06-16T04:46:18.498145Z","steps":["trace[1908528790] 'process raft request' (duration: 289.041274ms)","trace[1908528790] 'compare' (duration: 33.878402ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:46:18.49827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:18.1749Z","time spent":"323.313164ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T04:46:18.498421Z","caller":"traceutil/trace.go:171","msg":"trace[1987763337] linearizableReadLoop","detail":"{readStateIndex:26084; appliedIndex:26082; }","duration":"323.282754ms","start":"2026-06-16T04:46:18.175123Z","end":"2026-06-16T04:46:18.498406Z","steps":["trace[1987763337] 'read index received' (duration: 27.046584ms)","trace[1987763337] 'applied index is now lower than readState.Index' (duration: 296.23536ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:46:18.498514Z","caller":"traceutil/trace.go:171","msg":"trace[174947933] transaction","detail":"{read_only:false; response_revision:25272; number_of_response:1; }","duration":"322.277433ms","start":"2026-06-16T04:46:18.176208Z","end":"2026-06-16T04:46:18.498486Z","steps":["trace[174947933] 'process raft request' (duration: 322.12442ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:18.498572Z","caller":"traceutil/trace.go:171","msg":"trace[1862300484] transaction","detail":"{read_only:false; response_revision:25271; number_of_response:1; }","duration":"322.56288ms","start":"2026-06-16T04:46:18.175991Z","end":"2026-06-16T04:46:18.498554Z","steps":["trace[1862300484] 'process raft request' (duration: 322.137201ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:18.498591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.82279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T04:46:18.498645Z","caller":"traceutil/trace.go:171","msg":"trace[328337444] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25272; }","duration":"289.923061ms","start":"2026-06-16T04:46:18.208709Z","end":"2026-06-16T04:46:18.498632Z","steps":["trace[328337444] 'agreement among raft nodes before linearized reading' (duration: 289.830139ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:18.49864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:18.17614Z","time spent":"322.431837ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T04:46:18.498669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:18.175964Z","time spent":"322.661251ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T04:46:18.498719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.59505ms","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-06-16T04:46:18.498814Z","caller":"traceutil/trace.go:171","msg":"trace[495893113] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25272; }","duration":"323.655081ms","start":"2026-06-16T04:46:18.17509Z","end":"2026-06-16T04:46:18.498745Z","steps":["trace[495893113] 'agreement among raft nodes before linearized reading' (duration: 323.404656ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:18.499004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:18.175082Z","time spent":"323.762033ms","remote":"127.0.0.1:46420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1335,"request content":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" "} {"level":"warn","ts":"2026-06-16T04:46:19.154162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.309305ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T04:46:19.154331Z","caller":"traceutil/trace.go:171","msg":"trace[2039078986] transaction","detail":"{read_only:false; response_revision:25273; number_of_response:1; }","duration":"644.854883ms","start":"2026-06-16T04:46:18.509462Z","end":"2026-06-16T04:46:19.154317Z","steps":["trace[2039078986] 'process raft request' (duration: 190.321535ms)","trace[2039078986] 'compare' (duration: 453.949247ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:46:19.154375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:18.509446Z","time spent":"644.908245ms","remote":"127.0.0.1:46412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":724,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T04:46:19.1546Z","caller":"traceutil/trace.go:171","msg":"trace[1688387286] transaction","detail":"{read_only:false; response_revision:25274; number_of_response:1; }","duration":"642.424625ms","start":"2026-06-16T04:46:18.512168Z","end":"2026-06-16T04:46:19.154592Z","steps":["trace[1688387286] 'process raft request' (duration: 642.075528ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:19.154638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:18.512151Z","time spent":"642.465975ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T04:46:19.181002Z","caller":"traceutil/trace.go:171","msg":"trace[205073170] linearizableReadLoop","detail":"{readStateIndex:26088; appliedIndex:26086; }","duration":"667.854498ms","start":"2026-06-16T04:46:18.51311Z","end":"2026-06-16T04:46:19.180964Z","steps":["trace[205073170] 'read index received' (duration: 186.60049ms)","trace[205073170] 'applied index is now lower than readState.Index' (duration: 481.252248ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:46:19.181154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.043952ms","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-06-16T04:46:19.181207Z","caller":"traceutil/trace.go:171","msg":"trace[1465734361] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25275; }","duration":"668.122293ms","start":"2026-06-16T04:46:18.513074Z","end":"2026-06-16T04:46:19.181196Z","steps":["trace[1465734361] 'agreement among raft nodes before linearized reading' (duration: 667.96243ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:19.181236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:18.513059Z","time spent":"668.171564ms","remote":"127.0.0.1:46798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":9627,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" "} {"level":"info","ts":"2026-06-16T04:46:21.280134Z","caller":"traceutil/trace.go:171","msg":"trace[68430630] transaction","detail":"{read_only:false; response_revision:25292; number_of_response:1; }","duration":"117.579259ms","start":"2026-06-16T04:46:21.162532Z","end":"2026-06-16T04:46:21.280112Z","steps":["trace[68430630] 'process raft request' (duration: 117.412626ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:25.100118Z","caller":"traceutil/trace.go:171","msg":"trace[856044128] linearizableReadLoop","detail":"{readStateIndex:26155; appliedIndex:26154; }","duration":"226.042055ms","start":"2026-06-16T04:46:24.874052Z","end":"2026-06-16T04:46:25.100094Z","steps":["trace[856044128] 'read index received' (duration: 225.716818ms)","trace[856044128] 'applied index is now lower than readState.Index' (duration: 324.137µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:46:25.100221Z","caller":"traceutil/trace.go:171","msg":"trace[1877351499] transaction","detail":"{read_only:false; response_revision:25338; number_of_response:1; }","duration":"235.500915ms","start":"2026-06-16T04:46:24.864688Z","end":"2026-06-16T04:46:25.100188Z","steps":["trace[1877351499] 'process raft request' (duration: 235.22582ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:25.100338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.26111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-16T04:46:25.100385Z","caller":"traceutil/trace.go:171","msg":"trace[1229053750] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:25338; }","duration":"226.358751ms","start":"2026-06-16T04:46:24.874014Z","end":"2026-06-16T04:46:25.100372Z","steps":["trace[1229053750] 'agreement among raft nodes before linearized reading' (duration: 226.229159ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:25.100527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.49681ms","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-06-16T04:46:25.100574Z","caller":"traceutil/trace.go:171","msg":"trace[1884923035] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25338; }","duration":"196.586181ms","start":"2026-06-16T04:46:24.903975Z","end":"2026-06-16T04:46:25.100561Z","steps":["trace[1884923035] 'agreement among raft nodes before linearized reading' (duration: 196.411697ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:25.528738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.169725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T04:46:25.528844Z","caller":"traceutil/trace.go:171","msg":"trace[800942066] transaction","detail":"{read_only:false; response_revision:25339; number_of_response:1; }","duration":"421.446543ms","start":"2026-06-16T04:46:25.107383Z","end":"2026-06-16T04:46:25.528829Z","steps":["trace[800942066] 'process raft request' (duration: 143.106184ms)","trace[800942066] 'compare' (duration: 278.064444ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:46:25.528898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:25.107366Z","time spent":"421.504364ms","remote":"127.0.0.1:46614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T04:46:25.531818Z","caller":"traceutil/trace.go:171","msg":"trace[1155052997] transaction","detail":"{read_only:false; response_revision:25341; number_of_response:1; }","duration":"424.234989ms","start":"2026-06-16T04:46:25.107541Z","end":"2026-06-16T04:46:25.531776Z","steps":["trace[1155052997] 'process raft request' (duration: 424.173088ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:25.531885Z","caller":"traceutil/trace.go:171","msg":"trace[1447648575] transaction","detail":"{read_only:false; response_revision:25340; number_of_response:1; }","duration":"424.330302ms","start":"2026-06-16T04:46:25.107461Z","end":"2026-06-16T04:46:25.531792Z","steps":["trace[1447648575] 'process raft request' (duration: 424.157048ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:25.531977Z","caller":"traceutil/trace.go:171","msg":"trace[1941991197] linearizableReadLoop","detail":"{readStateIndex:26159; appliedIndex:26155; }","duration":"323.02707ms","start":"2026-06-16T04:46:25.208913Z","end":"2026-06-16T04:46:25.53194Z","steps":["trace[1941991197] 'read index received' (duration: 41.660549ms)","trace[1941991197] 'applied index is now lower than readState.Index' (duration: 281.3636ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:46:25.53201Z","caller":"traceutil/trace.go:171","msg":"trace[666723747] transaction","detail":"{read_only:false; response_revision:25342; number_of_response:1; }","duration":"424.360922ms","start":"2026-06-16T04:46:25.10761Z","end":"2026-06-16T04:46:25.531971Z","steps":["trace[666723747] 'process raft request' (duration: 424.130968ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:25.531969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:25.107534Z","time spent":"424.374632ms","remote":"127.0.0.1:46504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":885,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T04:46:25.532176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:25.107602Z","time spent":"424.479254ms","remote":"127.0.0.1:46614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1415,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T04:46:25.532206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:25.107445Z","time spent":"424.599107ms","remote":"127.0.0.1:46504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T04:46:25.532138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.222573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T04:46:25.532285Z","caller":"traceutil/trace.go:171","msg":"trace[292160454] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25342; }","duration":"323.398596ms","start":"2026-06-16T04:46:25.208869Z","end":"2026-06-16T04:46:25.532267Z","steps":["trace[292160454] 'agreement among raft nodes before linearized reading' (duration: 323.179432ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:25.532328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:25.208847Z","time spent":"323.471618ms","remote":"127.0.0.1:46340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-16T04:46:25.537812Z","caller":"traceutil/trace.go:171","msg":"trace[512154239] transaction","detail":"{read_only:false; response_revision:25343; number_of_response:1; }","duration":"245.12701ms","start":"2026-06-16T04:46:25.292663Z","end":"2026-06-16T04:46:25.53779Z","steps":["trace[512154239] 'process raft request' (duration: 244.944957ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:25.538246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.745184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-06-16T04:46:25.538309Z","caller":"traceutil/trace.go:171","msg":"trace[1653440067] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25346; }","duration":"122.854396ms","start":"2026-06-16T04:46:25.415437Z","end":"2026-06-16T04:46:25.538291Z","steps":["trace[1653440067] 'agreement among raft nodes before linearized reading' (duration: 122.651882ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:25.538408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.607828ms","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-06-16T04:46:25.538483Z","caller":"traceutil/trace.go:171","msg":"trace[1547560961] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25346; }","duration":"241.727171ms","start":"2026-06-16T04:46:25.296735Z","end":"2026-06-16T04:46:25.538462Z","steps":["trace[1547560961] 'agreement among raft nodes before linearized reading' (duration: 241.512936ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:26.402818Z","caller":"traceutil/trace.go:171","msg":"trace[1502233981] transaction","detail":"{read_only:false; response_revision:25353; number_of_response:1; }","duration":"178.358825ms","start":"2026-06-16T04:46:26.224434Z","end":"2026-06-16T04:46:26.402793Z","steps":["trace[1502233981] 'process raft request' (duration: 178.181351ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:26.404559Z","caller":"traceutil/trace.go:171","msg":"trace[736834016] linearizableReadLoop","detail":"{readStateIndex:26172; appliedIndex:26169; }","duration":"168.592447ms","start":"2026-06-16T04:46:26.235943Z","end":"2026-06-16T04:46:26.404535Z","steps":["trace[736834016] 'read index received' (duration: 166.830432ms)","trace[736834016] 'applied index is now lower than readState.Index' (duration: 1.760285ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:46:26.404732Z","caller":"traceutil/trace.go:171","msg":"trace[1145481593] transaction","detail":"{read_only:false; response_revision:25354; number_of_response:1; }","duration":"175.964375ms","start":"2026-06-16T04:46:26.228721Z","end":"2026-06-16T04:46:26.404685Z","steps":["trace[1145481593] 'process raft request' (duration: 175.622768ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:26.404791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.835792ms","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-06-16T04:46:26.404826Z","caller":"traceutil/trace.go:171","msg":"trace[167226156] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25355; }","duration":"168.918764ms","start":"2026-06-16T04:46:26.235898Z","end":"2026-06-16T04:46:26.404817Z","steps":["trace[167226156] 'agreement among raft nodes before linearized reading' (duration: 168.73518ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:26.404956Z","caller":"traceutil/trace.go:171","msg":"trace[1585093878] transaction","detail":"{read_only:false; response_revision:25355; number_of_response:1; }","duration":"175.312223ms","start":"2026-06-16T04:46:26.229634Z","end":"2026-06-16T04:46:26.404946Z","steps":["trace[1585093878] 'process raft request' (duration: 174.853533ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:48.121762Z","caller":"traceutil/trace.go:171","msg":"trace[1630290705] transaction","detail":"{read_only:false; response_revision:25533; number_of_response:1; }","duration":"100.692159ms","start":"2026-06-16T04:46:48.021021Z","end":"2026-06-16T04:46:48.121713Z","steps":["trace[1630290705] 'process raft request' (duration: 100.432583ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:48.66435Z","caller":"traceutil/trace.go:171","msg":"trace[935344745] transaction","detail":"{read_only:false; response_revision:25534; number_of_response:1; }","duration":"171.980905ms","start":"2026-06-16T04:46:48.492345Z","end":"2026-06-16T04:46:48.664326Z","steps":["trace[935344745] 'process raft request' (duration: 171.813342ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:48.85598Z","caller":"traceutil/trace.go:171","msg":"trace[520425819] transaction","detail":"{read_only:false; response_revision:25535; number_of_response:1; }","duration":"360.568616ms","start":"2026-06-16T04:46:48.495383Z","end":"2026-06-16T04:46:48.855952Z","steps":["trace[520425819] 'process raft request' (duration: 360.341871ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:48.856162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:48.49536Z","time spent":"360.704079ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T04:46:48.856164Z","caller":"traceutil/trace.go:171","msg":"trace[1579664589] transaction","detail":"{read_only:false; response_revision:25536; number_of_response:1; }","duration":"358.650347ms","start":"2026-06-16T04:46:48.497491Z","end":"2026-06-16T04:46:48.856141Z","steps":["trace[1579664589] 'process raft request' (duration: 358.413552ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:48.856284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:48.49748Z","time spent":"358.736339ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T04:46:49.087392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.270919ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-16T04:46:49.087519Z","caller":"traceutil/trace.go:171","msg":"trace[1211887564] linearizableReadLoop","detail":"{readStateIndex:26358; appliedIndex:26355; }","duration":"565.577827ms","start":"2026-06-16T04:46:48.52192Z","end":"2026-06-16T04:46:49.087497Z","steps":["trace[1211887564] 'read index received' (duration: 142.231555ms)","trace[1211887564] 'applied index is now lower than readState.Index' (duration: 423.344042ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:46:49.087747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.421013ms","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-06-16T04:46:49.087747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.805731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-06-16T04:46:49.087757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.604107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-06-16T04:46:49.087786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.825282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-16T04:46:49.087847Z","caller":"traceutil/trace.go:171","msg":"trace[1580412320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25536; }","duration":"426.533415ms","start":"2026-06-16T04:46:48.661293Z","end":"2026-06-16T04:46:49.087827Z","steps":["trace[1580412320] 'agreement among raft nodes before linearized reading' (duration: 426.354122ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:49.087859Z","caller":"traceutil/trace.go:171","msg":"trace[1615467462] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25536; }","duration":"565.972135ms","start":"2026-06-16T04:46:48.521869Z","end":"2026-06-16T04:46:49.087841Z","steps":["trace[1615467462] 'agreement among raft nodes before linearized reading' (duration: 565.691179ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:49.087863Z","caller":"traceutil/trace.go:171","msg":"trace[1021013918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25536; }","duration":"431.73848ms","start":"2026-06-16T04:46:48.65611Z","end":"2026-06-16T04:46:49.087849Z","steps":["trace[1021013918] 'agreement among raft nodes before linearized reading' (duration: 431.554396ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:49.087874Z","caller":"traceutil/trace.go:171","msg":"trace[1653442777] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25536; }","duration":"432.950704ms","start":"2026-06-16T04:46:48.654907Z","end":"2026-06-16T04:46:49.087858Z","steps":["trace[1653442777] 'agreement among raft nodes before linearized reading' (duration: 432.730519ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:49.087896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:48.661286Z","time spent":"426.600856ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-16T04:46:49.087909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:48.656095Z","time spent":"431.802281ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-16T04:46:49.087909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:48.521851Z","time spent":"566.044486ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-16T04:46:49.08793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:48.65489Z","time spent":"433.021276ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-16T04:46:49.232793Z","caller":"traceutil/trace.go:171","msg":"trace[1282772759] transaction","detail":"{read_only:false; response_revision:25537; number_of_response:1; }","duration":"140.895889ms","start":"2026-06-16T04:46:49.091875Z","end":"2026-06-16T04:46:49.232771Z","steps":["trace[1282772759] 'process raft request' (duration: 140.727456ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:49.275843Z","caller":"traceutil/trace.go:171","msg":"trace[546537497] transaction","detail":"{read_only:false; response_revision:25538; number_of_response:1; }","duration":"183.821124ms","start":"2026-06-16T04:46:49.091955Z","end":"2026-06-16T04:46:49.275776Z","steps":["trace[546537497] 'process raft request' (duration: 183.572439ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:49.27592Z","caller":"traceutil/trace.go:171","msg":"trace[84073898] transaction","detail":"{read_only:false; response_revision:25539; number_of_response:1; }","duration":"183.445627ms","start":"2026-06-16T04:46:49.092441Z","end":"2026-06-16T04:46:49.275887Z","steps":["trace[84073898] 'process raft request' (duration: 183.255233ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:49.276241Z","caller":"traceutil/trace.go:171","msg":"trace[1484339204] transaction","detail":"{read_only:false; response_revision:25540; number_of_response:1; }","duration":"183.251074ms","start":"2026-06-16T04:46:49.092942Z","end":"2026-06-16T04:46:49.276193Z","steps":["trace[1484339204] 'process raft request' (duration: 182.814295ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:49.490282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.228834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-06-16T04:46:49.490366Z","caller":"traceutil/trace.go:171","msg":"trace[57804980] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25540; }","duration":"196.360096ms","start":"2026-06-16T04:46:49.293985Z","end":"2026-06-16T04:46:49.490345Z","steps":["trace[57804980] 'range keys from in-memory index tree' (duration: 195.99842ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:49.882976Z","caller":"traceutil/trace.go:171","msg":"trace[1011836168] linearizableReadLoop","detail":"{readStateIndex:26364; appliedIndex:26363; }","duration":"192.742864ms","start":"2026-06-16T04:46:49.690205Z","end":"2026-06-16T04:46:49.882948Z","steps":["trace[1011836168] 'read index received' (duration: 192.482799ms)","trace[1011836168] 'applied index is now lower than readState.Index' (duration: 259.175µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:46:49.883071Z","caller":"traceutil/trace.go:171","msg":"trace[679922336] transaction","detail":"{read_only:false; response_revision:25541; number_of_response:1; }","duration":"239.728231ms","start":"2026-06-16T04:46:49.643315Z","end":"2026-06-16T04:46:49.883044Z","steps":["trace[679922336] 'process raft request' (duration: 239.376634ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:49.883143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.913938ms","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:6"} {"level":"info","ts":"2026-06-16T04:46:49.883192Z","caller":"traceutil/trace.go:171","msg":"trace[485481290] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:25541; }","duration":"193.03018ms","start":"2026-06-16T04:46:49.690146Z","end":"2026-06-16T04:46:49.883176Z","steps":["trace[485481290] 'agreement among raft nodes before linearized reading' (duration: 192.926068ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:50.104185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.424608ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T04:46:50.104405Z","caller":"traceutil/trace.go:171","msg":"trace[895061254] linearizableReadLoop","detail":"{readStateIndex:26367; appliedIndex:26364; }","duration":"221.340431ms","start":"2026-06-16T04:46:49.883047Z","end":"2026-06-16T04:46:50.104388Z","steps":["trace[895061254] 'read index received' (duration: 84.639805ms)","trace[895061254] 'applied index is now lower than readState.Index' (duration: 136.699726ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:46:50.10459Z","caller":"traceutil/trace.go:171","msg":"trace[1982334863] transaction","detail":"{read_only:false; response_revision:25544; number_of_response:1; }","duration":"374.214751ms","start":"2026-06-16T04:46:49.730355Z","end":"2026-06-16T04:46:50.104569Z","steps":["trace[1982334863] 'process raft request' (duration: 373.991046ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:50.104702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:49.730328Z","time spent":"374.316833ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T04:46:50.104706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.099666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-06-16T04:46:50.104625Z","caller":"traceutil/trace.go:171","msg":"trace[2018750438] transaction","detail":"{read_only:false; response_revision:25543; number_of_response:1; }","duration":"374.472436ms","start":"2026-06-16T04:46:49.730108Z","end":"2026-06-16T04:46:50.104581Z","steps":["trace[2018750438] 'process raft request' (duration: 374.175669ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:50.104753Z","caller":"traceutil/trace.go:171","msg":"trace[29005224] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:25544; }","duration":"225.195849ms","start":"2026-06-16T04:46:49.879548Z","end":"2026-06-16T04:46:50.104743Z","steps":["trace[29005224] 'agreement among raft nodes before linearized reading' (duration: 225.024405ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:50.104843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:49.730083Z","time spent":"374.69094ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T04:46:50.10491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.877024ms","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-06-16T04:46:50.104965Z","caller":"traceutil/trace.go:171","msg":"trace[2049671217] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25544; }","duration":"369.951785ms","start":"2026-06-16T04:46:49.734997Z","end":"2026-06-16T04:46:50.104949Z","steps":["trace[2049671217] 'agreement among raft nodes before linearized reading' (duration: 369.579727ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:50.105001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:49.73499Z","time spent":"370.002485ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-16T04:46:50.105279Z","caller":"traceutil/trace.go:171","msg":"trace[1132879787] transaction","detail":"{read_only:false; response_revision:25542; number_of_response:1; }","duration":"378.106909ms","start":"2026-06-16T04:46:49.72627Z","end":"2026-06-16T04:46:50.104377Z","steps":["trace[1132879787] 'process raft request' (duration: 241.397144ms)","trace[1132879787] 'compare' (duration: 136.277366ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:46:50.105469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:49.726247Z","time spent":"379.131239ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T04:46:50.145976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.350112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T04:46:50.146098Z","caller":"traceutil/trace.go:171","msg":"trace[1751024775] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:25545; }","duration":"169.523085ms","start":"2026-06-16T04:46:49.976554Z","end":"2026-06-16T04:46:50.146077Z","steps":["trace[1751024775] 'agreement among raft nodes before linearized reading' (duration: 169.313741ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:50.146167Z","caller":"traceutil/trace.go:171","msg":"trace[1536092583] transaction","detail":"{read_only:false; response_revision:25545; number_of_response:1; }","duration":"217.44774ms","start":"2026-06-16T04:46:49.928677Z","end":"2026-06-16T04:46:50.146125Z","steps":["trace[1536092583] 'process raft request' (duration: 216.873759ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:50.481001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.756273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T04:46:50.481098Z","caller":"traceutil/trace.go:171","msg":"trace[1111673624] linearizableReadLoop","detail":"{readStateIndex:26369; appliedIndex:26368; }","duration":"335.334588ms","start":"2026-06-16T04:46:50.14575Z","end":"2026-06-16T04:46:50.481085Z","steps":["trace[1111673624] 'read index received' (duration: 208.418311ms)","trace[1111673624] 'applied index is now lower than readState.Index' (duration: 126.915577ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:46:50.481189Z","caller":"traceutil/trace.go:171","msg":"trace[1170011199] transaction","detail":"{read_only:false; response_revision:25546; number_of_response:1; }","duration":"373.050627ms","start":"2026-06-16T04:46:50.108131Z","end":"2026-06-16T04:46:50.481181Z","steps":["trace[1170011199] 'process raft request' (duration: 246.045808ms)","trace[1170011199] 'compare' (duration: 126.639371ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:46:50.481232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:50.108102Z","time spent":"373.107908ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T04:46:50.481357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.690275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T04:46:50.481412Z","caller":"traceutil/trace.go:171","msg":"trace[861125596] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25546; }","duration":"271.774617ms","start":"2026-06-16T04:46:50.209624Z","end":"2026-06-16T04:46:50.481399Z","steps":["trace[861125596] 'agreement among raft nodes before linearized reading' (duration: 271.685415ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:50.48159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.174959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-16T04:46:50.481636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.337763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-1947343c-4056-4824-83f2-f76b25d21601\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-06-16T04:46:50.481676Z","caller":"traceutil/trace.go:171","msg":"trace[947490887] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:25546; }","duration":"169.302801ms","start":"2026-06-16T04:46:50.312349Z","end":"2026-06-16T04:46:50.481652Z","steps":["trace[947490887] 'agreement among raft nodes before linearized reading' (duration: 169.136408ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:50.481704Z","caller":"traceutil/trace.go:171","msg":"trace[2079512696] range","detail":"{range_begin:/registry/persistentvolumes/pvc-1947343c-4056-4824-83f2-f76b25d21601; range_end:; response_count:1; response_revision:25546; }","duration":"373.433225ms","start":"2026-06-16T04:46:50.10825Z","end":"2026-06-16T04:46:50.481683Z","steps":["trace[2079512696] 'agreement among raft nodes before linearized reading' (duration: 373.233491ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:50.481759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:50.108234Z","time spent":"373.516436ms","remote":"127.0.0.1:46486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1342,"request content":"key:\"/registry/persistentvolumes/pvc-1947343c-4056-4824-83f2-f76b25d21601\" "} {"level":"info","ts":"2026-06-16T04:46:50.976314Z","caller":"traceutil/trace.go:171","msg":"trace[1265684530] transaction","detail":"{read_only:false; response_revision:25548; number_of_response:1; }","duration":"113.410995ms","start":"2026-06-16T04:46:50.862876Z","end":"2026-06-16T04:46:50.976287Z","steps":["trace[1265684530] 'process raft request' (duration: 113.235972ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:51.332747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.485705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T04:46:51.332891Z","caller":"traceutil/trace.go:171","msg":"trace[2030601646] linearizableReadLoop","detail":"{readStateIndex:26372; appliedIndex:26371; }","duration":"122.305754ms","start":"2026-06-16T04:46:51.210565Z","end":"2026-06-16T04:46:51.332871Z","steps":["trace[2030601646] 'read index received' (duration: 63.251µs)","trace[2030601646] 'applied index is now lower than readState.Index' (duration: 122.241303ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:46:51.332965Z","caller":"traceutil/trace.go:171","msg":"trace[486777086] transaction","detail":"{read_only:false; response_revision:25549; number_of_response:1; }","duration":"469.909569ms","start":"2026-06-16T04:46:50.863015Z","end":"2026-06-16T04:46:51.332925Z","steps":["trace[486777086] 'process raft request' (duration: 163.110758ms)","trace[486777086] 'compare' (duration: 306.308572ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:46:51.333112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.524469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-16T04:46:51.333153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:50.862981Z","time spent":"470.068942ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T04:46:51.333208Z","caller":"traceutil/trace.go:171","msg":"trace[1359602458] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:25549; }","duration":"122.681173ms","start":"2026-06-16T04:46:51.210504Z","end":"2026-06-16T04:46:51.333185Z","steps":["trace[1359602458] 'agreement among raft nodes before linearized reading' (duration: 122.445818ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:51.344612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.478355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-16T04:46:51.344669Z","caller":"traceutil/trace.go:171","msg":"trace[1116285664] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25550; }","duration":"107.566197ms","start":"2026-06-16T04:46:51.237089Z","end":"2026-06-16T04:46:51.344655Z","steps":["trace[1116285664] 'agreement among raft nodes before linearized reading' (duration: 107.373333ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:51.567019Z","caller":"traceutil/trace.go:171","msg":"trace[1235726994] transaction","detail":"{read_only:false; response_revision:25551; number_of_response:1; }","duration":"218.618025ms","start":"2026-06-16T04:46:51.348371Z","end":"2026-06-16T04:46:51.566989Z","steps":["trace[1235726994] 'process raft request' (duration: 136.082392ms)","trace[1235726994] 'compare' (duration: 82.408801ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:46:51.661176Z","caller":"traceutil/trace.go:171","msg":"trace[263890046] linearizableReadLoop","detail":"{readStateIndex:26377; appliedIndex:26374; }","duration":"114.602789ms","start":"2026-06-16T04:46:51.546543Z","end":"2026-06-16T04:46:51.661146Z","steps":["trace[263890046] 'read index received' (duration: 114.234142ms)","trace[263890046] 'applied index is now lower than readState.Index' (duration: 367.407µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:46:51.661486Z","caller":"traceutil/trace.go:171","msg":"trace[1172756687] transaction","detail":"{read_only:false; response_revision:25554; number_of_response:1; }","duration":"312.594418ms","start":"2026-06-16T04:46:51.348852Z","end":"2026-06-16T04:46:51.661446Z","steps":["trace[1172756687] 'process raft request' (duration: 312.249421ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:51.661569Z","caller":"traceutil/trace.go:171","msg":"trace[2130352770] transaction","detail":"{read_only:false; response_revision:25553; number_of_response:1; }","duration":"312.809163ms","start":"2026-06-16T04:46:51.348731Z","end":"2026-06-16T04:46:51.66154Z","steps":["trace[2130352770] 'process raft request' (duration: 312.331533ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:51.661604Z","caller":"traceutil/trace.go:171","msg":"trace[745390945] transaction","detail":"{read_only:false; response_revision:25552; number_of_response:1; }","duration":"312.870483ms","start":"2026-06-16T04:46:51.348653Z","end":"2026-06-16T04:46:51.661523Z","steps":["trace[745390945] 'process raft request' (duration: 312.248811ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:51.66168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.11177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-16T04:46:51.66172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:51.348725Z","time spent":"312.911485ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T04:46:51.661782Z","caller":"traceutil/trace.go:171","msg":"trace[1319497270] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25554; }","duration":"115.252382ms","start":"2026-06-16T04:46:51.546494Z","end":"2026-06-16T04:46:51.661746Z","steps":["trace[1319497270] 'agreement among raft nodes before linearized reading' (duration: 115.083648ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:46:51.661879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:51.348638Z","time spent":"313.110319ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T04:46:51.66165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:46:51.348826Z","time spent":"312.735421ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T04:46:52.221311Z","caller":"traceutil/trace.go:171","msg":"trace[715222342] transaction","detail":"{read_only:false; response_revision:25557; number_of_response:1; }","duration":"110.815592ms","start":"2026-06-16T04:46:52.110469Z","end":"2026-06-16T04:46:52.221284Z","steps":["trace[715222342] 'process raft request' (duration: 110.644069ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:52.270558Z","caller":"traceutil/trace.go:171","msg":"trace[1043666844] transaction","detail":"{read_only:false; response_revision:25558; number_of_response:1; }","duration":"160.056035ms","start":"2026-06-16T04:46:52.110474Z","end":"2026-06-16T04:46:52.27053Z","steps":["trace[1043666844] 'process raft request' (duration: 159.848861ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:52.270857Z","caller":"traceutil/trace.go:171","msg":"trace[1600554444] transaction","detail":"{read_only:false; response_revision:25559; number_of_response:1; }","duration":"159.717919ms","start":"2026-06-16T04:46:52.111108Z","end":"2026-06-16T04:46:52.270826Z","steps":["trace[1600554444] 'process raft request' (duration: 159.358282ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:52.271048Z","caller":"traceutil/trace.go:171","msg":"trace[320973393] transaction","detail":"{read_only:false; response_revision:25560; number_of_response:1; }","duration":"119.953247ms","start":"2026-06-16T04:46:52.151084Z","end":"2026-06-16T04:46:52.271037Z","steps":["trace[320973393] 'process raft request' (duration: 119.638361ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:46:52.802681Z","caller":"traceutil/trace.go:171","msg":"trace[27557152] transaction","detail":"{read_only:false; response_revision:25562; number_of_response:1; }","duration":"119.166402ms","start":"2026-06-16T04:46:52.683472Z","end":"2026-06-16T04:46:52.802639Z","steps":["trace[27557152] 'process raft request' (duration: 118.920617ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:47:08.909464Z","caller":"traceutil/trace.go:171","msg":"trace[1504526162] linearizableReadLoop","detail":"{readStateIndex:26517; appliedIndex:26516; }","duration":"166.486264ms","start":"2026-06-16T04:47:08.742954Z","end":"2026-06-16T04:47:08.90944Z","steps":["trace[1504526162] 'read index received' (duration: 166.348472ms)","trace[1504526162] 'applied index is now lower than readState.Index' (duration: 136.852µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:47:08.909516Z","caller":"traceutil/trace.go:171","msg":"trace[374332351] transaction","detail":"{read_only:false; response_revision:25690; number_of_response:1; }","duration":"171.523906ms","start":"2026-06-16T04:47:08.737965Z","end":"2026-06-16T04:47:08.909489Z","steps":["trace[374332351] 'process raft request' (duration: 171.321042ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:47:08.909703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.729009ms","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-06-16T04:47:08.90975Z","caller":"traceutil/trace.go:171","msg":"trace[350626953] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25690; }","duration":"166.810001ms","start":"2026-06-16T04:47:08.742927Z","end":"2026-06-16T04:47:08.909737Z","steps":["trace[350626953] 'agreement among raft nodes before linearized reading' (duration: 166.643888ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:47:09.028352Z","caller":"traceutil/trace.go:171","msg":"trace[1564752389] linearizableReadLoop","detail":"{readStateIndex:26518; appliedIndex:26517; }","duration":"118.788015ms","start":"2026-06-16T04:47:08.909543Z","end":"2026-06-16T04:47:09.028331Z","steps":["trace[1564752389] 'read index received' (duration: 68.136834ms)","trace[1564752389] 'applied index is now lower than readState.Index' (duration: 50.650501ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:47:09.02849Z","caller":"traceutil/trace.go:171","msg":"trace[1120488837] transaction","detail":"{read_only:false; response_revision:25691; number_of_response:1; }","duration":"168.79411ms","start":"2026-06-16T04:47:08.859624Z","end":"2026-06-16T04:47:09.028418Z","steps":["trace[1120488837] 'process raft request' (duration: 118.019858ms)","trace[1120488837] 'compare' (duration: 50.601639ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:47:09.028559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.014212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7914"} {"level":"info","ts":"2026-06-16T04:47:09.028599Z","caller":"traceutil/trace.go:171","msg":"trace[66625280] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:25691; }","duration":"204.113653ms","start":"2026-06-16T04:47:08.824473Z","end":"2026-06-16T04:47:09.028587Z","steps":["trace[66625280] 'agreement among raft nodes before linearized reading' (duration: 203.944809ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:47:09.215985Z","caller":"traceutil/trace.go:171","msg":"trace[1576162176] transaction","detail":"{read_only:false; response_revision:25692; number_of_response:1; }","duration":"164.648517ms","start":"2026-06-16T04:47:09.0513Z","end":"2026-06-16T04:47:09.215948Z","steps":["trace[1576162176] 'process raft request' (duration: 116.61496ms)","trace[1576162176] 'compare' (duration: 47.799633ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:47:09.719155Z","caller":"traceutil/trace.go:171","msg":"trace[1329335859] transaction","detail":"{read_only:false; response_revision:25694; number_of_response:1; }","duration":"145.690326ms","start":"2026-06-16T04:47:09.573449Z","end":"2026-06-16T04:47:09.71914Z","steps":["trace[1329335859] 'process raft request' (duration: 145.245878ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:47:19.491958Z","caller":"traceutil/trace.go:171","msg":"trace[726478622] transaction","detail":"{read_only:false; response_revision:25766; number_of_response:1; }","duration":"241.014426ms","start":"2026-06-16T04:47:19.250916Z","end":"2026-06-16T04:47:19.49193Z","steps":["trace[726478622] 'process raft request' (duration: 240.764021ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:47:19.782374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.929791ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T04:47:19.782486Z","caller":"traceutil/trace.go:171","msg":"trace[1036244363] transaction","detail":"{read_only:false; response_revision:25767; number_of_response:1; }","duration":"254.279974ms","start":"2026-06-16T04:47:19.528187Z","end":"2026-06-16T04:47:19.782466Z","steps":["trace[1036244363] 'process raft request' (duration: 107.167809ms)","trace[1036244363] 'compare' (duration: 146.801108ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:47:20.201159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.019146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-16T04:47:20.201318Z","caller":"traceutil/trace.go:171","msg":"trace[391015202] transaction","detail":"{read_only:false; response_revision:25768; number_of_response:1; }","duration":"362.898732ms","start":"2026-06-16T04:47:19.838391Z","end":"2026-06-16T04:47:20.20129Z","steps":["trace[391015202] 'process raft request' (duration: 189.61824ms)","trace[391015202] 'compare' (duration: 172.850373ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:47:20.201377Z","caller":"traceutil/trace.go:171","msg":"trace[2073600370] transaction","detail":"{read_only:false; response_revision:25769; number_of_response:1; }","duration":"171.816572ms","start":"2026-06-16T04:47:20.029546Z","end":"2026-06-16T04:47:20.201363Z","steps":["trace[2073600370] 'process raft request' (duration: 171.737081ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:47:20.201413Z","caller":"traceutil/trace.go:171","msg":"trace[1056467243] linearizableReadLoop","detail":"{readStateIndex:26597; appliedIndex:26596; }","duration":"349.472472ms","start":"2026-06-16T04:47:19.851892Z","end":"2026-06-16T04:47:20.201364Z","steps":["trace[1056467243] 'read index received' (duration: 176.070958ms)","trace[1056467243] 'applied index is now lower than readState.Index' (duration: 173.396634ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T04:47:20.201415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:47:19.838374Z","time spent":"362.994894ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T04:47:20.201772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.85781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-16T04:47:20.201887Z","caller":"traceutil/trace.go:171","msg":"trace[344739647] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25769; }","duration":"350.108105ms","start":"2026-06-16T04:47:19.851755Z","end":"2026-06-16T04:47:20.201863Z","steps":["trace[344739647] 'agreement among raft nodes before linearized reading' (duration: 349.709567ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:47:20.201939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:47:19.851719Z","time spent":"350.210727ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-16T04:47:20.201959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.206786ms","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-06-16T04:47:20.20201Z","caller":"traceutil/trace.go:171","msg":"trace[1617635297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25769; }","duration":"346.302888ms","start":"2026-06-16T04:47:19.855688Z","end":"2026-06-16T04:47:20.201991Z","steps":["trace[1617635297] 'agreement among raft nodes before linearized reading' (duration: 346.056753ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:47:20.202044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.249686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-06-16T04:47:20.202058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:47:19.855681Z","time spent":"346.367909ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-16T04:47:20.202097Z","caller":"traceutil/trace.go:171","msg":"trace[219117755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25769; }","duration":"345.368149ms","start":"2026-06-16T04:47:19.856716Z","end":"2026-06-16T04:47:20.202084Z","steps":["trace[219117755] 'agreement among raft nodes before linearized reading' (duration: 345.196496ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:47:20.20213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:47:19.856652Z","time spent":"345.471191ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-16T04:47:20.794263Z","caller":"traceutil/trace.go:171","msg":"trace[1811939903] linearizableReadLoop","detail":"{readStateIndex:26601; appliedIndex:26598; }","duration":"583.209412ms","start":"2026-06-16T04:47:20.211028Z","end":"2026-06-16T04:47:20.794238Z","steps":["trace[1811939903] 'read index received' (duration: 81.190707ms)","trace[1811939903] 'applied index is now lower than readState.Index' (duration: 502.017805ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:47:20.794265Z","caller":"traceutil/trace.go:171","msg":"trace[1098188324] transaction","detail":"{read_only:false; response_revision:25771; number_of_response:1; }","duration":"587.398907ms","start":"2026-06-16T04:47:20.206834Z","end":"2026-06-16T04:47:20.794233Z","steps":["trace[1098188324] 'process raft request' (duration: 503.19822ms)","trace[1098188324] 'compare' (duration: 83.705077ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:47:20.794324Z","caller":"traceutil/trace.go:171","msg":"trace[1880101027] transaction","detail":"{read_only:false; response_revision:25772; number_of_response:1; }","duration":"587.002309ms","start":"2026-06-16T04:47:20.207264Z","end":"2026-06-16T04:47:20.794266Z","steps":["trace[1880101027] 'process raft request' (duration: 586.855326ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:47:20.794413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.375445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-16T04:47:20.794459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:47:20.206808Z","time spent":"587.57079ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T04:47:20.794478Z","caller":"traceutil/trace.go:171","msg":"trace[1464178588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25772; }","duration":"583.483787ms","start":"2026-06-16T04:47:20.210983Z","end":"2026-06-16T04:47:20.794466Z","steps":["trace[1464178588] 'agreement among raft nodes before linearized reading' (duration: 583.373924ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:47:20.794482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:47:20.207245Z","time spent":"587.151231ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T04:47:20.794523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:47:20.210971Z","time spent":"583.540278ms","remote":"127.0.0.1:46340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-16T04:47:20.797262Z","caller":"traceutil/trace.go:171","msg":"trace[1042436833] transaction","detail":"{read_only:false; response_revision:25773; number_of_response:1; }","duration":"472.091113ms","start":"2026-06-16T04:47:20.325085Z","end":"2026-06-16T04:47:20.797176Z","steps":["trace[1042436833] 'process raft request' (duration: 471.95483ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:47:20.797381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:47:20.325063Z","time spent":"472.251697ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T04:47:20.797512Z","caller":"traceutil/trace.go:171","msg":"trace[233005900] transaction","detail":"{read_only:false; response_revision:25774; number_of_response:1; }","duration":"427.102716ms","start":"2026-06-16T04:47:20.370387Z","end":"2026-06-16T04:47:20.797489Z","steps":["trace[233005900] 'process raft request' (duration: 426.750459ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:47:20.797617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:47:20.370367Z","time spent":"427.201668ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T04:47:20.797662Z","caller":"traceutil/trace.go:171","msg":"trace[1960283336] transaction","detail":"{read_only:false; response_revision:25775; number_of_response:1; }","duration":"405.501142ms","start":"2026-06-16T04:47:20.392148Z","end":"2026-06-16T04:47:20.797649Z","steps":["trace[1960283336] 'process raft request' (duration: 405.166565ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:47:20.797718Z","caller":"traceutil/trace.go:171","msg":"trace[1647281332] transaction","detail":"{read_only:false; response_revision:25776; number_of_response:1; }","duration":"404.700155ms","start":"2026-06-16T04:47:20.393Z","end":"2026-06-16T04:47:20.7977Z","steps":["trace[1647281332] 'process raft request' (duration: 404.394999ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:47:20.797644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.127201ms","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-06-16T04:47:20.797781Z","caller":"traceutil/trace.go:171","msg":"trace[995687960] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25776; }","duration":"120.346045ms","start":"2026-06-16T04:47:20.677419Z","end":"2026-06-16T04:47:20.797765Z","steps":["trace[995687960] 'agreement among raft nodes before linearized reading' (duration: 120.065569ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:47:20.797828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:47:20.392987Z","time spent":"404.779806ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T04:47:20.797759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.675192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-16T04:47:20.797734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:47:20.392131Z","time spent":"405.568773ms","remote":"127.0.0.1:46610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T04:47:20.798005Z","caller":"traceutil/trace.go:171","msg":"trace[1255186159] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:25776; }","duration":"521.11259ms","start":"2026-06-16T04:47:20.276874Z","end":"2026-06-16T04:47:20.797987Z","steps":["trace[1255186159] 'agreement among raft nodes before linearized reading' (duration: 520.61779ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:47:20.798195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.897548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-16T04:47:20.798212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T04:47:20.276854Z","time spent":"521.302445ms","remote":"127.0.0.1:46414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-06-16T04:47:20.79826Z","caller":"traceutil/trace.go:171","msg":"trace[324644917] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25776; }","duration":"271.038812ms","start":"2026-06-16T04:47:20.527205Z","end":"2026-06-16T04:47:20.798243Z","steps":["trace[324644917] 'agreement among raft nodes before linearized reading' (duration: 270.92022ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:47:40.160734Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23078} {"level":"info","ts":"2026-06-16T04:47:40.299004Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23078,"took":"135.063842ms","hash":1192549344,"current-db-size-bytes":58789888,"current-db-size":"59 MB","current-db-size-in-use-bytes":36216832,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-06-16T04:47:40.299055Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1192549344,"revision":23078,"compact-revision":20189} {"level":"info","ts":"2026-06-16T04:47:42.608102Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068c2.wal"} {"level":"info","ts":"2026-06-16T04:50:47.882369Z","caller":"traceutil/trace.go:171","msg":"trace[2106337525] transaction","detail":"{read_only:false; response_revision:27834; number_of_response:1; }","duration":"121.190807ms","start":"2026-06-16T04:50:47.761144Z","end":"2026-06-16T04:50:47.882335Z","steps":["trace[2106337525] 'process raft request' (duration: 120.982403ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:50:47.907258Z","caller":"traceutil/trace.go:171","msg":"trace[241683221] transaction","detail":"{read_only:false; response_revision:27835; number_of_response:1; }","duration":"145.990608ms","start":"2026-06-16T04:50:47.761233Z","end":"2026-06-16T04:50:47.907223Z","steps":["trace[241683221] 'process raft request' (duration: 145.688592ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:52:40.169517Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25915} {"level":"info","ts":"2026-06-16T04:52:40.310156Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25915,"took":"134.373084ms","hash":1271236210,"current-db-size-bytes":58789888,"current-db-size":"59 MB","current-db-size-in-use-bytes":37793792,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-06-16T04:52:40.310262Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1271236210,"revision":25915,"compact-revision":23078} {"level":"info","ts":"2026-06-16T04:53:20.577148Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"572db18442eba1c2","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-16T04:53:20.581981Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-16T04:53:20.582078Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-16T04:56:14.131835Z","caller":"traceutil/trace.go:171","msg":"trace[934908294] transaction","detail":"{read_only:false; response_revision:30385; number_of_response:1; }","duration":"295.211164ms","start":"2026-06-16T04:56:13.83654Z","end":"2026-06-16T04:56:14.131751Z","steps":["trace[934908294] 'process raft request' (duration: 294.883537ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:56:14.132082Z","caller":"traceutil/trace.go:171","msg":"trace[630135325] linearizableReadLoop","detail":"{readStateIndex:31342; appliedIndex:31339; }","duration":"219.362121ms","start":"2026-06-16T04:56:13.912683Z","end":"2026-06-16T04:56:14.132045Z","steps":["trace[630135325] 'read index received' (duration: 218.618886ms)","trace[630135325] 'applied index is now lower than readState.Index' (duration: 741.015µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T04:56:14.132174Z","caller":"traceutil/trace.go:171","msg":"trace[555230698] transaction","detail":"{read_only:false; response_revision:30387; number_of_response:1; }","duration":"277.27053ms","start":"2026-06-16T04:56:13.85486Z","end":"2026-06-16T04:56:14.13213Z","steps":["trace[555230698] 'process raft request' (duration: 277.063866ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:56:14.132308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.611067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"warn","ts":"2026-06-16T04:56:14.132322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.772127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-16T04:56:14.132389Z","caller":"traceutil/trace.go:171","msg":"trace[1722292447] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:30387; }","duration":"219.756449ms","start":"2026-06-16T04:56:13.912618Z","end":"2026-06-16T04:56:14.132374Z","steps":["trace[1722292447] 'agreement among raft nodes before linearized reading' (duration: 219.543755ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:56:14.132416Z","caller":"traceutil/trace.go:171","msg":"trace[1521657605] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:30387; }","duration":"205.94349ms","start":"2026-06-16T04:56:13.926444Z","end":"2026-06-16T04:56:14.132388Z","steps":["trace[1521657605] 'agreement among raft nodes before linearized reading' (duration: 205.768747ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T04:56:14.132539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.11608ms","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-06-16T04:56:14.132661Z","caller":"traceutil/trace.go:171","msg":"trace[1143711655] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:30387; }","duration":"185.260912ms","start":"2026-06-16T04:56:13.947373Z","end":"2026-06-16T04:56:14.132633Z","steps":["trace[1143711655] 'agreement among raft nodes before linearized reading' (duration: 184.920205ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:56:14.132667Z","caller":"traceutil/trace.go:171","msg":"trace[1382914912] transaction","detail":"{read_only:false; response_revision:30386; number_of_response:1; }","duration":"294.990519ms","start":"2026-06-16T04:56:13.837043Z","end":"2026-06-16T04:56:14.132034Z","steps":["trace[1382914912] 'process raft request' (duration: 294.630202ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T04:57:40.178376Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28784} {"level":"info","ts":"2026-06-16T04:57:40.349058Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28784,"took":"166.249159ms","hash":1562758465,"current-db-size-bytes":58789888,"current-db-size":"59 MB","current-db-size-in-use-bytes":31793152,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-16T04:57:40.349138Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1562758465,"revision":28784,"compact-revision":25915} {"level":"info","ts":"2026-06-16T05:02:40.186743Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31008} {"level":"info","ts":"2026-06-16T05:02:40.320079Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31008,"took":"125.212434ms","hash":1507233828,"current-db-size-bytes":58789888,"current-db-size":"59 MB","current-db-size-in-use-bytes":31657984,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-16T05:02:40.320131Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1507233828,"revision":31008,"compact-revision":28784} {"level":"info","ts":"2026-06-16T05:07:40.195332Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33339} {"level":"info","ts":"2026-06-16T05:07:40.367023Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33339,"took":"166.272534ms","hash":415456015,"current-db-size-bytes":60674048,"current-db-size":"61 MB","current-db-size-in-use-bytes":32231424,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-16T05:07:40.367123Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":415456015,"revision":33339,"compact-revision":31008} {"level":"info","ts":"2026-06-16T05:12:40.204462Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35668} {"level":"info","ts":"2026-06-16T05:12:40.35688Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35668,"took":"147.358971ms","hash":208089436,"current-db-size-bytes":61992960,"current-db-size":"62 MB","current-db-size-in-use-bytes":32317440,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-16T05:12:40.356946Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":208089436,"revision":35668,"compact-revision":33339} {"level":"info","ts":"2026-06-16T05:14:38.959543Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"572db18442eba1c2","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-16T05:14:38.964577Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-16T05:14:38.964665Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-06-16T05:17:40.218002Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37894} {"level":"info","ts":"2026-06-16T05:17:40.331637Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37894,"took":"110.155759ms","hash":3479524399,"current-db-size-bytes":63258624,"current-db-size":"63 MB","current-db-size-in-use-bytes":32620544,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-16T05:17:40.331719Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3479524399,"revision":37894,"compact-revision":35668}