{"level":"warn","ts":"2026-08-06T07:03:07.039495Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-06T07:03:07.039583Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.222: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.222:2380","--initial-cluster=instance=https://199.204.45.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.222: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-08-06T07:03:07.039646Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-06T07:03:07.039653Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-08-06T07:03:07.03967Z","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-08-06T07:03:07.040238Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"]} {"level":"info","ts":"2026-08-06T07:03:07.040298Z","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.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.222: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-08-06T07:03:07.042592Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.106252ms"} {"level":"info","ts":"2026-08-06T07:03:07.056427Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"380f6760faea5f9e","cluster-id":"b87e8aca83a17eab"} {"level":"info","ts":"2026-08-06T07:03:07.056535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=()"} {"level":"info","ts":"2026-08-06T07:03:07.056561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 0"} {"level":"info","ts":"2026-08-06T07:03:07.056578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 380f6760faea5f9e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-06T07:03:07.056597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 1"} {"level":"info","ts":"2026-08-06T07:03:07.056644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"warn","ts":"2026-08-06T07:03:07.06046Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-06T07:03:07.061713Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-06T07:03:07.063558Z","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-08-06T07:03:07.066217Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"380f6760faea5f9e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-06T07:03:07.066378Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"380f6760faea5f9e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-06T07:03:07.066631Z","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-08-06T07:03:07.066744Z","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-08-06T07:03:07.066788Z","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-08-06T07:03:07.068826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"info","ts":"2026-08-06T07:03:07.069216Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","added-peer-id":"380f6760faea5f9e","added-peer-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-08-06T07:03:07.070459Z","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-08-06T07:03:07.070582Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-08-06T07:03:07.070662Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-08-06T07:03:07.070717Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"380f6760faea5f9e","initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-06T07:03:07.070765Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-06T07:03:08.057786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e is starting a new election at term 1"} {"level":"info","ts":"2026-08-06T07:03:08.057846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became pre-candidate at term 1"} {"level":"info","ts":"2026-08-06T07:03:08.057885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgPreVoteResp from 380f6760faea5f9e at term 1"} {"level":"info","ts":"2026-08-06T07:03:08.057906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became candidate at term 2"} {"level":"info","ts":"2026-08-06T07:03:08.057916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgVoteResp from 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-08-06T07:03:08.057984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became leader at term 2"} {"level":"info","ts":"2026-08-06T07:03:08.058005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 380f6760faea5f9e elected leader 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-08-06T07:03:08.059228Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T07:03:08.060113Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-06T07:03:08.060114Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"380f6760faea5f9e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.222:2379]}","request-path":"/0/members/380f6760faea5f9e/attributes","cluster-id":"b87e8aca83a17eab","publish-timeout":"7s"} {"level":"info","ts":"2026-08-06T07:03:08.060179Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-06T07:03:08.060461Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-06T07:03:08.060499Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-06T07:03:08.060804Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T07:03:08.060928Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T07:03:08.060973Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T07:03:08.063745Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-06T07:03:08.064076Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.222:2379"} {"level":"warn","ts":"2026-08-06T07:03:21.974048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.835079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-06T07:03:21.974164Z","caller":"traceutil/trace.go:171","msg":"trace[427456364] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:368; }","duration":"170.008442ms","start":"2026-08-06T07:03:21.804127Z","end":"2026-08-06T07:03:21.974136Z","steps":["trace[427456364] 'range keys from in-memory index tree' (duration: 169.738897ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:03:22.126179Z","caller":"traceutil/trace.go:171","msg":"trace[1415810319] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"148.292926ms","start":"2026-08-06T07:03:21.977856Z","end":"2026-08-06T07:03:22.126149Z","steps":["trace[1415810319] 'process raft request' (duration: 148.042471ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:03:22.41098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.172369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T07:03:22.411075Z","caller":"traceutil/trace.go:171","msg":"trace[1897339387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:369; }","duration":"192.341362ms","start":"2026-08-06T07:03:22.218713Z","end":"2026-08-06T07:03:22.411054Z","steps":["trace[1897339387] 'range keys from in-memory index tree' (duration: 192.089837ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:03:45.120012Z","caller":"traceutil/trace.go:171","msg":"trace[1975991247] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"117.338348ms","start":"2026-08-06T07:03:45.002613Z","end":"2026-08-06T07:03:45.119951Z","steps":["trace[1975991247] 'process raft request' (duration: 117.183725ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:03:45.126197Z","caller":"traceutil/trace.go:171","msg":"trace[652260464] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"117.289187ms","start":"2026-08-06T07:03:45.007774Z","end":"2026-08-06T07:03:45.125064Z","steps":["trace[652260464] 'process raft request' (duration: 117.139854ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:03:45.126354Z","caller":"traceutil/trace.go:171","msg":"trace[1304648559] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"110.372954ms","start":"2026-08-06T07:03:45.015796Z","end":"2026-08-06T07:03:45.126169Z","steps":["trace[1304648559] 'process raft request' (duration: 109.152729ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:03:45.126427Z","caller":"traceutil/trace.go:171","msg":"trace[270210050] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"109.22796ms","start":"2026-08-06T07:03:45.015835Z","end":"2026-08-06T07:03:45.125063Z","steps":["trace[270210050] 'process raft request' (duration: 109.152269ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:03:45.126185Z","caller":"traceutil/trace.go:171","msg":"trace[1912661851] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"117.399389ms","start":"2026-08-06T07:03:45.007637Z","end":"2026-08-06T07:03:45.125036Z","steps":["trace[1912661851] 'process raft request' (duration: 117.170254ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:03:45.126414Z","caller":"traceutil/trace.go:171","msg":"trace[306334495] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"107.787761ms","start":"2026-08-06T07:03:45.018429Z","end":"2026-08-06T07:03:45.126216Z","steps":["trace[306334495] 'process raft request' (duration: 106.609277ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:03:45.127326Z","caller":"traceutil/trace.go:171","msg":"trace[666858995] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"108.154078ms","start":"2026-08-06T07:03:45.018802Z","end":"2026-08-06T07:03:45.126956Z","steps":["trace[666858995] 'process raft request' (duration: 107.017575ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:03:45.12998Z","caller":"traceutil/trace.go:171","msg":"trace[1194139958] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"108.613027ms","start":"2026-08-06T07:03:45.021307Z","end":"2026-08-06T07:03:45.12992Z","steps":["trace[1194139958] 'process raft request' (duration: 107.232469ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:03:45.130128Z","caller":"traceutil/trace.go:171","msg":"trace[68289758] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"101.700895ms","start":"2026-08-06T07:03:45.028394Z","end":"2026-08-06T07:03:45.130095Z","steps":["trace[68289758] 'process raft request' (duration: 101.403699ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:03:45.13091Z","caller":"traceutil/trace.go:171","msg":"trace[268515144] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"102.237347ms","start":"2026-08-06T07:03:45.028482Z","end":"2026-08-06T07:03:45.13072Z","steps":["trace[268515144] 'process raft request' (duration: 101.805027ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:03:50.262624Z","caller":"traceutil/trace.go:171","msg":"trace[793585550] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"153.552434ms","start":"2026-08-06T07:03:50.109039Z","end":"2026-08-06T07:03:50.262591Z","steps":["trace[793585550] 'process raft request' (duration: 86.201795ms)","trace[793585550] 'compare' (duration: 67.201825ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:03:50.323594Z","caller":"traceutil/trace.go:171","msg":"trace[928925846] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:605; }","duration":"103.808018ms","start":"2026-08-06T07:03:50.219761Z","end":"2026-08-06T07:03:50.323569Z","steps":["trace[928925846] 'read index received' (duration: 103.800108ms)","trace[928925846] 'applied index is now lower than readState.Index' (duration: 6.03µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:03:50.323587Z","caller":"traceutil/trace.go:171","msg":"trace[850097137] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"123.053095ms","start":"2026-08-06T07:03:50.200506Z","end":"2026-08-06T07:03:50.323559Z","steps":["trace[850097137] 'process raft request' (duration: 122.911562ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:03:50.323812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.022123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T07:03:50.323913Z","caller":"traceutil/trace.go:171","msg":"trace[1220391438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"104.132295ms","start":"2026-08-06T07:03:50.219754Z","end":"2026-08-06T07:03:50.323886Z","steps":["trace[1220391438] 'agreement among raft nodes before linearized reading' (duration: 103.954021ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:03:51.36514Z","caller":"traceutil/trace.go:171","msg":"trace[832266903] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"144.734063ms","start":"2026-08-06T07:03:51.220383Z","end":"2026-08-06T07:03:51.365117Z","steps":["trace[832266903] 'process raft request' (duration: 144.287144ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:03:51.537024Z","caller":"traceutil/trace.go:171","msg":"trace[1196114930] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"110.368834ms","start":"2026-08-06T07:03:51.426626Z","end":"2026-08-06T07:03:51.536995Z","steps":["trace[1196114930] 'process raft request' (duration: 98.99075ms)","trace[1196114930] 'compare' (duration: 11.247791ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:03:52.126716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.879568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-06T07:03:52.126802Z","caller":"traceutil/trace.go:171","msg":"trace[320400413] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:594; }","duration":"333.025072ms","start":"2026-08-06T07:03:51.793762Z","end":"2026-08-06T07:03:52.126788Z","steps":["trace[320400413] 'range keys from in-memory index tree' (duration: 332.713105ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:03:52.126836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:03:51.793742Z","time spent":"333.084922ms","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-06T07:03:52.126988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.606921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-chqnv\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-06T07:03:52.127048Z","caller":"traceutil/trace.go:171","msg":"trace[1890145011] range","detail":"{range_begin:/registry/pods/kube-system/cilium-chqnv; range_end:; response_count:1; response_revision:594; }","duration":"412.699973ms","start":"2026-08-06T07:03:51.714335Z","end":"2026-08-06T07:03:52.127035Z","steps":["trace[1890145011] 'range keys from in-memory index tree' (duration: 412.481049ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:03:52.127081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:03:51.714316Z","time spent":"412.757834ms","remote":"127.0.0.1:60862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-chqnv\" "} {"level":"warn","ts":"2026-08-06T07:03:52.12724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.879256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-06T07:03:52.127403Z","caller":"traceutil/trace.go:171","msg":"trace[2122804308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:594; }","duration":"361.079189ms","start":"2026-08-06T07:03:51.766296Z","end":"2026-08-06T07:03:52.127375Z","steps":["trace[2122804308] 'range keys from in-memory index tree' (duration: 360.63068ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:03:52.127562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:03:51.766278Z","time spent":"361.267714ms","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-06T07:03:52.128604Z","caller":"traceutil/trace.go:171","msg":"trace[1324470661] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"164.98396ms","start":"2026-08-06T07:03:51.963588Z","end":"2026-08-06T07:03:52.128572Z","steps":["trace[1324470661] 'process raft request' (duration: 157.505325ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:04:07.457234Z","caller":"traceutil/trace.go:171","msg":"trace[1332972604] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"153.094533ms","start":"2026-08-06T07:04:07.304104Z","end":"2026-08-06T07:04:07.457198Z","steps":["trace[1332972604] 'process raft request' (duration: 152.812938ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:04:17.61426Z","caller":"traceutil/trace.go:171","msg":"trace[1254665363] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"108.659736ms","start":"2026-08-06T07:04:17.505578Z","end":"2026-08-06T07:04:17.614237Z","steps":["trace[1254665363] 'process raft request' (duration: 108.549044ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:06:08.613903Z","caller":"traceutil/trace.go:171","msg":"trace[1140605299] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"101.781656ms","start":"2026-08-06T07:06:08.512096Z","end":"2026-08-06T07:06:08.613877Z","steps":["trace[1140605299] 'process raft request' (duration: 101.628613ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:06:08.787055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.752746ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T07:06:08.787175Z","caller":"traceutil/trace.go:171","msg":"trace[1530605639] linearizableReadLoop","detail":"{readStateIndex:2207; appliedIndex:2206; }","duration":"155.211061ms","start":"2026-08-06T07:06:08.631945Z","end":"2026-08-06T07:06:08.787156Z","steps":["trace[1530605639] 'read index received' (duration: 21.700799ms)","trace[1530605639] 'applied index is now lower than readState.Index' (duration: 133.508481ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:06:08.787288Z","caller":"traceutil/trace.go:171","msg":"trace[712390741] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"247.604242ms","start":"2026-08-06T07:06:08.539575Z","end":"2026-08-06T07:06:08.78718Z","steps":["trace[712390741] 'process raft request' (duration: 114.06078ms)","trace[712390741] 'compare' (duration: 132.636894ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:06:08.787348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.404335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12383"} {"level":"info","ts":"2026-08-06T07:06:08.78739Z","caller":"traceutil/trace.go:171","msg":"trace[1976648607] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2127; }","duration":"155.479417ms","start":"2026-08-06T07:06:08.631898Z","end":"2026-08-06T07:06:08.787377Z","steps":["trace[1976648607] 'agreement among raft nodes before linearized reading' (duration: 155.314193ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:06:08.947356Z","caller":"traceutil/trace.go:171","msg":"trace[872521239] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"103.767797ms","start":"2026-08-06T07:06:08.843562Z","end":"2026-08-06T07:06:08.947329Z","steps":["trace[872521239] 'process raft request' (duration: 103.623934ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:06:08.948648Z","caller":"traceutil/trace.go:171","msg":"trace[1555497824] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"102.174923ms","start":"2026-08-06T07:06:08.846439Z","end":"2026-08-06T07:06:08.948614Z","steps":["trace[1555497824] 'process raft request' (duration: 101.886437ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:06:48.951662Z","caller":"traceutil/trace.go:171","msg":"trace[1501741465] transaction","detail":"{read_only:false; response_revision:2520; number_of_response:1; }","duration":"200.443797ms","start":"2026-08-06T07:06:48.751187Z","end":"2026-08-06T07:06:48.951631Z","steps":["trace[1501741465] 'process raft request' (duration: 200.289604ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:07:23.680132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.018829ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T07:07:23.680315Z","caller":"traceutil/trace.go:171","msg":"trace[1599459539] linearizableReadLoop","detail":"{readStateIndex:2834; appliedIndex:2832; }","duration":"260.676478ms","start":"2026-08-06T07:07:23.419623Z","end":"2026-08-06T07:07:23.680299Z","steps":["trace[1599459539] 'read index received' (duration: 58.428504ms)","trace[1599459539] 'applied index is now lower than readState.Index' (duration: 202.247064ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:07:23.680397Z","caller":"traceutil/trace.go:171","msg":"trace[684268018] transaction","detail":"{read_only:false; response_revision:2729; number_of_response:1; }","duration":"315.876986ms","start":"2026-08-06T07:07:23.36451Z","end":"2026-08-06T07:07:23.680387Z","steps":["trace[684268018] 'process raft request' (duration: 315.726863ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:07:23.680445Z","caller":"traceutil/trace.go:171","msg":"trace[195964662] transaction","detail":"{read_only:false; response_revision:2728; number_of_response:1; }","duration":"320.910239ms","start":"2026-08-06T07:07:23.359513Z","end":"2026-08-06T07:07:23.680423Z","steps":["trace[195964662] 'process raft request' (duration: 118.520633ms)","trace[195964662] 'compare' (duration: 201.863376ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:07:23.680451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:07:23.364488Z","time spent":"315.931527ms","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T07:07:23.680582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:07:23.359489Z","time spent":"321.042151ms","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T07:07:23.68093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.276589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-06T07:07:23.681039Z","caller":"traceutil/trace.go:171","msg":"trace[616704189] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2729; }","duration":"261.436763ms","start":"2026-08-06T07:07:23.419574Z","end":"2026-08-06T07:07:23.681011Z","steps":["trace[616704189] 'agreement among raft nodes before linearized reading' (duration: 261.101766ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:07:23.681044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.791852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-06T07:07:23.681146Z","caller":"traceutil/trace.go:171","msg":"trace[1853398112] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2729; }","duration":"218.957295ms","start":"2026-08-06T07:07:23.462164Z","end":"2026-08-06T07:07:23.681122Z","steps":["trace[1853398112] 'agreement among raft nodes before linearized reading' (duration: 218.619878ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:07:23.681277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.217857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-06T07:07:23.681332Z","caller":"traceutil/trace.go:171","msg":"trace[1416516520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2729; }","duration":"258.327519ms","start":"2026-08-06T07:07:23.422992Z","end":"2026-08-06T07:07:23.68132Z","steps":["trace[1416516520] 'agreement among raft nodes before linearized reading' (duration: 257.564384ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:07:23.881128Z","caller":"traceutil/trace.go:171","msg":"trace[2079370242] transaction","detail":"{read_only:false; response_revision:2732; number_of_response:1; }","duration":"194.991986ms","start":"2026-08-06T07:07:23.686106Z","end":"2026-08-06T07:07:23.881098Z","steps":["trace[2079370242] 'process raft request' (duration: 194.940965ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:07:23.881142Z","caller":"traceutil/trace.go:171","msg":"trace[548741138] transaction","detail":"{read_only:false; response_revision:2731; number_of_response:1; }","duration":"195.145828ms","start":"2026-08-06T07:07:23.685961Z","end":"2026-08-06T07:07:23.881107Z","steps":["trace[548741138] 'process raft request' (duration: 194.953844ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:09:48.725582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.151862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-06T07:09:48.725689Z","caller":"traceutil/trace.go:171","msg":"trace[115719829] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3693; }","duration":"109.305686ms","start":"2026-08-06T07:09:48.616361Z","end":"2026-08-06T07:09:48.725667Z","steps":["trace[115719829] 'range keys from in-memory index tree' (duration: 108.878717ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:09:48.725768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.624184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-06T07:09:48.725851Z","caller":"traceutil/trace.go:171","msg":"trace[730426640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3693; }","duration":"112.757797ms","start":"2026-08-06T07:09:48.613075Z","end":"2026-08-06T07:09:48.725833Z","steps":["trace[730426640] 'range keys from in-memory index tree' (duration: 112.172805ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:11:12.323262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.521948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T07:11:12.323361Z","caller":"traceutil/trace.go:171","msg":"trace[691937301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4749; }","duration":"105.678481ms","start":"2026-08-06T07:11:12.217663Z","end":"2026-08-06T07:11:12.323341Z","steps":["trace[691937301] 'range keys from in-memory index tree' (duration: 105.414165ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:11:16.12434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.490213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-08-06T07:11:16.124427Z","caller":"traceutil/trace.go:171","msg":"trace[494027089] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:4897; }","duration":"228.628706ms","start":"2026-08-06T07:11:15.895783Z","end":"2026-08-06T07:11:16.124412Z","steps":["trace[494027089] 'range keys from in-memory index tree' (duration: 228.296809ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:11:16.124403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.0824ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T07:11:16.124526Z","caller":"traceutil/trace.go:171","msg":"trace[1920927986] linearizableReadLoop","detail":"{readStateIndex:5083; appliedIndex:5082; }","duration":"158.164015ms","start":"2026-08-06T07:11:15.966346Z","end":"2026-08-06T07:11:16.12451Z","steps":["trace[1920927986] 'read index received' (duration: 5.824629ms)","trace[1920927986] 'applied index is now lower than readState.Index' (duration: 152.338046ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:11:16.124597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.354538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786944"} {"level":"warn","ts":"2026-08-06T07:11:16.124672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.334358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T07:11:16.124699Z","caller":"traceutil/trace.go:171","msg":"trace[1440658315] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:4898; }","duration":"158.367098ms","start":"2026-08-06T07:11:15.966324Z","end":"2026-08-06T07:11:16.124691Z","steps":["trace[1440658315] 'agreement among raft nodes before linearized reading' (duration: 158.306107ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:11:16.124612Z","caller":"traceutil/trace.go:171","msg":"trace[1282612445] transaction","detail":"{read_only:false; response_revision:4898; number_of_response:1; }","duration":"222.716864ms","start":"2026-08-06T07:11:15.901884Z","end":"2026-08-06T07:11:16.1246Z","steps":["trace[1282612445] 'process raft request' (duration: 70.354789ms)","trace[1282612445] 'compare' (duration: 151.866545ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:11:16.124856Z","caller":"traceutil/trace.go:171","msg":"trace[320927946] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4897; }","duration":"224.4435ms","start":"2026-08-06T07:11:15.900193Z","end":"2026-08-06T07:11:16.124636Z","steps":["trace[320927946] 'range keys from in-memory index tree' (duration: 223.92668ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:11:16.125112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.004606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-29w79-tls\" ","response":"range_response_count:1 size:1967"} {"level":"info","ts":"2026-08-06T07:11:16.125172Z","caller":"traceutil/trace.go:171","msg":"trace[1283199939] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-29w79-tls; range_end:; response_count:1; response_revision:4898; }","duration":"125.105878ms","start":"2026-08-06T07:11:16.000047Z","end":"2026-08-06T07:11:16.125153Z","steps":["trace[1283199939] 'agreement among raft nodes before linearized reading' (duration: 124.920014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:11:16.125308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.801765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3501f4c8-dab1-401c-842b-ae075da31614\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-08-06T07:11:16.125393Z","caller":"traceutil/trace.go:171","msg":"trace[2061875712] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3501f4c8-dab1-401c-842b-ae075da31614; range_end:; response_count:1; response_revision:4898; }","duration":"150.939947ms","start":"2026-08-06T07:11:15.974431Z","end":"2026-08-06T07:11:16.125371Z","steps":["trace[2061875712] 'agreement among raft nodes before linearized reading' (duration: 150.439486ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:11:17.961905Z","caller":"traceutil/trace.go:171","msg":"trace[597489538] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"112.809567ms","start":"2026-08-06T07:11:17.849066Z","end":"2026-08-06T07:11:17.961875Z","steps":["trace[597489538] 'process raft request' (duration: 112.584793ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:11:28.027015Z","caller":"traceutil/trace.go:171","msg":"trace[1003407754] transaction","detail":"{read_only:false; response_revision:5134; number_of_response:1; }","duration":"108.390277ms","start":"2026-08-06T07:11:27.918605Z","end":"2026-08-06T07:11:28.026995Z","steps":["trace[1003407754] 'process raft request' (duration: 108.281725ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:11:28.224993Z","caller":"traceutil/trace.go:171","msg":"trace[272016698] transaction","detail":"{read_only:false; response_revision:5138; number_of_response:1; }","duration":"123.561377ms","start":"2026-08-06T07:11:28.101391Z","end":"2026-08-06T07:11:28.224952Z","steps":["trace[272016698] 'process raft request' (duration: 30.699298ms)","trace[272016698] 'compare' (duration: 92.539782ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:11:28.603747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.396262ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T07:11:28.603922Z","caller":"traceutil/trace.go:171","msg":"trace[1780366049] transaction","detail":"{read_only:false; response_revision:5141; number_of_response:1; }","duration":"326.590589ms","start":"2026-08-06T07:11:28.277301Z","end":"2026-08-06T07:11:28.603892Z","steps":["trace[1780366049] 'process raft request' (duration: 96.930443ms)","trace[1780366049] 'compare' (duration: 229.272139ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:11:28.604019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:11:28.277273Z","time spent":"326.703892ms","remote":"127.0.0.1:60862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8041,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T07:11:28.906383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.205876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T07:11:28.906715Z","caller":"traceutil/trace.go:171","msg":"trace[1067917489] transaction","detail":"{read_only:false; response_revision:5145; number_of_response:1; }","duration":"297.366701ms","start":"2026-08-06T07:11:28.609329Z","end":"2026-08-06T07:11:28.906696Z","steps":["trace[1067917489] 'process raft request' (duration: 297.28838ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:11:28.906845Z","caller":"traceutil/trace.go:171","msg":"trace[1466803195] transaction","detail":"{read_only:false; response_revision:5142; number_of_response:1; }","duration":"343.006645ms","start":"2026-08-06T07:11:28.563808Z","end":"2026-08-06T07:11:28.906815Z","steps":["trace[1466803195] 'process raft request' (duration: 206.288968ms)","trace[1466803195] 'compare' (duration: 136.069144ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:11:28.907Z","caller":"traceutil/trace.go:171","msg":"trace[1118666796] transaction","detail":"{read_only:false; response_revision:5143; number_of_response:1; }","duration":"298.400482ms","start":"2026-08-06T07:11:28.608589Z","end":"2026-08-06T07:11:28.906989Z","steps":["trace[1118666796] 'process raft request' (duration: 297.931133ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:11:28.907038Z","caller":"traceutil/trace.go:171","msg":"trace[782612641] linearizableReadLoop","detail":"{readStateIndex:5335; appliedIndex:5334; }","duration":"298.565336ms","start":"2026-08-06T07:11:28.608462Z","end":"2026-08-06T07:11:28.907028Z","steps":["trace[782612641] 'read index received' (duration: 161.647195ms)","trace[782612641] 'applied index is now lower than readState.Index' (duration: 136.917211ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:11:28.907151Z","caller":"traceutil/trace.go:171","msg":"trace[1259312983] transaction","detail":"{read_only:false; response_revision:5146; number_of_response:1; }","duration":"297.762179ms","start":"2026-08-06T07:11:28.609379Z","end":"2026-08-06T07:11:28.907141Z","steps":["trace[1259312983] 'process raft request' (duration: 297.28498ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:11:28.907165Z","caller":"traceutil/trace.go:171","msg":"trace[1721732597] transaction","detail":"{read_only:false; response_revision:5144; number_of_response:1; }","duration":"298.29287ms","start":"2026-08-06T07:11:28.608862Z","end":"2026-08-06T07:11:28.907155Z","steps":["trace[1721732597] 'process raft request' (duration: 297.721548ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:11:28.906998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:11:28.563779Z","time spent":"343.141268ms","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T07:11:28.907909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.421734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/goldpinger-qv26l\" ","response":"range_response_count:1 size:4995"} {"level":"info","ts":"2026-08-06T07:11:28.907983Z","caller":"traceutil/trace.go:171","msg":"trace[980660779] range","detail":"{range_begin:/registry/pods/monitoring/goldpinger-qv26l; range_end:; response_count:1; response_revision:5146; }","duration":"299.538186ms","start":"2026-08-06T07:11:28.608428Z","end":"2026-08-06T07:11:28.907966Z","steps":["trace[980660779] 'agreement among raft nodes before linearized reading' (duration: 299.343121ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:11:28.908596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:11:28.608412Z","time spent":"300.127548ms","remote":"127.0.0.1:60862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":5018,"request content":"key:\"/registry/pods/monitoring/goldpinger-qv26l\" "} {"level":"info","ts":"2026-08-06T07:11:42.458847Z","caller":"traceutil/trace.go:171","msg":"trace[1978977961] linearizableReadLoop","detail":"{readStateIndex:5594; appliedIndex:5592; }","duration":"112.256395ms","start":"2026-08-06T07:11:42.346571Z","end":"2026-08-06T07:11:42.458828Z","steps":["trace[1978977961] 'read index received' (duration: 42.982158ms)","trace[1978977961] 'applied index is now lower than readState.Index' (duration: 69.273007ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:11:42.458854Z","caller":"traceutil/trace.go:171","msg":"trace[1752139197] transaction","detail":"{read_only:false; response_revision:5394; number_of_response:1; }","duration":"176.648353ms","start":"2026-08-06T07:11:42.282167Z","end":"2026-08-06T07:11:42.458815Z","steps":["trace[1752139197] 'process raft request' (duration: 107.371586ms)","trace[1752139197] 'compare' (duration: 69.011972ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:11:42.458947Z","caller":"traceutil/trace.go:171","msg":"trace[2121685004] transaction","detail":"{read_only:false; response_revision:5396; number_of_response:1; }","duration":"109.463328ms","start":"2026-08-06T07:11:42.349471Z","end":"2026-08-06T07:11:42.458934Z","steps":["trace[2121685004] 'process raft request' (duration: 109.300745ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:11:42.458958Z","caller":"traceutil/trace.go:171","msg":"trace[432341497] transaction","detail":"{read_only:false; response_revision:5395; number_of_response:1; }","duration":"141.061995ms","start":"2026-08-06T07:11:42.31786Z","end":"2026-08-06T07:11:42.458922Z","steps":["trace[432341497] 'process raft request' (duration: 140.839301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:11:42.459146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.553242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-08-06T07:11:42.459216Z","caller":"traceutil/trace.go:171","msg":"trace[1010504231] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5396; }","duration":"112.640424ms","start":"2026-08-06T07:11:42.346557Z","end":"2026-08-06T07:11:42.459197Z","steps":["trace[1010504231] 'agreement among raft nodes before linearized reading' (duration: 112.43534ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:11:42.88449Z","caller":"traceutil/trace.go:171","msg":"trace[358697741] transaction","detail":"{read_only:false; response_revision:5398; number_of_response:1; }","duration":"417.0871ms","start":"2026-08-06T07:11:42.46738Z","end":"2026-08-06T07:11:42.884467Z","steps":["trace[358697741] 'process raft request' (duration: 341.673898ms)","trace[358697741] 'compare' (duration: 75.075156ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:11:42.884635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:11:42.467361Z","time spent":"417.197872ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:11:42.884759Z","caller":"traceutil/trace.go:171","msg":"trace[964314170] transaction","detail":"{read_only:false; response_revision:5399; number_of_response:1; }","duration":"416.962428ms","start":"2026-08-06T07:11:42.46777Z","end":"2026-08-06T07:11:42.884733Z","steps":["trace[964314170] 'process raft request' (duration: 416.643761ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:11:42.884867Z","caller":"traceutil/trace.go:171","msg":"trace[1459944033] transaction","detail":"{read_only:false; response_revision:5400; number_of_response:1; }","duration":"416.759953ms","start":"2026-08-06T07:11:42.468095Z","end":"2026-08-06T07:11:42.884855Z","steps":["trace[1459944033] 'process raft request' (duration: 416.510138ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:11:42.885049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:11:42.467746Z","time spent":"417.130071ms","remote":"127.0.0.1:60840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1273,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:11:42.885127Z","caller":"traceutil/trace.go:171","msg":"trace[1501941636] transaction","detail":"{read_only:false; response_revision:5401; number_of_response:1; }","duration":"408.676878ms","start":"2026-08-06T07:11:42.47643Z","end":"2026-08-06T07:11:42.885107Z","steps":["trace[1501941636] 'process raft request' (duration: 408.341541ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:11:42.884892Z","caller":"traceutil/trace.go:171","msg":"trace[1346199899] linearizableReadLoop","detail":"{readStateIndex:5600; appliedIndex:5596; }","duration":"350.319154ms","start":"2026-08-06T07:11:42.534545Z","end":"2026-08-06T07:11:42.884864Z","steps":["trace[1346199899] 'read index received' (duration: 274.410432ms)","trace[1346199899] 'applied index is now lower than readState.Index' (duration: 75.907582ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:11:42.885299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:11:42.476414Z","time spent":"408.78095ms","remote":"127.0.0.1:32906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15330,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T07:11:42.885318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.762843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9df185fb-d9b1-48e4-a949-c52db9101037\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-08-06T07:11:42.88537Z","caller":"traceutil/trace.go:171","msg":"trace[1633686689] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9df185fb-d9b1-48e4-a949-c52db9101037; range_end:; response_count:1; response_revision:5401; }","duration":"350.852375ms","start":"2026-08-06T07:11:42.534505Z","end":"2026-08-06T07:11:42.885357Z","steps":["trace[1633686689] 'agreement among raft nodes before linearized reading' (duration: 350.697962ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:11:42.885411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:11:42.53449Z","time spent":"350.912326ms","remote":"127.0.0.1:60862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3786,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9df185fb-d9b1-48e4-a949-c52db9101037\" "} {"level":"warn","ts":"2026-08-06T07:11:42.885467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.029512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-08-06T07:11:42.885554Z","caller":"traceutil/trace.go:171","msg":"trace[1847408236] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5401; }","duration":"224.156024ms","start":"2026-08-06T07:11:42.661387Z","end":"2026-08-06T07:11:42.885543Z","steps":["trace[1847408236] 'agreement among raft nodes before linearized reading' (duration: 223.991531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:11:42.885483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.729874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-06T07:11:42.885633Z","caller":"traceutil/trace.go:171","msg":"trace[119639491] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5401; }","duration":"284.914028ms","start":"2026-08-06T07:11:42.600708Z","end":"2026-08-06T07:11:42.885622Z","steps":["trace[119639491] 'agreement among raft nodes before linearized reading' (duration: 284.640782ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:11:42.885814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:11:42.468076Z","time spent":"416.820865ms","remote":"127.0.0.1:60930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1853,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:11:43.092699Z","caller":"traceutil/trace.go:171","msg":"trace[926249317] linearizableReadLoop","detail":"{readStateIndex:5603; appliedIndex:5601; }","duration":"116.743387ms","start":"2026-08-06T07:11:42.975937Z","end":"2026-08-06T07:11:43.09268Z","steps":["trace[926249317] 'read index received' (duration: 72.441861ms)","trace[926249317] 'applied index is now lower than readState.Index' (duration: 44.300666ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:11:43.092771Z","caller":"traceutil/trace.go:171","msg":"trace[1763743606] transaction","detail":"{read_only:false; response_revision:5404; number_of_response:1; }","duration":"138.182426ms","start":"2026-08-06T07:11:42.954555Z","end":"2026-08-06T07:11:43.092738Z","steps":["trace[1763743606] 'process raft request' (duration: 138.084864ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:11:43.093166Z","caller":"traceutil/trace.go:171","msg":"trace[469401047] transaction","detail":"{read_only:false; response_revision:5403; number_of_response:1; }","duration":"160.619195ms","start":"2026-08-06T07:11:42.932527Z","end":"2026-08-06T07:11:43.093146Z","steps":["trace[469401047] 'process raft request' (duration: 115.834619ms)","trace[469401047] 'compare' (duration: 44.121923ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:11:43.093301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.114177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T07:11:43.093412Z","caller":"traceutil/trace.go:171","msg":"trace[1942047680] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5404; }","duration":"100.25836ms","start":"2026-08-06T07:11:42.993127Z","end":"2026-08-06T07:11:43.093386Z","steps":["trace[1942047680] 'agreement among raft nodes before linearized reading' (duration: 100.082646ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:11:43.093881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.916132ms","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-08-06T07:11:43.093943Z","caller":"traceutil/trace.go:171","msg":"trace[1824335994] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5404; }","duration":"118.013623ms","start":"2026-08-06T07:11:42.975902Z","end":"2026-08-06T07:11:43.093915Z","steps":["trace[1824335994] 'agreement among raft nodes before linearized reading' (duration: 116.903371ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:12:32.872692Z","caller":"traceutil/trace.go:171","msg":"trace[725771986] transaction","detail":"{read_only:false; response_revision:5837; number_of_response:1; }","duration":"162.336777ms","start":"2026-08-06T07:12:32.710318Z","end":"2026-08-06T07:12:32.872654Z","steps":["trace[725771986] 'process raft request' (duration: 162.082891ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:12:32.891979Z","caller":"traceutil/trace.go:171","msg":"trace[7642693] linearizableReadLoop","detail":"{readStateIndex:6050; appliedIndex:6048; }","duration":"105.497746ms","start":"2026-08-06T07:12:32.786458Z","end":"2026-08-06T07:12:32.891956Z","steps":["trace[7642693] 'read index received' (duration: 85.881835ms)","trace[7642693] 'applied index is now lower than readState.Index' (duration: 19.615011ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:12:32.892122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.645549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T07:12:32.892082Z","caller":"traceutil/trace.go:171","msg":"trace[1445143083] transaction","detail":"{read_only:false; response_revision:5838; number_of_response:1; }","duration":"173.867843ms","start":"2026-08-06T07:12:32.71818Z","end":"2026-08-06T07:12:32.892047Z","steps":["trace[1445143083] 'process raft request' (duration: 173.640498ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:12:32.892158Z","caller":"traceutil/trace.go:171","msg":"trace[936607006] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:5838; }","duration":"105.739171ms","start":"2026-08-06T07:12:32.786408Z","end":"2026-08-06T07:12:32.892147Z","steps":["trace[936607006] 'agreement among raft nodes before linearized reading' (duration: 105.636949ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:13:08.091927Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2973} {"level":"info","ts":"2026-08-06T07:13:08.166319Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2973,"took":"73.056152ms","hash":2772625668,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":34050048,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-06T07:13:08.166401Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2772625668,"revision":2973,"compact-revision":-1} {"level":"warn","ts":"2026-08-06T07:17:00.455248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000144107s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-06T07:17:00.455326Z","caller":"traceutil/trace.go:171","msg":"trace[933023825] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000259899s","start":"2026-08-06T07:16:58.455053Z","end":"2026-08-06T07:17:00.455312Z","steps":["trace[933023825] 'agreement among raft nodes before linearized reading' (duration: 2.000165867s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:00.455359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:16:58.455036Z","time spent":"2.00031591s","remote":"127.0.0.1:60664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/06 07:17:00 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-06T07:17:02.227041Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.778255379s","expected-duration":"1s"} {"level":"info","ts":"2026-08-06T07:17:02.227566Z","caller":"traceutil/trace.go:171","msg":"trace[577278230] linearizableReadLoop","detail":"{readStateIndex:8000; appliedIndex:7999; }","duration":"3.7792951s","start":"2026-08-06T07:16:58.448242Z","end":"2026-08-06T07:17:02.227537Z","steps":["trace[577278230] 'read index received' (duration: 659.693µs)","trace[577278230] 'applied index is now lower than readState.Index' (duration: 3.778634097s)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:17:02.227665Z","caller":"traceutil/trace.go:171","msg":"trace[1705275602] transaction","detail":"{read_only:false; response_revision:7725; number_of_response:1; }","duration":"3.779473404s","start":"2026-08-06T07:16:58.448154Z","end":"2026-08-06T07:17:02.227627Z","steps":["trace[1705275602] 'process raft request' (duration: 3.779173818s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:02.227869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.779590026s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T07:17:02.227942Z","caller":"traceutil/trace.go:171","msg":"trace[506273621] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:7725; }","duration":"3.779721269s","start":"2026-08-06T07:16:58.448203Z","end":"2026-08-06T07:17:02.227925Z","steps":["trace[506273621] 'agreement among raft nodes before linearized reading' (duration: 3.779471694s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:02.227992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:16:58.448191Z","time spent":"3.77978885s","remote":"127.0.0.1:42428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":36,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-08-06T07:17:02.227968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:16:58.448144Z","time spent":"3.779655918s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:17:02.259802Z","caller":"traceutil/trace.go:171","msg":"trace[444940236] transaction","detail":"{read_only:false; response_revision:7726; number_of_response:1; }","duration":"3.807558229s","start":"2026-08-06T07:16:58.452145Z","end":"2026-08-06T07:17:02.259703Z","steps":["trace[444940236] 'process raft request' (duration: 3.807178011s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:02.260295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:16:58.452126Z","time spent":"3.807840184s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:17:02.260515Z","caller":"traceutil/trace.go:171","msg":"trace[1943752416] transaction","detail":"{read_only:false; response_revision:7727; number_of_response:1; }","duration":"2.833070317s","start":"2026-08-06T07:16:59.427418Z","end":"2026-08-06T07:17:02.260488Z","steps":["trace[1943752416] 'process raft request' (duration: 2.832204899s)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:17:02.260572Z","caller":"traceutil/trace.go:171","msg":"trace[1878362600] transaction","detail":"{read_only:false; response_revision:7730; number_of_response:1; }","duration":"2.138202936s","start":"2026-08-06T07:17:00.122345Z","end":"2026-08-06T07:17:02.260548Z","steps":["trace[1878362600] 'process raft request' (duration: 2.138162945s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:02.260655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:16:59.427398Z","time spent":"2.833179589s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T07:17:02.26069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:00.122328Z","time spent":"2.138299138s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:17:02.260568Z","caller":"traceutil/trace.go:171","msg":"trace[187945626] transaction","detail":"{read_only:false; response_revision:7729; number_of_response:1; }","duration":"2.373647725s","start":"2026-08-06T07:16:59.8869Z","end":"2026-08-06T07:17:02.260548Z","steps":["trace[187945626] 'process raft request' (duration: 2.373582044s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:02.260838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:16:59.886883Z","time spent":"2.37390587s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T07:17:02.260972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.764151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-06T07:17:02.26097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.328569706s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-06T07:17:02.261014Z","caller":"traceutil/trace.go:171","msg":"trace[1909356827] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:7730; }","duration":"178.845343ms","start":"2026-08-06T07:17:02.082153Z","end":"2026-08-06T07:17:02.260999Z","steps":["trace[1909356827] 'agreement among raft nodes before linearized reading' (duration: 178.785662ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:17:02.261021Z","caller":"traceutil/trace.go:171","msg":"trace[538029923] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7730; }","duration":"3.328662428s","start":"2026-08-06T07:16:58.932343Z","end":"2026-08-06T07:17:02.261006Z","steps":["trace[538029923] 'agreement among raft nodes before linearized reading' (duration: 3.328484374s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:02.260938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.654401879s","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-08-06T07:17:02.261104Z","caller":"traceutil/trace.go:171","msg":"trace[1874627372] transaction","detail":"{read_only:false; response_revision:7728; number_of_response:1; }","duration":"2.416849598s","start":"2026-08-06T07:16:59.844236Z","end":"2026-08-06T07:17:02.261085Z","steps":["trace[1874627372] 'process raft request' (duration: 2.416134713s)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:17:02.26113Z","caller":"traceutil/trace.go:171","msg":"trace[469673238] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7730; }","duration":"3.654640294s","start":"2026-08-06T07:16:58.606475Z","end":"2026-08-06T07:17:02.261115Z","steps":["trace[469673238] 'agreement among raft nodes before linearized reading' (duration: 3.654111094s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:02.261181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.107321117s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"warn","ts":"2026-08-06T07:17:02.261169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.240589169s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-twgth\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-06T07:17:02.261215Z","caller":"traceutil/trace.go:171","msg":"trace[644560987] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:7730; }","duration":"3.107392619s","start":"2026-08-06T07:16:59.153814Z","end":"2026-08-06T07:17:02.261207Z","steps":["trace[644560987] 'agreement among raft nodes before linearized reading' (duration: 3.107325218s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:02.261203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:16:59.844225Z","time spent":"2.416928099s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:17:02.261226Z","caller":"traceutil/trace.go:171","msg":"trace[1286680797] range","detail":"{range_begin:/registry/pods/openstack/keepalived-twgth; range_end:; response_count:1; response_revision:7730; }","duration":"3.240670671s","start":"2026-08-06T07:16:59.020544Z","end":"2026-08-06T07:17:02.261214Z","steps":["trace[1286680797] 'agreement among raft nodes before linearized reading' (duration: 3.240540449s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:02.261273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.520901642s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T07:17:02.260903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.758550236s","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-08-06T07:17:02.261302Z","caller":"traceutil/trace.go:171","msg":"trace[536197422] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:7730; }","duration":"2.520967763s","start":"2026-08-06T07:16:59.740324Z","end":"2026-08-06T07:17:02.261292Z","steps":["trace[536197422] 'agreement among raft nodes before linearized reading' (duration: 2.520930532s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:02.261318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.34454686s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T07:17:02.261329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:16:59.740307Z","time spent":"2.521015024s","remote":"127.0.0.1:57870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "} {"level":"info","ts":"2026-08-06T07:17:02.261337Z","caller":"traceutil/trace.go:171","msg":"trace[912133365] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:7730; }","duration":"2.344591172s","start":"2026-08-06T07:16:59.916739Z","end":"2026-08-06T07:17:02.26133Z","steps":["trace[912133365] 'agreement among raft nodes before linearized reading' (duration: 2.344561901s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:02.261339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.37049227s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T07:17:02.261369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:16:59.916727Z","time spent":"2.344637552s","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true "} {"level":"warn","ts":"2026-08-06T07:17:02.261121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.120855484s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-06T07:17:02.261484Z","caller":"traceutil/trace.go:171","msg":"trace[2142089595] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:7730; }","duration":"3.121259422s","start":"2026-08-06T07:16:59.140211Z","end":"2026-08-06T07:17:02.26147Z","steps":["trace[2142089595] 'agreement among raft nodes before linearized reading' (duration: 3.120831424s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:02.261553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:16:59.140187Z","time spent":"3.121351234s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-08-06T07:17:02.261575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.419995823s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-06T07:17:02.261596Z","caller":"traceutil/trace.go:171","msg":"trace[1222522826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7730; }","duration":"3.420044404s","start":"2026-08-06T07:16:58.841545Z","end":"2026-08-06T07:17:02.26159Z","steps":["trace[1222522826] 'agreement among raft nodes before linearized reading' (duration: 3.419982723s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:02.261617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:16:58.841531Z","time spent":"3.420082525s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-06T07:17:02.261331Z","caller":"traceutil/trace.go:171","msg":"trace[1783339619] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7730; }","duration":"2.759022214s","start":"2026-08-06T07:16:59.502294Z","end":"2026-08-06T07:17:02.261316Z","steps":["trace[1783339619] 'agreement among raft nodes before linearized reading' (duration: 2.758514264s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:02.261671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:16:59.502275Z","time spent":"2.759388162s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-06T07:17:02.261689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.407065289s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-06T07:17:02.261709Z","caller":"traceutil/trace.go:171","msg":"trace[2086863487] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7730; }","duration":"3.407092919s","start":"2026-08-06T07:16:58.854608Z","end":"2026-08-06T07:17:02.261701Z","steps":["trace[2086863487] 'agreement among raft nodes before linearized reading' (duration: 3.407048688s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:02.261238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:16:59.153793Z","time spent":"3.10744053s","remote":"127.0.0.1:60864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2011,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-08-06T07:17:02.261728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:16:58.854602Z","time spent":"3.407121339s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-06T07:17:02.261241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.805847069s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T07:17:02.26178Z","caller":"traceutil/trace.go:171","msg":"trace[346559785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7730; }","duration":"1.806409199s","start":"2026-08-06T07:17:00.455365Z","end":"2026-08-06T07:17:02.261774Z","steps":["trace[346559785] 'agreement among raft nodes before linearized reading' (duration: 1.805863209s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:02.261799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:00.455351Z","time spent":"1.806443051s","remote":"127.0.0.1:60670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T07:17:02.261451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:16:58.606455Z","time spent":"3.654985192s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-06T07:17:02.261385Z","caller":"traceutil/trace.go:171","msg":"trace[917026646] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:7730; }","duration":"2.370560131s","start":"2026-08-06T07:16:59.890808Z","end":"2026-08-06T07:17:02.261369Z","steps":["trace[917026646] 'agreement among raft nodes before linearized reading' (duration: 2.37049914s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:02.262008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:16:59.890799Z","time spent":"2.371186614s","remote":"127.0.0.1:39180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-06T07:17:02.26106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:16:58.932323Z","time spent":"3.328728579s","remote":"127.0.0.1:60862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-06T07:17:02.261216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.696636848s","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:7"} {"level":"warn","ts":"2026-08-06T07:17:02.262117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.654937621s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-06T07:17:02.262145Z","caller":"traceutil/trace.go:171","msg":"trace[307148154] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:7730; }","duration":"1.697635589s","start":"2026-08-06T07:17:00.564501Z","end":"2026-08-06T07:17:02.262136Z","steps":["trace[307148154] 'agreement among raft nodes before linearized reading' (duration: 1.696701279s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:02.262179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:00.56448Z","time spent":"1.69768752s","remote":"127.0.0.1:35588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":1,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true "} {"level":"warn","ts":"2026-08-06T07:17:02.262222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.58831541s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-user\" ","response":"range_response_count:1 size:7403"} {"level":"info","ts":"2026-08-06T07:17:02.262251Z","caller":"traceutil/trace.go:171","msg":"trace[1658692285] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-user; range_end:; response_count:1; response_revision:7730; }","duration":"3.588375612s","start":"2026-08-06T07:16:58.673869Z","end":"2026-08-06T07:17:02.262245Z","steps":["trace[1658692285] 'agreement among raft nodes before linearized reading' (duration: 3.58829544s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:02.261188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190463932s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.222\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-06T07:17:02.262279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:16:58.673854Z","time spent":"3.588419352s","remote":"127.0.0.1:60902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":7426,"request content":"key:\"/registry/jobs/openstack/barbican-ks-user\" "} {"level":"info","ts":"2026-08-06T07:17:02.262285Z","caller":"traceutil/trace.go:171","msg":"trace[983814542] range","detail":"{range_begin:/registry/masterleases/199.204.45.222; range_end:; response_count:1; response_revision:7730; }","duration":"1.191597646s","start":"2026-08-06T07:17:01.07068Z","end":"2026-08-06T07:17:02.262277Z","steps":["trace[983814542] 'agreement among raft nodes before linearized reading' (duration: 1.190463722s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:02.262312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:01.070655Z","time spent":"1.191649017s","remote":"127.0.0.1:60704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.222\" "} {"level":"info","ts":"2026-08-06T07:17:02.262149Z","caller":"traceutil/trace.go:171","msg":"trace[2145880050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7730; }","duration":"3.654999693s","start":"2026-08-06T07:16:58.60714Z","end":"2026-08-06T07:17:02.26214Z","steps":["trace[2145880050] 'agreement among raft nodes before linearized reading' (duration: 3.65490955s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:02.262421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:16:58.607123Z","time spent":"3.655291658s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-06T07:17:02.26129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.44444133s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-06T07:17:02.261275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:16:59.020527Z","time spent":"3.240740252s","remote":"127.0.0.1:60862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-twgth\" "} {"level":"info","ts":"2026-08-06T07:17:02.262594Z","caller":"traceutil/trace.go:171","msg":"trace[1050135395] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7730; }","duration":"2.445708046s","start":"2026-08-06T07:16:59.816816Z","end":"2026-08-06T07:17:02.262524Z","steps":["trace[1050135395] 'agreement among raft nodes before linearized reading' (duration: 2.4444359s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:02.262951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:16:59.816799Z","time spent":"2.446139495s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-06T07:17:12.796347Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890120220892231954,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T07:17:13.297255Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890120220892231954,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T07:17:13.797669Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890120220892231954,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T07:17:14.298327Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890120220892231954,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T07:17:14.798836Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890120220892231954,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T07:17:14.844182Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.541200016s","expected-duration":"1s"} {"level":"info","ts":"2026-08-06T07:17:14.844458Z","caller":"traceutil/trace.go:171","msg":"trace[1446793128] transaction","detail":"{read_only:false; response_revision:7779; number_of_response:1; }","duration":"2.58597135s","start":"2026-08-06T07:17:12.258465Z","end":"2026-08-06T07:17:14.844436Z","steps":["trace[1446793128] 'process raft request' (duration: 2.585852148s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:14.844562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:12.258439Z","time spent":"2.586055992s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:17:14.850094Z","caller":"traceutil/trace.go:171","msg":"trace[424622501] transaction","detail":"{read_only:false; response_revision:7781; number_of_response:1; }","duration":"2.548059196s","start":"2026-08-06T07:17:12.302022Z","end":"2026-08-06T07:17:14.850081Z","steps":["trace[424622501] 'process raft request' (duration: 2.548008795s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:14.850202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:12.302003Z","time spent":"2.548133729s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:17:14.850096Z","caller":"traceutil/trace.go:171","msg":"trace[113058333] transaction","detail":"{read_only:false; response_revision:7780; number_of_response:1; }","duration":"2.551883414s","start":"2026-08-06T07:17:12.298189Z","end":"2026-08-06T07:17:14.850073Z","steps":["trace[113058333] 'process raft request' (duration: 2.551726221s)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:17:14.850287Z","caller":"traceutil/trace.go:171","msg":"trace[16003411] linearizableReadLoop","detail":"{readStateIndex:8061; appliedIndex:8056; }","duration":"2.554164581s","start":"2026-08-06T07:17:12.296105Z","end":"2026-08-06T07:17:14.85027Z","steps":["trace[16003411] 'read index received' (duration: 2.548262061s)","trace[16003411] 'applied index is now lower than readState.Index' (duration: 5.90158ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:17:14.850351Z","caller":"traceutil/trace.go:171","msg":"trace[1648271084] transaction","detail":"{read_only:false; response_revision:7782; number_of_response:1; }","duration":"2.463712854s","start":"2026-08-06T07:17:12.386632Z","end":"2026-08-06T07:17:14.850344Z","steps":["trace[1648271084] 'process raft request' (duration: 2.463424708s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:14.85034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:12.298172Z","time spent":"2.55212212s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T07:17:14.850386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:12.386608Z","time spent":"2.463756684s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T07:17:14.850419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.554322364s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-user\" ","response":"range_response_count:1 size:7403"} {"level":"info","ts":"2026-08-06T07:17:14.850444Z","caller":"traceutil/trace.go:171","msg":"trace[1685767186] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-user; range_end:; response_count:1; response_revision:7783; }","duration":"2.554380756s","start":"2026-08-06T07:17:12.296057Z","end":"2026-08-06T07:17:14.850438Z","steps":["trace[1685767186] 'agreement among raft nodes before linearized reading' (duration: 2.554293564s)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:17:14.850455Z","caller":"traceutil/trace.go:171","msg":"trace[20521420] transaction","detail":"{read_only:false; response_revision:7783; number_of_response:1; }","duration":"102.284318ms","start":"2026-08-06T07:17:14.748147Z","end":"2026-08-06T07:17:14.850431Z","steps":["trace[20521420] 'process raft request' (duration: 102.080384ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:14.850463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:12.296038Z","time spent":"2.554419456s","remote":"127.0.0.1:60902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":7426,"request content":"key:\"/registry/jobs/openstack/barbican-ks-user\" "} {"level":"warn","ts":"2026-08-06T07:17:14.850484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.500364082s","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-08-06T07:17:14.850506Z","caller":"traceutil/trace.go:171","msg":"trace[1915443527] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7783; }","duration":"2.500385743s","start":"2026-08-06T07:17:12.350114Z","end":"2026-08-06T07:17:14.8505Z","steps":["trace[1915443527] 'agreement among raft nodes before linearized reading' (duration: 2.500327602s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:14.850522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:12.350099Z","time spent":"2.500419233s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-06T07:17:14.850604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.323077132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-06T07:17:14.850625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.36166905s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-06T07:17:14.850635Z","caller":"traceutil/trace.go:171","msg":"trace[1206869936] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:7783; }","duration":"2.323144802s","start":"2026-08-06T07:17:12.527481Z","end":"2026-08-06T07:17:14.850625Z","steps":["trace[1206869936] 'agreement among raft nodes before linearized reading' (duration: 2.323087981s)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:17:14.850644Z","caller":"traceutil/trace.go:171","msg":"trace[1922572046] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7783; }","duration":"2.361718841s","start":"2026-08-06T07:17:12.488919Z","end":"2026-08-06T07:17:14.850638Z","steps":["trace[1922572046] 'agreement among raft nodes before linearized reading' (duration: 2.361651679s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:14.850661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:12.488902Z","time spent":"2.361753732s","remote":"127.0.0.1:60862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-06T07:17:14.850661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:12.527463Z","time spent":"2.323190304s","remote":"127.0.0.1:60824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":7,"response size":30,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-08-06T07:17:14.850779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.21442655s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-08-06T07:17:14.850802Z","caller":"traceutil/trace.go:171","msg":"trace[405031163] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:7783; }","duration":"1.214449311s","start":"2026-08-06T07:17:13.636345Z","end":"2026-08-06T07:17:14.850795Z","steps":["trace[405031163] 'agreement among raft nodes before linearized reading' (duration: 1.21438871s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:14.850805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.485715963s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-twgth\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-08-06T07:17:14.850817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.632501948s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T07:17:14.850844Z","caller":"traceutil/trace.go:171","msg":"trace[1780556056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7783; }","duration":"1.63255935s","start":"2026-08-06T07:17:13.218277Z","end":"2026-08-06T07:17:14.850836Z","steps":["trace[1780556056] 'agreement among raft nodes before linearized reading' (duration: 1.632516519s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:14.850857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.941063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-06T07:17:14.850868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:13.218259Z","time spent":"1.63260245s","remote":"127.0.0.1:60664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T07:17:14.850874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.492587113s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-06T07:17:14.850891Z","caller":"traceutil/trace.go:171","msg":"trace[2077611674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7783; }","duration":"2.492648064s","start":"2026-08-06T07:17:12.358238Z","end":"2026-08-06T07:17:14.850886Z","steps":["trace[2077611674] 'agreement among raft nodes before linearized reading' (duration: 2.492596353s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:14.850907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:12.358219Z","time spent":"2.492683465s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-06T07:17:14.850906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.692594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T07:17:14.85094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.218635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T07:17:14.850974Z","caller":"traceutil/trace.go:171","msg":"trace[894764841] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:7783; }","duration":"735.789525ms","start":"2026-08-06T07:17:14.115177Z","end":"2026-08-06T07:17:14.850967Z","steps":["trace[894764841] 'agreement among raft nodes before linearized reading' (duration: 735.709674ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:14.850996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:14.115161Z","time spent":"735.828107ms","remote":"127.0.0.1:39302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"info","ts":"2026-08-06T07:17:14.851001Z","caller":"traceutil/trace.go:171","msg":"trace[347545124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7783; }","duration":"521.366648ms","start":"2026-08-06T07:17:14.329618Z","end":"2026-08-06T07:17:14.850985Z","steps":["trace[347545124] 'agreement among raft nodes before linearized reading' (duration: 521.238575ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:14.85103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:14.329596Z","time spent":"521.428268ms","remote":"127.0.0.1:60664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T07:17:14.851046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.768232391s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T07:17:14.851071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.492935821s","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-08-06T07:17:14.851077Z","caller":"traceutil/trace.go:171","msg":"trace[23351454] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:7783; }","duration":"1.768302482s","start":"2026-08-06T07:17:13.082764Z","end":"2026-08-06T07:17:14.851066Z","steps":["trace[23351454] 'agreement among raft nodes before linearized reading' (duration: 1.768254411s)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:17:14.851094Z","caller":"traceutil/trace.go:171","msg":"trace[912114841] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7783; }","duration":"2.492992562s","start":"2026-08-06T07:17:12.358095Z","end":"2026-08-06T07:17:14.851088Z","steps":["trace[912114841] 'agreement among raft nodes before linearized reading' (duration: 2.49293467s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:14.851105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:13.082744Z","time spent":"1.768352903s","remote":"127.0.0.1:45746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-08-06T07:17:14.851179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.493155935s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-06T07:17:14.851198Z","caller":"traceutil/trace.go:171","msg":"trace[1844670111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7783; }","duration":"2.493189166s","start":"2026-08-06T07:17:12.358004Z","end":"2026-08-06T07:17:14.851193Z","steps":["trace[1844670111] 'agreement among raft nodes before linearized reading' (duration: 2.493123104s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:14.851215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:12.357997Z","time spent":"2.493212976s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-06T07:17:14.851245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.495348969s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-08-06T07:17:14.851258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.162959482s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T07:17:14.851288Z","caller":"traceutil/trace.go:171","msg":"trace[181589259] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:7783; }","duration":"2.162992662s","start":"2026-08-06T07:17:12.688287Z","end":"2026-08-06T07:17:14.851279Z","steps":["trace[181589259] 'agreement among raft nodes before linearized reading' (duration: 2.162945922s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:14.851296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.498195958s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-08-06T07:17:14.851313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:12.688228Z","time spent":"2.163077954s","remote":"127.0.0.1:39176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"info","ts":"2026-08-06T07:17:14.851322Z","caller":"traceutil/trace.go:171","msg":"trace[642702440] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7783; }","duration":"2.498257768s","start":"2026-08-06T07:17:12.353055Z","end":"2026-08-06T07:17:14.851312Z","steps":["trace[642702440] 'agreement among raft nodes before linearized reading' (duration: 2.498173176s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:14.851341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:12.353033Z","time spent":"2.49830355s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-06T07:17:14.850824Z","caller":"traceutil/trace.go:171","msg":"trace[1055184304] range","detail":"{range_begin:/registry/pods/openstack/keepalived-twgth; range_end:; response_count:1; response_revision:7783; }","duration":"2.485762944s","start":"2026-08-06T07:17:12.365056Z","end":"2026-08-06T07:17:14.850819Z","steps":["trace[1055184304] 'agreement among raft nodes before linearized reading' (duration: 2.485703813s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:14.85141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:12.365043Z","time spent":"2.486361407s","remote":"127.0.0.1:60862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-twgth\" "} {"level":"warn","ts":"2026-08-06T07:17:14.851115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:12.35808Z","time spent":"2.493028213s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-06T07:17:14.851265Z","caller":"traceutil/trace.go:171","msg":"trace[253748020] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7783; }","duration":"2.49540554s","start":"2026-08-06T07:17:12.355853Z","end":"2026-08-06T07:17:14.851259Z","steps":["trace[253748020] 'agreement among raft nodes before linearized reading' (duration: 2.495348649s)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:17:14.850875Z","caller":"traceutil/trace.go:171","msg":"trace[1252265366] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:7783; }","duration":"437.000464ms","start":"2026-08-06T07:17:14.413869Z","end":"2026-08-06T07:17:14.850869Z","steps":["trace[1252265366] 'agreement among raft nodes before linearized reading' (duration: 436.966394ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:14.85148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:12.355835Z","time spent":"2.495636506s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-06T07:17:14.850825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:13.636272Z","time spent":"1.214545563s","remote":"127.0.0.1:60824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1656,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"warn","ts":"2026-08-06T07:17:14.851558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:17:14.413849Z","time spent":"437.651328ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":30,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"info","ts":"2026-08-06T07:17:37.160785Z","caller":"traceutil/trace.go:171","msg":"trace[2121972527] transaction","detail":"{read_only:false; response_revision:8076; number_of_response:1; }","duration":"198.412562ms","start":"2026-08-06T07:17:36.962343Z","end":"2026-08-06T07:17:37.160756Z","steps":["trace[2121972527] 'process raft request' (duration: 198.225788ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:17:37.163386Z","caller":"traceutil/trace.go:171","msg":"trace[1890754446] transaction","detail":"{read_only:false; response_revision:8077; number_of_response:1; }","duration":"195.86579ms","start":"2026-08-06T07:17:36.967496Z","end":"2026-08-06T07:17:37.163361Z","steps":["trace[1890754446] 'process raft request' (duration: 195.684116ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:17:37.163486Z","caller":"traceutil/trace.go:171","msg":"trace[1903108534] linearizableReadLoop","detail":"{readStateIndex:8364; appliedIndex:8361; }","duration":"162.033239ms","start":"2026-08-06T07:17:37.001426Z","end":"2026-08-06T07:17:37.163459Z","steps":["trace[1903108534] 'read index received' (duration: 159.247512ms)","trace[1903108534] 'applied index is now lower than readState.Index' (duration: 2.784537ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:17:37.163527Z","caller":"traceutil/trace.go:171","msg":"trace[1252038388] transaction","detail":"{read_only:false; response_revision:8078; number_of_response:1; }","duration":"189.743915ms","start":"2026-08-06T07:17:36.973767Z","end":"2026-08-06T07:17:37.163511Z","steps":["trace[1252038388] 'process raft request' (duration: 189.553091ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:37.163643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.221822ms","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-08-06T07:17:37.16368Z","caller":"traceutil/trace.go:171","msg":"trace[267417247] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8078; }","duration":"162.312594ms","start":"2026-08-06T07:17:37.001361Z","end":"2026-08-06T07:17:37.163673Z","steps":["trace[267417247] 'agreement among raft nodes before linearized reading' (duration: 162.203472ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:37.16369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.000233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T07:17:37.163729Z","caller":"traceutil/trace.go:171","msg":"trace[561046720] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:8078; }","duration":"149.103394ms","start":"2026-08-06T07:17:37.014614Z","end":"2026-08-06T07:17:37.163717Z","steps":["trace[561046720] 'agreement among raft nodes before linearized reading' (duration: 148.991842ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:37.163822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.520945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-twgth\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-06T07:17:37.163853Z","caller":"traceutil/trace.go:171","msg":"trace[1056043420] range","detail":"{range_begin:/registry/pods/openstack/keepalived-twgth; range_end:; response_count:1; response_revision:8078; }","duration":"128.560216ms","start":"2026-08-06T07:17:37.035283Z","end":"2026-08-06T07:17:37.163843Z","steps":["trace[1056043420] 'agreement among raft nodes before linearized reading' (duration: 128.481344ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:37.163728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.293085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-06T07:17:37.163913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.49633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-06T07:17:37.163913Z","caller":"traceutil/trace.go:171","msg":"trace[1974447048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8078; }","duration":"140.503089ms","start":"2026-08-06T07:17:37.023399Z","end":"2026-08-06T07:17:37.163902Z","steps":["trace[1974447048] 'agreement among raft nodes before linearized reading' (duration: 140.223893ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:17:37.163929Z","caller":"traceutil/trace.go:171","msg":"trace[1130215255] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8078; }","duration":"143.532621ms","start":"2026-08-06T07:17:37.020392Z","end":"2026-08-06T07:17:37.163924Z","steps":["trace[1130215255] 'agreement among raft nodes before linearized reading' (duration: 143.48495ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:37.163921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.039492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-08-06T07:17:37.163903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.994951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-06T07:17:37.163968Z","caller":"traceutil/trace.go:171","msg":"trace[1339068253] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8078; }","duration":"146.135284ms","start":"2026-08-06T07:17:37.017817Z","end":"2026-08-06T07:17:37.163952Z","steps":["trace[1339068253] 'agreement among raft nodes before linearized reading' (duration: 145.984781ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:17:37.16399Z","caller":"traceutil/trace.go:171","msg":"trace[773100562] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8078; }","duration":"146.138454ms","start":"2026-08-06T07:17:37.017832Z","end":"2026-08-06T07:17:37.163971Z","steps":["trace[773100562] 'agreement among raft nodes before linearized reading' (duration: 145.828478ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:37.164082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.811594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-06T07:17:37.164113Z","caller":"traceutil/trace.go:171","msg":"trace[1935414530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8078; }","duration":"137.863325ms","start":"2026-08-06T07:17:37.026241Z","end":"2026-08-06T07:17:37.164104Z","steps":["trace[1935414530] 'agreement among raft nodes before linearized reading' (duration: 137.781473ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:48.402977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.657041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T07:17:48.403064Z","caller":"traceutil/trace.go:171","msg":"trace[417191714] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8138; }","duration":"184.792724ms","start":"2026-08-06T07:17:48.218249Z","end":"2026-08-06T07:17:48.403042Z","steps":["trace[417191714] 'range keys from in-memory index tree' (duration: 184.553079ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:17:48.403184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.099109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T07:17:48.403273Z","caller":"traceutil/trace.go:171","msg":"trace[1953843602] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:8138; }","duration":"112.238212ms","start":"2026-08-06T07:17:48.291014Z","end":"2026-08-06T07:17:48.403252Z","steps":["trace[1953843602] 'count revisions from in-memory index tree' (duration: 112.004297ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:17:49.208Z","caller":"traceutil/trace.go:171","msg":"trace[848415215] transaction","detail":"{read_only:false; response_revision:8139; number_of_response:1; }","duration":"267.438482ms","start":"2026-08-06T07:17:48.940541Z","end":"2026-08-06T07:17:49.207979Z","steps":["trace[848415215] 'process raft request' (duration: 267.297509ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:18:08.098884Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6101} {"level":"info","ts":"2026-08-06T07:18:08.202188Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6101,"took":"101.110885ms","hash":1477757144,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":23109632,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-06T07:18:08.202323Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1477757144,"revision":6101,"compact-revision":2973} {"level":"info","ts":"2026-08-06T07:18:18.18961Z","caller":"traceutil/trace.go:171","msg":"trace[1773445279] transaction","detail":"{read_only:false; response_revision:8357; number_of_response:1; }","duration":"158.771523ms","start":"2026-08-06T07:18:18.030813Z","end":"2026-08-06T07:18:18.189585Z","steps":["trace[1773445279] 'process raft request' (duration: 158.579299ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:18:30.588027Z","caller":"traceutil/trace.go:171","msg":"trace[344874203] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8460; }","duration":"124.298319ms","start":"2026-08-06T07:18:30.463697Z","end":"2026-08-06T07:18:30.587995Z","steps":["trace[344874203] 'process raft request' (duration: 114.144171ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:18:30.750497Z","caller":"traceutil/trace.go:171","msg":"trace[1364775781] linearizableReadLoop","detail":"{readStateIndex:8764; appliedIndex:8762; }","duration":"254.824986ms","start":"2026-08-06T07:18:30.495643Z","end":"2026-08-06T07:18:30.750468Z","steps":["trace[1364775781] 'read index received' (duration: 82.216039ms)","trace[1364775781] 'applied index is now lower than readState.Index' (duration: 172.607647ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:18:30.750687Z","caller":"traceutil/trace.go:171","msg":"trace[1914803416] transaction","detail":"{read_only:false; response_revision:8461; number_of_response:1; }","duration":"285.237006ms","start":"2026-08-06T07:18:30.465427Z","end":"2026-08-06T07:18:30.750664Z","steps":["trace[1914803416] 'process raft request' (duration: 278.156893ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:18:30.75101Z","caller":"traceutil/trace.go:171","msg":"trace[1547968824] transaction","detail":"{read_only:false; response_revision:8462; number_of_response:1; }","duration":"254.787695ms","start":"2026-08-06T07:18:30.496187Z","end":"2026-08-06T07:18:30.750974Z","steps":["trace[1547968824] 'process raft request' (duration: 254.188463ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:18:30.751091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.433358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-bw9wr\" ","response":"range_response_count:1 size:4645"} {"level":"warn","ts":"2026-08-06T07:18:30.751138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.312691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T07:18:30.751176Z","caller":"traceutil/trace.go:171","msg":"trace[1321097275] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-bw9wr; range_end:; response_count:1; response_revision:8462; }","duration":"255.55457ms","start":"2026-08-06T07:18:30.495603Z","end":"2026-08-06T07:18:30.751158Z","steps":["trace[1321097275] 'agreement among raft nodes before linearized reading' (duration: 255.387057ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:18:30.751215Z","caller":"traceutil/trace.go:171","msg":"trace[1254363158] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:8462; }","duration":"106.444964ms","start":"2026-08-06T07:18:30.644749Z","end":"2026-08-06T07:18:30.751194Z","steps":["trace[1254363158] 'agreement among raft nodes before linearized reading' (duration: 106.195639ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:18:31.655799Z","caller":"traceutil/trace.go:171","msg":"trace[1931768789] transaction","detail":"{read_only:false; response_revision:8465; number_of_response:1; }","duration":"209.371067ms","start":"2026-08-06T07:18:31.446387Z","end":"2026-08-06T07:18:31.655758Z","steps":["trace[1931768789] 'process raft request' (duration: 209.220254ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:18:31.662041Z","caller":"traceutil/trace.go:171","msg":"trace[358811188] transaction","detail":"{read_only:false; response_revision:8467; number_of_response:1; }","duration":"203.66636ms","start":"2026-08-06T07:18:31.458348Z","end":"2026-08-06T07:18:31.662014Z","steps":["trace[358811188] 'process raft request' (duration: 203.536108ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:18:31.66217Z","caller":"traceutil/trace.go:171","msg":"trace[1569800399] linearizableReadLoop","detail":"{readStateIndex:8770; appliedIndex:8768; }","duration":"211.97377ms","start":"2026-08-06T07:18:31.450185Z","end":"2026-08-06T07:18:31.662159Z","steps":["trace[1569800399] 'read index received' (duration: 205.507227ms)","trace[1569800399] 'applied index is now lower than readState.Index' (duration: 6.465413ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:18:31.662057Z","caller":"traceutil/trace.go:171","msg":"trace[780331805] transaction","detail":"{read_only:false; response_revision:8466; number_of_response:1; }","duration":"213.671425ms","start":"2026-08-06T07:18:31.448355Z","end":"2026-08-06T07:18:31.662026Z","steps":["trace[780331805] 'process raft request' (duration: 213.406089ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:18:31.662414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.638291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-08-06T07:18:31.662465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.286354ms","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-08-06T07:18:31.662476Z","caller":"traceutil/trace.go:171","msg":"trace[38801763] transaction","detail":"{read_only:false; response_revision:8468; number_of_response:1; }","duration":"202.687921ms","start":"2026-08-06T07:18:31.459511Z","end":"2026-08-06T07:18:31.662199Z","steps":["trace[38801763] 'process raft request' (duration: 202.463746ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:18:31.662514Z","caller":"traceutil/trace.go:171","msg":"trace[1845488040] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8468; }","duration":"158.377376ms","start":"2026-08-06T07:18:31.504125Z","end":"2026-08-06T07:18:31.662502Z","steps":["trace[1845488040] 'agreement among raft nodes before linearized reading' (duration: 158.126591ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:18:31.662535Z","caller":"traceutil/trace.go:171","msg":"trace[1233925624] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8468; }","duration":"111.782104ms","start":"2026-08-06T07:18:31.550708Z","end":"2026-08-06T07:18:31.66249Z","steps":["trace[1233925624] 'agreement among raft nodes before linearized reading' (duration: 111.616191ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:18:31.66258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.371602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-06T07:18:31.662688Z","caller":"traceutil/trace.go:171","msg":"trace[879518848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8468; }","duration":"124.536324ms","start":"2026-08-06T07:18:31.538131Z","end":"2026-08-06T07:18:31.662667Z","steps":["trace[879518848] 'agreement among raft nodes before linearized reading' (duration: 124.226588ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:18:31.6624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.191015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"warn","ts":"2026-08-06T07:18:31.662745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.123579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-06T07:18:31.662897Z","caller":"traceutil/trace.go:171","msg":"trace[41264551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8468; }","duration":"108.315093ms","start":"2026-08-06T07:18:31.55456Z","end":"2026-08-06T07:18:31.662875Z","steps":["trace[41264551] 'agreement among raft nodes before linearized reading' (duration: 107.868314ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:18:31.66281Z","caller":"traceutil/trace.go:171","msg":"trace[2001539677] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:8468; }","duration":"212.653174ms","start":"2026-08-06T07:18:31.450141Z","end":"2026-08-06T07:18:31.662794Z","steps":["trace[2001539677] 'agreement among raft nodes before linearized reading' (duration: 212.101373ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:18:31.662418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.030199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-06T07:18:31.663306Z","caller":"traceutil/trace.go:171","msg":"trace[78529416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8468; }","duration":"111.946997ms","start":"2026-08-06T07:18:31.551345Z","end":"2026-08-06T07:18:31.663292Z","steps":["trace[78529416] 'agreement among raft nodes before linearized reading' (duration: 110.919906ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:18:31.662418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.174335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-06T07:18:31.663414Z","caller":"traceutil/trace.go:171","msg":"trace[1738389002] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8468; }","duration":"121.239636ms","start":"2026-08-06T07:18:31.542164Z","end":"2026-08-06T07:18:31.663403Z","steps":["trace[1738389002] 'agreement among raft nodes before linearized reading' (duration: 120.156164ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:18:32.256353Z","caller":"traceutil/trace.go:171","msg":"trace[306601879] transaction","detail":"{read_only:false; response_revision:8475; number_of_response:1; }","duration":"104.71699ms","start":"2026-08-06T07:18:32.151576Z","end":"2026-08-06T07:18:32.256293Z","steps":["trace[306601879] 'process raft request' (duration: 104.462145ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:19:07.221723Z","caller":"traceutil/trace.go:171","msg":"trace[764700062] transaction","detail":"{read_only:false; response_revision:8812; number_of_response:1; }","duration":"166.012121ms","start":"2026-08-06T07:19:07.055695Z","end":"2026-08-06T07:19:07.221707Z","steps":["trace[764700062] 'process raft request' (duration: 165.892918ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:19:08.37606Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890120220892234915,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T07:19:08.877125Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890120220892234915,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T07:19:09.377892Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890120220892234915,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T07:19:09.878023Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890120220892234915,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T07:19:09.980242Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.886363583s","expected-duration":"1s"} {"level":"info","ts":"2026-08-06T07:19:09.980982Z","caller":"traceutil/trace.go:171","msg":"trace[1197331525] transaction","detail":"{read_only:false; response_revision:8814; number_of_response:1; }","duration":"2.153065033s","start":"2026-08-06T07:19:07.827683Z","end":"2026-08-06T07:19:09.980748Z","steps":["trace[1197331525] 'process raft request' (duration: 2.152810608s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:19:09.981133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:19:07.827656Z","time spent":"2.153385269s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T07:19:09.984442Z","caller":"traceutil/trace.go:171","msg":"trace[814438908] transaction","detail":"{read_only:false; response_revision:8816; number_of_response:1; }","duration":"2.144904975s","start":"2026-08-06T07:19:07.839514Z","end":"2026-08-06T07:19:09.984419Z","steps":["trace[814438908] 'process raft request' (duration: 2.144809823s)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:19:09.984455Z","caller":"traceutil/trace.go:171","msg":"trace[1847978621] transaction","detail":"{read_only:false; response_revision:8815; number_of_response:1; }","duration":"2.155565773s","start":"2026-08-06T07:19:07.828863Z","end":"2026-08-06T07:19:09.984428Z","steps":["trace[1847978621] 'process raft request' (duration: 2.155319788s)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:19:09.984578Z","caller":"traceutil/trace.go:171","msg":"trace[1192735857] linearizableReadLoop","detail":"{readStateIndex:9131; appliedIndex:9128; }","duration":"2.109192556s","start":"2026-08-06T07:19:07.875327Z","end":"2026-08-06T07:19:09.98452Z","steps":["trace[1192735857] 'read index received' (duration: 2.105214535s)","trace[1192735857] 'applied index is now lower than readState.Index' (duration: 3.976191ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:19:09.984603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:19:07.839499Z","time spent":"2.145007587s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T07:19:09.984608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:19:07.828842Z","time spent":"2.155678276s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T07:19:09.984973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.109635116s","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-08-06T07:19:09.985034Z","caller":"traceutil/trace.go:171","msg":"trace[1311905161] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8817; }","duration":"2.109728228s","start":"2026-08-06T07:19:07.875286Z","end":"2026-08-06T07:19:09.985014Z","steps":["trace[1311905161] 'agreement among raft nodes before linearized reading' (duration: 2.109535114s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:19:09.985096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:19:07.875273Z","time spent":"2.10980725s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-06T07:19:09.988704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.081256408s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T07:19:09.988762Z","caller":"traceutil/trace.go:171","msg":"trace[1731027944] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:8817; }","duration":"1.081312129s","start":"2026-08-06T07:19:08.907429Z","end":"2026-08-06T07:19:09.988742Z","steps":["trace[1731027944] 'agreement among raft nodes before linearized reading' (duration: 1.081221768s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:19:09.988761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.076664311s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-06T07:19:09.988772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.769981467s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T07:19:09.988751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.961995879s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-56c8495665-59f7s.18c92620b1675ad8\" ","response":"range_response_count:1 size:1135"} {"level":"warn","ts":"2026-08-06T07:19:09.988691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.22272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T07:19:09.988797Z","caller":"traceutil/trace.go:171","msg":"trace[225190482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8817; }","duration":"2.076728052s","start":"2026-08-06T07:19:07.912061Z","end":"2026-08-06T07:19:09.988789Z","steps":["trace[225190482] 'agreement among raft nodes before linearized reading' (duration: 2.07660615s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:19:09.9888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.048753591s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-twgth\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-08-06T07:19:09.988822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:19:07.912049Z","time spent":"2.076767544s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-06T07:19:09.988811Z","caller":"traceutil/trace.go:171","msg":"trace[1815775887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8817; }","duration":"1.770050418s","start":"2026-08-06T07:19:08.218748Z","end":"2026-08-06T07:19:09.988799Z","steps":["trace[1815775887] 'agreement among raft nodes before linearized reading' (duration: 1.770004278s)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:19:09.988819Z","caller":"traceutil/trace.go:171","msg":"trace[1300537426] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:8817; }","duration":"198.372853ms","start":"2026-08-06T07:19:09.790439Z","end":"2026-08-06T07:19:09.988812Z","steps":["trace[1300537426] 'agreement among raft nodes before linearized reading' (duration: 198.190819ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:19:09.988833Z","caller":"traceutil/trace.go:171","msg":"trace[1114203351] range","detail":"{range_begin:/registry/events/openstack/barbican-api-56c8495665-59f7s.18c92620b1675ad8; range_end:; response_count:1; response_revision:8817; }","duration":"1.962088351s","start":"2026-08-06T07:19:08.026724Z","end":"2026-08-06T07:19:09.988812Z","steps":["trace[1114203351] 'agreement among raft nodes before linearized reading' (duration: 1.961863946s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:19:09.988848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:19:08.21873Z","time spent":"1.770112019s","remote":"127.0.0.1:60664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-06T07:19:09.988848Z","caller":"traceutil/trace.go:171","msg":"trace[1030935189] range","detail":"{range_begin:/registry/pods/openstack/keepalived-twgth; range_end:; response_count:1; response_revision:8817; }","duration":"2.048822072s","start":"2026-08-06T07:19:07.940012Z","end":"2026-08-06T07:19:09.988834Z","steps":["trace[1030935189] 'agreement among raft nodes before linearized reading' (duration: 2.04872653s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:19:09.988884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:19:07.939998Z","time spent":"2.048877464s","remote":"127.0.0.1:60862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-twgth\" "} {"level":"warn","ts":"2026-08-06T07:19:09.988706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.067156107s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-06T07:19:09.988954Z","caller":"traceutil/trace.go:171","msg":"trace[455649544] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8817; }","duration":"2.067440903s","start":"2026-08-06T07:19:07.921504Z","end":"2026-08-06T07:19:09.988945Z","steps":["trace[455649544] 'agreement among raft nodes before linearized reading' (duration: 2.067103667s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:19:09.988961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.097677782s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-06T07:19:09.988979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:19:07.921488Z","time spent":"2.067484604s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-06T07:19:09.988987Z","caller":"traceutil/trace.go:171","msg":"trace[380344965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8817; }","duration":"2.097720712s","start":"2026-08-06T07:19:07.891258Z","end":"2026-08-06T07:19:09.988979Z","steps":["trace[380344965] 'agreement among raft nodes before linearized reading' (duration: 2.097644711s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:19:09.989018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:19:07.891248Z","time spent":"2.097766043s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-06T07:19:09.988882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:19:08.026678Z","time spent":"1.962195803s","remote":"127.0.0.1:60748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1158,"request content":"key:\"/registry/events/openstack/barbican-api-56c8495665-59f7s.18c92620b1675ad8\" "} {"level":"warn","ts":"2026-08-06T07:19:09.988795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:19:08.90737Z","time spent":"1.081418501s","remote":"127.0.0.1:39176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"warn","ts":"2026-08-06T07:19:09.989138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.067568556s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-06T07:19:09.98918Z","caller":"traceutil/trace.go:171","msg":"trace[680085452] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8817; }","duration":"2.067657138s","start":"2026-08-06T07:19:07.921512Z","end":"2026-08-06T07:19:09.989169Z","steps":["trace[680085452] 'agreement among raft nodes before linearized reading' (duration: 2.067555786s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:19:09.989209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:19:07.92149Z","time spent":"2.067710719s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-06T07:19:09.989349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.108960931s","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-08-06T07:19:09.989382Z","caller":"traceutil/trace.go:171","msg":"trace[1681584709] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8817; }","duration":"2.109017513s","start":"2026-08-06T07:19:07.880357Z","end":"2026-08-06T07:19:09.989375Z","steps":["trace[1681584709] 'agreement among raft nodes before linearized reading' (duration: 2.108194107s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:19:09.989402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:19:07.880346Z","time spent":"2.109051043s","remote":"127.0.0.1:60916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-06T07:19:19.883744Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002430.wal"} {"level":"info","ts":"2026-08-06T07:19:31.437658Z","caller":"traceutil/trace.go:171","msg":"trace[752699791] transaction","detail":"{read_only:false; response_revision:9090; number_of_response:1; }","duration":"103.488993ms","start":"2026-08-06T07:19:31.334118Z","end":"2026-08-06T07:19:31.437607Z","steps":["trace[752699791] 'process raft request' (duration: 76.049343ms)","trace[752699791] 'compare' (duration: 27.209456ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:19:42.361456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.263603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T07:19:42.361612Z","caller":"traceutil/trace.go:171","msg":"trace[1805081188] linearizableReadLoop","detail":"{readStateIndex:9507; appliedIndex:9504; }","duration":"180.053618ms","start":"2026-08-06T07:19:42.181547Z","end":"2026-08-06T07:19:42.3616Z","steps":["trace[1805081188] 'read index received' (duration: 44.579571ms)","trace[1805081188] 'applied index is now lower than readState.Index' (duration: 135.473347ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:19:42.361725Z","caller":"traceutil/trace.go:171","msg":"trace[767634183] transaction","detail":"{read_only:false; response_revision:9182; number_of_response:1; }","duration":"182.675811ms","start":"2026-08-06T07:19:42.179006Z","end":"2026-08-06T07:19:42.361682Z","steps":["trace[767634183] 'process raft request' (duration: 47.121432ms)","trace[767634183] 'compare' (duration: 135.153571ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T07:19:42.361884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.336364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:31 size:291249"} {"level":"info","ts":"2026-08-06T07:19:42.361908Z","caller":"traceutil/trace.go:171","msg":"trace[580346681] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:31; response_revision:9184; }","duration":"180.361875ms","start":"2026-08-06T07:19:42.181539Z","end":"2026-08-06T07:19:42.361901Z","steps":["trace[580346681] 'agreement among raft nodes before linearized reading' (duration: 180.095599ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:19:42.361882Z","caller":"traceutil/trace.go:171","msg":"trace[1532701051] transaction","detail":"{read_only:false; response_revision:9184; number_of_response:1; }","duration":"181.493748ms","start":"2026-08-06T07:19:42.180359Z","end":"2026-08-06T07:19:42.361853Z","steps":["trace[1532701051] 'process raft request' (duration: 181.215972ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:19:42.361897Z","caller":"traceutil/trace.go:171","msg":"trace[322950394] transaction","detail":"{read_only:false; response_revision:9183; number_of_response:1; }","duration":"181.650051ms","start":"2026-08-06T07:19:42.180169Z","end":"2026-08-06T07:19:42.361819Z","steps":["trace[322950394] 'process raft request' (duration: 181.368875ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:20:52.17866Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"380f6760faea5f9e","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-06T07:20:52.185571Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-06T07:20:52.185697Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-06T07:23:08.105279Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8251} {"level":"info","ts":"2026-08-06T07:23:08.186721Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8251,"took":"79.235436ms","hash":3908395059,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":26497024,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-06T07:23:08.186802Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3908395059,"revision":8251,"compact-revision":6101} {"level":"info","ts":"2026-08-06T07:28:08.111602Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10924} {"level":"info","ts":"2026-08-06T07:28:08.19179Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10924,"took":"77.402269ms","hash":417178240,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":28147712,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-06T07:28:08.191858Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":417178240,"revision":10924,"compact-revision":8251} {"level":"info","ts":"2026-08-06T07:29:53.46645Z","caller":"traceutil/trace.go:171","msg":"trace[249491941] linearizableReadLoop","detail":"{readStateIndex:14108; appliedIndex:14107; }","duration":"193.785461ms","start":"2026-08-06T07:29:53.272641Z","end":"2026-08-06T07:29:53.466427Z","steps":["trace[249491941] 'read index received' (duration: 193.527666ms)","trace[249491941] 'applied index is now lower than readState.Index' (duration: 256.955µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:29:53.466599Z","caller":"traceutil/trace.go:171","msg":"trace[1001188940] transaction","detail":"{read_only:false; response_revision:13636; number_of_response:1; }","duration":"197.20446ms","start":"2026-08-06T07:29:53.269383Z","end":"2026-08-06T07:29:53.466587Z","steps":["trace[1001188940] 'process raft request' (duration: 196.878453ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:29:53.466783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.541114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-06T07:29:53.466853Z","caller":"traceutil/trace.go:171","msg":"trace[817842546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13636; }","duration":"191.642646ms","start":"2026-08-06T07:29:53.275195Z","end":"2026-08-06T07:29:53.466837Z","steps":["trace[817842546] 'agreement among raft nodes before linearized reading' (duration: 191.475793ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:29:53.466995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.346322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-06T07:29:53.467078Z","caller":"traceutil/trace.go:171","msg":"trace[2031053668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13636; }","duration":"194.458105ms","start":"2026-08-06T07:29:53.272604Z","end":"2026-08-06T07:29:53.467062Z","steps":["trace[2031053668] 'agreement among raft nodes before linearized reading' (duration: 194.25525ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:29:53.708018Z","caller":"traceutil/trace.go:171","msg":"trace[1264596828] transaction","detail":"{read_only:false; response_revision:13638; number_of_response:1; }","duration":"237.040673ms","start":"2026-08-06T07:29:53.470949Z","end":"2026-08-06T07:29:53.70799Z","steps":["trace[1264596828] 'process raft request' (duration: 236.96403ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:29:53.708022Z","caller":"traceutil/trace.go:171","msg":"trace[1380548231] transaction","detail":"{read_only:false; response_revision:13637; number_of_response:1; }","duration":"237.440031ms","start":"2026-08-06T07:29:53.470542Z","end":"2026-08-06T07:29:53.707982Z","steps":["trace[1380548231] 'process raft request' (duration: 204.712283ms)","trace[1380548231] 'compare' (duration: 32.466202ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:29:53.708238Z","caller":"traceutil/trace.go:171","msg":"trace[1925304798] transaction","detail":"{read_only:false; response_revision:13639; number_of_response:1; }","duration":"237.254968ms","start":"2026-08-06T07:29:53.470972Z","end":"2026-08-06T07:29:53.708227Z","steps":["trace[1925304798] 'process raft request' (duration: 236.982942ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:29:53.708483Z","caller":"traceutil/trace.go:171","msg":"trace[1739856135] transaction","detail":"{read_only:false; response_revision:13640; number_of_response:1; }","duration":"237.212796ms","start":"2026-08-06T07:29:53.471248Z","end":"2026-08-06T07:29:53.708461Z","steps":["trace[1739856135] 'process raft request' (duration: 236.89315ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:29:53.708562Z","caller":"traceutil/trace.go:171","msg":"trace[251324367] transaction","detail":"{read_only:false; response_revision:13641; number_of_response:1; }","duration":"236.708397ms","start":"2026-08-06T07:29:53.471823Z","end":"2026-08-06T07:29:53.708531Z","steps":["trace[251324367] 'process raft request' (duration: 236.552884ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:33:08.118389Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12959} {"level":"info","ts":"2026-08-06T07:33:08.19734Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12959,"took":"76.291316ms","hash":2516685294,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":30752768,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-06T07:33:08.197462Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2516685294,"revision":12959,"compact-revision":10924} {"level":"warn","ts":"2026-08-06T07:36:47.432169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.386997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-tplb6\" ","response":"range_response_count:1 size:22133"} {"level":"info","ts":"2026-08-06T07:36:47.432284Z","caller":"traceutil/trace.go:171","msg":"trace[1328778567] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-tplb6; range_end:; response_count:1; response_revision:17100; }","duration":"112.54756ms","start":"2026-08-06T07:36:47.319718Z","end":"2026-08-06T07:36:47.432266Z","steps":["trace[1328778567] 'range keys from in-memory index tree' (duration: 112.166823ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:36:53.463093Z","caller":"traceutil/trace.go:171","msg":"trace[2001133662] linearizableReadLoop","detail":"{readStateIndex:17728; appliedIndex:17727; }","duration":"245.877044ms","start":"2026-08-06T07:36:53.217184Z","end":"2026-08-06T07:36:53.463061Z","steps":["trace[2001133662] 'read index received' (duration: 245.579618ms)","trace[2001133662] 'applied index is now lower than readState.Index' (duration: 296.236µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:36:53.463204Z","caller":"traceutil/trace.go:171","msg":"trace[720391548] transaction","detail":"{read_only:false; response_revision:17142; number_of_response:1; }","duration":"275.51569ms","start":"2026-08-06T07:36:53.187654Z","end":"2026-08-06T07:36:53.46317Z","steps":["trace[720391548] 'process raft request' (duration: 275.183583ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:36:53.463327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.12374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:36:53.463409Z","caller":"traceutil/trace.go:171","msg":"trace[1576619710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17142; }","duration":"246.264082ms","start":"2026-08-06T07:36:53.21713Z","end":"2026-08-06T07:36:53.463394Z","steps":["trace[1576619710] 'agreement among raft nodes before linearized reading' (duration: 246.123279ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:36:53.463472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.85633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T07:36:53.46352Z","caller":"traceutil/trace.go:171","msg":"trace[457676680] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:17142; }","duration":"212.947381ms","start":"2026-08-06T07:36:53.250559Z","end":"2026-08-06T07:36:53.463507Z","steps":["trace[457676680] 'agreement among raft nodes before linearized reading' (duration: 212.842549ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:36:53.463782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.345919ms","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-08-06T07:36:53.463866Z","caller":"traceutil/trace.go:171","msg":"trace[291441639] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:17142; }","duration":"165.477191ms","start":"2026-08-06T07:36:53.29837Z","end":"2026-08-06T07:36:53.463848Z","steps":["trace[291441639] 'agreement among raft nodes before linearized reading' (duration: 165.283497ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:36:53.637995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.431086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-tplb6\" ","response":"range_response_count:1 size:22133"} {"level":"info","ts":"2026-08-06T07:36:53.638051Z","caller":"traceutil/trace.go:171","msg":"trace[1452337125] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-tplb6; range_end:; response_count:1; response_revision:17142; }","duration":"111.563109ms","start":"2026-08-06T07:36:53.526476Z","end":"2026-08-06T07:36:53.638039Z","steps":["trace[1452337125] 'range keys from in-memory index tree' (duration: 111.239163ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:38:08.12422Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15321} {"level":"info","ts":"2026-08-06T07:38:08.218996Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15321,"took":"92.01619ms","hash":1265440676,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":33820672,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-06T07:38:08.219072Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1265440676,"revision":15321,"compact-revision":12959} {"level":"info","ts":"2026-08-06T07:38:14.374595Z","caller":"traceutil/trace.go:171","msg":"trace[399197941] transaction","detail":"{read_only:false; response_revision:18015; number_of_response:1; }","duration":"105.821452ms","start":"2026-08-06T07:38:14.268742Z","end":"2026-08-06T07:38:14.374563Z","steps":["trace[399197941] 'process raft request' (duration: 105.582067ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:41:10.133568Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"380f6760faea5f9e","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-06T07:41:10.139183Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-06T07:41:10.139299Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-06T07:42:17.194754Z","caller":"traceutil/trace.go:171","msg":"trace[508635459] transaction","detail":"{read_only:false; response_revision:19709; number_of_response:1; }","duration":"114.768098ms","start":"2026-08-06T07:42:17.0799Z","end":"2026-08-06T07:42:17.194668Z","steps":["trace[508635459] 'process raft request' (duration: 114.597015ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:42:19.70802Z","caller":"traceutil/trace.go:171","msg":"trace[178662457] transaction","detail":"{read_only:false; response_revision:19730; number_of_response:1; }","duration":"108.49344ms","start":"2026-08-06T07:42:19.599501Z","end":"2026-08-06T07:42:19.707994Z","steps":["trace[178662457] 'process raft request' (duration: 82.368766ms)","trace[178662457] 'compare' (duration: 25.9477ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:42:23.453501Z","caller":"traceutil/trace.go:171","msg":"trace[84405726] transaction","detail":"{read_only:false; response_revision:19741; number_of_response:1; }","duration":"154.815707ms","start":"2026-08-06T07:42:23.298618Z","end":"2026-08-06T07:42:23.453434Z","steps":["trace[84405726] 'process raft request' (duration: 154.349748ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:42:23.590132Z","caller":"traceutil/trace.go:171","msg":"trace[1675541815] linearizableReadLoop","detail":"{readStateIndex:20416; appliedIndex:20414; }","duration":"129.336086ms","start":"2026-08-06T07:42:23.460779Z","end":"2026-08-06T07:42:23.590115Z","steps":["trace[1675541815] 'read index received' (duration: 78.267641ms)","trace[1675541815] 'applied index is now lower than readState.Index' (duration: 51.067905ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:42:23.590171Z","caller":"traceutil/trace.go:171","msg":"trace[71426777] transaction","detail":"{read_only:false; response_revision:19743; number_of_response:1; }","duration":"143.504715ms","start":"2026-08-06T07:42:23.446629Z","end":"2026-08-06T07:42:23.590134Z","steps":["trace[71426777] 'process raft request' (duration: 143.412303ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:42:23.590452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.658772ms","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-08-06T07:42:23.590486Z","caller":"traceutil/trace.go:171","msg":"trace[421995802] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19743; }","duration":"129.728794ms","start":"2026-08-06T07:42:23.460749Z","end":"2026-08-06T07:42:23.590478Z","steps":["trace[421995802] 'agreement among raft nodes before linearized reading' (duration: 129.460059ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:42:23.590157Z","caller":"traceutil/trace.go:171","msg":"trace[2135975055] transaction","detail":"{read_only:false; response_revision:19742; number_of_response:1; }","duration":"144.040566ms","start":"2026-08-06T07:42:23.446083Z","end":"2026-08-06T07:42:23.590124Z","steps":["trace[2135975055] 'process raft request' (duration: 93.017682ms)","trace[2135975055] 'compare' (duration: 50.674897ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:42:23.843856Z","caller":"traceutil/trace.go:171","msg":"trace[1313131245] transaction","detail":"{read_only:false; response_revision:19745; number_of_response:1; }","duration":"249.075954ms","start":"2026-08-06T07:42:23.594749Z","end":"2026-08-06T07:42:23.843825Z","steps":["trace[1313131245] 'process raft request' (duration: 248.884651ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:42:23.844152Z","caller":"traceutil/trace.go:171","msg":"trace[944613452] transaction","detail":"{read_only:false; response_revision:19746; number_of_response:1; }","duration":"249.133697ms","start":"2026-08-06T07:42:23.59498Z","end":"2026-08-06T07:42:23.844114Z","steps":["trace[944613452] 'process raft request' (duration: 248.80325ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:42:23.844789Z","caller":"traceutil/trace.go:171","msg":"trace[721371762] linearizableReadLoop","detail":"{readStateIndex:20420; appliedIndex:20416; }","duration":"239.573562ms","start":"2026-08-06T07:42:23.60519Z","end":"2026-08-06T07:42:23.844764Z","steps":["trace[721371762] 'read index received' (duration: 80.59031ms)","trace[721371762] 'applied index is now lower than readState.Index' (duration: 158.982122ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:42:23.844991Z","caller":"traceutil/trace.go:171","msg":"trace[280463087] transaction","detail":"{read_only:false; response_revision:19747; number_of_response:1; }","duration":"248.409702ms","start":"2026-08-06T07:42:23.59654Z","end":"2026-08-06T07:42:23.84495Z","steps":["trace[280463087] 'process raft request' (duration: 247.732448ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:42:23.845279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.161968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-06T07:42:23.845357Z","caller":"traceutil/trace.go:171","msg":"trace[866074129] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19747; }","duration":"120.278711ms","start":"2026-08-06T07:42:23.72506Z","end":"2026-08-06T07:42:23.845339Z","steps":["trace[866074129] 'agreement among raft nodes before linearized reading' (duration: 120.066547ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:42:23.845488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.273606ms","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-08-06T07:42:23.84554Z","caller":"traceutil/trace.go:171","msg":"trace[1361471468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19747; }","duration":"240.360678ms","start":"2026-08-06T07:42:23.605165Z","end":"2026-08-06T07:42:23.845526Z","steps":["trace[1361471468] 'agreement among raft nodes before linearized reading' (duration: 240.186254ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:43:08.131553Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17952} {"level":"info","ts":"2026-08-06T07:43:08.251721Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17952,"took":"117.023524ms","hash":75782812,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":30236672,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-06T07:43:08.2518Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":75782812,"revision":17952,"compact-revision":15321} {"level":"info","ts":"2026-08-06T07:46:05.018791Z","caller":"traceutil/trace.go:171","msg":"trace[1097285900] transaction","detail":"{read_only:false; response_revision:22116; number_of_response:1; }","duration":"105.394536ms","start":"2026-08-06T07:46:04.913378Z","end":"2026-08-06T07:46:05.018772Z","steps":["trace[1097285900] 'process raft request' (duration: 97.177798ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:46:05.306941Z","caller":"traceutil/trace.go:171","msg":"trace[761832348] transaction","detail":"{read_only:false; response_revision:22120; number_of_response:1; }","duration":"282.281865ms","start":"2026-08-06T07:46:05.024636Z","end":"2026-08-06T07:46:05.306918Z","steps":["trace[761832348] 'process raft request' (duration: 281.930028ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:46:05.306968Z","caller":"traceutil/trace.go:171","msg":"trace[1706760431] transaction","detail":"{read_only:false; response_revision:22119; number_of_response:1; }","duration":"282.653473ms","start":"2026-08-06T07:46:05.024285Z","end":"2026-08-06T07:46:05.306939Z","steps":["trace[1706760431] 'process raft request' (duration: 282.241854ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:46:05.306989Z","caller":"traceutil/trace.go:171","msg":"trace[830359470] transaction","detail":"{read_only:false; response_revision:22122; number_of_response:1; }","duration":"264.418199ms","start":"2026-08-06T07:46:05.042534Z","end":"2026-08-06T07:46:05.306952Z","steps":["trace[830359470] 'process raft request' (duration: 264.103723ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:46:05.307053Z","caller":"traceutil/trace.go:171","msg":"trace[1908321240] transaction","detail":"{read_only:false; response_revision:22121; number_of_response:1; }","duration":"281.200713ms","start":"2026-08-06T07:46:05.025797Z","end":"2026-08-06T07:46:05.306998Z","steps":["trace[1908321240] 'process raft request' (duration: 280.804005ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:46:05.307057Z","caller":"traceutil/trace.go:171","msg":"trace[1084333918] transaction","detail":"{read_only:false; response_revision:22117; number_of_response:1; }","duration":"282.770125ms","start":"2026-08-06T07:46:05.024221Z","end":"2026-08-06T07:46:05.306991Z","steps":["trace[1084333918] 'process raft request' (duration: 212.096059ms)","trace[1084333918] 'compare' (duration: 70.007912ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:46:05.307056Z","caller":"traceutil/trace.go:171","msg":"trace[499187782] transaction","detail":"{read_only:false; response_revision:22118; number_of_response:1; }","duration":"282.746195ms","start":"2026-08-06T07:46:05.02424Z","end":"2026-08-06T07:46:05.306986Z","steps":["trace[499187782] 'process raft request' (duration: 282.234284ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:48:08.142003Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20240} {"level":"info","ts":"2026-08-06T07:48:08.255519Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20240,"took":"109.635952ms","hash":2598343913,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":43393024,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-06T07:48:08.255604Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2598343913,"revision":20240,"compact-revision":17952} {"level":"warn","ts":"2026-08-06T07:52:45.277663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.239644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/auth-system/keycloak\" ","response":"range_response_count:1 size:993"} {"level":"warn","ts":"2026-08-06T07:52:45.277722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.624238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-06T07:52:45.277769Z","caller":"traceutil/trace.go:171","msg":"trace[1149131282] range","detail":"{range_begin:/registry/services/endpoints/auth-system/keycloak; range_end:; response_count:1; response_revision:25394; }","duration":"112.368547ms","start":"2026-08-06T07:52:45.16538Z","end":"2026-08-06T07:52:45.277749Z","steps":["trace[1149131282] 'range keys from in-memory index tree' (duration: 112.061491ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:52:45.277805Z","caller":"traceutil/trace.go:171","msg":"trace[2070901437] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:25394; }","duration":"100.75909ms","start":"2026-08-06T07:52:45.177025Z","end":"2026-08-06T07:52:45.277784Z","steps":["trace[2070901437] 'count revisions from in-memory index tree' (duration: 100.534295ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:52:45.277868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.966194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/\" range_end:\"/registry/events/auth-system0\" limit:500 ","response":"range_response_count:21 size:16655"} {"level":"warn","ts":"2026-08-06T07:52:45.277992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.152913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/auth-system/\" range_end:\"/registry/ingress/auth-system0\" limit:500 ","response":"range_response_count:1 size:870"} {"level":"info","ts":"2026-08-06T07:52:45.278022Z","caller":"traceutil/trace.go:171","msg":"trace[772470889] range","detail":"{range_begin:/registry/events/auth-system/; range_end:/registry/events/auth-system0; response_count:21; response_revision:25394; }","duration":"121.165467ms","start":"2026-08-06T07:52:45.156833Z","end":"2026-08-06T07:52:45.277999Z","steps":["trace[772470889] 'range keys from in-memory index tree' (duration: 120.753848ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:52:45.278065Z","caller":"traceutil/trace.go:171","msg":"trace[495667606] range","detail":"{range_begin:/registry/ingress/auth-system/; range_end:/registry/ingress/auth-system0; response_count:1; response_revision:25394; }","duration":"113.266995ms","start":"2026-08-06T07:52:45.164781Z","end":"2026-08-06T07:52:45.278048Z","steps":["trace[495667606] 'range keys from in-memory index tree' (duration: 112.946919ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:53:08.161913Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23322} {"level":"info","ts":"2026-08-06T07:53:08.317421Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23322,"took":"152.424656ms","hash":3065698501,"current-db-size-bytes":58892288,"current-db-size":"59 MB","current-db-size-in-use-bytes":30187520,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-06T07:53:08.317486Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3065698501,"revision":23322,"compact-revision":20240} {"level":"warn","ts":"2026-08-06T07:53:51.77197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.419228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 ","response":"range_response_count:500 size:388981"} {"level":"info","ts":"2026-08-06T07:53:51.772048Z","caller":"traceutil/trace.go:171","msg":"trace[1867510986] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:500; response_revision:25884; }","duration":"315.541971ms","start":"2026-08-06T07:53:51.456493Z","end":"2026-08-06T07:53:51.772035Z","steps":["trace[1867510986] 'range keys from in-memory index tree' (duration: 314.130162ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:53:51.772093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T07:53:51.456481Z","time spent":"315.595142ms","remote":"127.0.0.1:60748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1881,"response size":389004,"request content":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 "} {"level":"info","ts":"2026-08-06T07:53:52.269307Z","caller":"traceutil/trace.go:171","msg":"trace[1078892570] linearizableReadLoop","detail":"{readStateIndex:26743; appliedIndex:26742; }","duration":"128.387854ms","start":"2026-08-06T07:53:52.140897Z","end":"2026-08-06T07:53:52.269285Z","steps":["trace[1078892570] 'read index received' (duration: 128.355354ms)","trace[1078892570] 'applied index is now lower than readState.Index' (duration: 31.52µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T07:53:52.269414Z","caller":"traceutil/trace.go:171","msg":"trace[1426552495] transaction","detail":"{read_only:false; response_revision:25892; number_of_response:1; }","duration":"145.828901ms","start":"2026-08-06T07:53:52.123546Z","end":"2026-08-06T07:53:52.269375Z","steps":["trace[1426552495] 'process raft request' (duration: 145.603537ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:53:52.271609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.689012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/placement-ks-user-hq7z4.18c926baa4c19d74\\000\" range_end:\"/registry/events/openstack0\" limit:2000 revision:25891 ","response":"range_response_count:381 size:291668"} {"level":"info","ts":"2026-08-06T07:53:52.271684Z","caller":"traceutil/trace.go:171","msg":"trace[1279738684] range","detail":"{range_begin:/registry/events/openstack/placement-ks-user-hq7z4.18c926baa4c19d74\u0000; range_end:/registry/events/openstack0; response_count:381; response_revision:25892; }","duration":"130.788674ms","start":"2026-08-06T07:53:52.14088Z","end":"2026-08-06T07:53:52.271669Z","steps":["trace[1279738684] 'agreement among raft nodes before linearized reading' (duration: 128.524458ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T07:53:52.273795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.60075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/openstack/rabbitmq-messaging-topology-operator\" ","response":"range_response_count:1 size:1440"} {"level":"info","ts":"2026-08-06T07:53:52.273884Z","caller":"traceutil/trace.go:171","msg":"trace[1687136761] range","detail":"{range_begin:/registry/networkpolicies/openstack/rabbitmq-messaging-topology-operator; range_end:; response_count:1; response_revision:25894; }","duration":"106.698292ms","start":"2026-08-06T07:53:52.167165Z","end":"2026-08-06T07:53:52.273863Z","steps":["trace[1687136761] 'agreement among raft nodes before linearized reading' (duration: 106.548799ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T07:53:52.273903Z","caller":"traceutil/trace.go:171","msg":"trace[361025721] transaction","detail":"{read_only:false; response_revision:25893; number_of_response:1; }","duration":"119.087695ms","start":"2026-08-06T07:53:52.154771Z","end":"2026-08-06T07:53:52.273859Z","steps":["trace[361025721] 'process raft request' (duration: 118.752488ms)"],"step_count":1}