{"level":"warn","ts":"2026-04-22T06:07:36.785987Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T06:07:36.786064Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.4: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.4:2380","--initial-cluster=instance=https://199.204.45.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.4:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-22T06:07:36.786123Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T06:07:36.786129Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-04-22T06:07:36.786144Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-22T06:07:36.786717Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"]} {"level":"info","ts":"2026-04-22T06:07:36.786787Z","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.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.4:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-22T06:07:36.792352Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.3683ms"} {"level":"info","ts":"2026-04-22T06:07:36.802146Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"66f7b57d56ad567d","cluster-id":"ec267dcaac715770"} {"level":"info","ts":"2026-04-22T06:07:36.802258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T06:07:36.802291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 0"} {"level":"info","ts":"2026-04-22T06:07:36.802305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 66f7b57d56ad567d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T06:07:36.802317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 1"} {"level":"info","ts":"2026-04-22T06:07:36.802365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"warn","ts":"2026-04-22T06:07:36.810038Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T06:07:36.81334Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T06:07:36.814591Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-22T06:07:36.81717Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"66f7b57d56ad567d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T06:07:36.81744Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T06:07:36.817549Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T06:07:36.81756Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T06:07:36.817473Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"66f7b57d56ad567d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T06:07:36.818668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"info","ts":"2026-04-22T06:07:36.819828Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","added-peer-id":"66f7b57d56ad567d","added-peer-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-04-22T06:07:36.823357Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-22T06:07:36.823557Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-04-22T06:07:36.823631Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-04-22T06:07:36.823779Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"66f7b57d56ad567d","initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T06:07:36.823839Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T06:07:37.303038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T06:07:37.303146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T06:07:37.303322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgPreVoteResp from 66f7b57d56ad567d at term 1"} {"level":"info","ts":"2026-04-22T06:07:37.303395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became candidate at term 2"} {"level":"info","ts":"2026-04-22T06:07:37.30342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgVoteResp from 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-04-22T06:07:37.303478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became leader at term 2"} {"level":"info","ts":"2026-04-22T06:07:37.303555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 66f7b57d56ad567d elected leader 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-04-22T06:07:37.304892Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"66f7b57d56ad567d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.4:2379]}","request-path":"/0/members/66f7b57d56ad567d/attributes","cluster-id":"ec267dcaac715770","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T06:07:37.304896Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T06:07:37.304937Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:07:37.30499Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T06:07:37.305282Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T06:07:37.305361Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T06:07:37.306031Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:07:37.306148Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:07:37.306185Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T06:07:37.308319Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.4:2379"} {"level":"info","ts":"2026-04-22T06:07:37.308945Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T06:07:55.140982Z","caller":"traceutil/trace.go:171","msg":"trace[497085735] linearizableReadLoop","detail":"{readStateIndex:454; appliedIndex:453; }","duration":"163.891122ms","start":"2026-04-22T06:07:54.977069Z","end":"2026-04-22T06:07:55.14096Z","steps":["trace[497085735] 'read index received' (duration: 163.677687ms)","trace[497085735] 'applied index is now lower than readState.Index' (duration: 212.485µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:07:55.141097Z","caller":"traceutil/trace.go:171","msg":"trace[446639022] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"170.841769ms","start":"2026-04-22T06:07:54.970233Z","end":"2026-04-22T06:07:55.141075Z","steps":["trace[446639022] 'process raft request' (duration: 170.588723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:55.14127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.149089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-22T06:07:55.141367Z","caller":"traceutil/trace.go:171","msg":"trace[1035916153] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:440; }","duration":"164.328353ms","start":"2026-04-22T06:07:54.977023Z","end":"2026-04-22T06:07:55.141351Z","steps":["trace[1035916153] 'agreement among raft nodes before linearized reading' (duration: 164.129637ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:55.343108Z","caller":"traceutil/trace.go:171","msg":"trace[1857893659] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"197.180218ms","start":"2026-04-22T06:07:55.145902Z","end":"2026-04-22T06:07:55.343082Z","steps":["trace[1857893659] 'process raft request' (duration: 180.573647ms)","trace[1857893659] 'compare' (duration: 16.401475ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:07:55.343434Z","caller":"traceutil/trace.go:171","msg":"trace[553248321] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"197.481144ms","start":"2026-04-22T06:07:55.14594Z","end":"2026-04-22T06:07:55.343421Z","steps":["trace[553248321] 'process raft request' (duration: 197.099046ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:55.343467Z","caller":"traceutil/trace.go:171","msg":"trace[684044560] linearizableReadLoop","detail":"{readStateIndex:458; appliedIndex:454; }","duration":"165.579811ms","start":"2026-04-22T06:07:55.177862Z","end":"2026-04-22T06:07:55.343442Z","steps":["trace[684044560] 'read index received' (duration: 148.702524ms)","trace[684044560] 'applied index is now lower than readState.Index' (duration: 16.876127ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:07:55.343579Z","caller":"traceutil/trace.go:171","msg":"trace[559488292] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"191.554301ms","start":"2026-04-22T06:07:55.151991Z","end":"2026-04-22T06:07:55.343545Z","steps":["trace[559488292] 'process raft request' (duration: 191.396358ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:07:55.343654Z","caller":"traceutil/trace.go:171","msg":"trace[298155088] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"197.623977ms","start":"2026-04-22T06:07:55.145984Z","end":"2026-04-22T06:07:55.343608Z","steps":["trace[298155088] 'process raft request' (duration: 197.30813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:07:55.343611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.725444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:07:55.343864Z","caller":"traceutil/trace.go:171","msg":"trace[1274918331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:444; }","duration":"166.012929ms","start":"2026-04-22T06:07:55.177825Z","end":"2026-04-22T06:07:55.343838Z","steps":["trace[1274918331] 'agreement among raft nodes before linearized reading' (duration: 165.734323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:06.204803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.177026ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-22T06:08:06.204921Z","caller":"traceutil/trace.go:171","msg":"trace[906641969] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:519; }","duration":"351.793542ms","start":"2026-04-22T06:08:05.85311Z","end":"2026-04-22T06:08:06.204904Z","steps":["trace[906641969] 'read index received' (duration: 69.301839ms)","trace[906641969] 'applied index is now lower than readState.Index' (duration: 282.490323ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:08:06.205036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.913584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:08:06.205071Z","caller":"traceutil/trace.go:171","msg":"trace[393136322] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:503; }","duration":"351.998166ms","start":"2026-04-22T06:08:05.853063Z","end":"2026-04-22T06:08:06.205061Z","steps":["trace[393136322] 'agreement among raft nodes before linearized reading' (duration: 351.927535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:06.205116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:05.853042Z","time spent":"352.055658ms","remote":"127.0.0.1:50056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-04-22T06:08:07.136168Z","caller":"traceutil/trace.go:171","msg":"trace[1009858000] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"126.442895ms","start":"2026-04-22T06:08:07.009682Z","end":"2026-04-22T06:08:07.136125Z","steps":["trace[1009858000] 'process raft request' (duration: 46.460728ms)","trace[1009858000] 'compare' (duration: 79.782833ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:08:07.14564Z","caller":"traceutil/trace.go:171","msg":"trace[642141113] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"130.673391ms","start":"2026-04-22T06:08:07.014948Z","end":"2026-04-22T06:08:07.145622Z","steps":["trace[642141113] 'process raft request' (duration: 130.450056ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:07.146099Z","caller":"traceutil/trace.go:171","msg":"trace[1184877273] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"113.757033ms","start":"2026-04-22T06:08:07.03229Z","end":"2026-04-22T06:08:07.146047Z","steps":["trace[1184877273] 'process raft request' (duration: 113.292352ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:07.14619Z","caller":"traceutil/trace.go:171","msg":"trace[1796094009] transaction","detail":"{read_only:false; number_of_response:1; response_revision:559; }","duration":"105.643891ms","start":"2026-04-22T06:08:07.040511Z","end":"2026-04-22T06:08:07.146155Z","steps":["trace[1796094009] 'process raft request' (duration: 105.378115ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:12.928135Z","caller":"traceutil/trace.go:171","msg":"trace[237254787] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"274.053175ms","start":"2026-04-22T06:08:12.654049Z","end":"2026-04-22T06:08:12.928102Z","steps":["trace[237254787] 'process raft request' (duration: 272.191663ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:13.08592Z","caller":"traceutil/trace.go:171","msg":"trace[83055347] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"100.653287ms","start":"2026-04-22T06:08:12.985242Z","end":"2026-04-22T06:08:13.085895Z","steps":["trace[83055347] 'process raft request' (duration: 100.424472ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:13.085908Z","caller":"traceutil/trace.go:171","msg":"trace[79770764] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"401.8707ms","start":"2026-04-22T06:08:12.68397Z","end":"2026-04-22T06:08:13.08584Z","steps":["trace[79770764] 'process raft request' (duration: 352.239427ms)","trace[79770764] 'compare' (duration: 49.236454ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:08:13.086601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:12.683941Z","time spent":"402.140086ms","remote":"127.0.0.1:50202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:08:13.868452Z","caller":"traceutil/trace.go:171","msg":"trace[1221165862] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"375.498801ms","start":"2026-04-22T06:08:13.492924Z","end":"2026-04-22T06:08:13.868423Z","steps":["trace[1221165862] 'read index received' (duration: 374.389685ms)","trace[1221165862] 'applied index is now lower than readState.Index' (duration: 1.107876ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:08:13.868488Z","caller":"traceutil/trace.go:171","msg":"trace[97699172] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"392.518572ms","start":"2026-04-22T06:08:13.475936Z","end":"2026-04-22T06:08:13.868454Z","steps":["trace[97699172] 'process raft request' (duration: 391.807356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:13.868658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:13.475904Z","time spent":"392.665695ms","remote":"127.0.0.1:50056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:08:13.868761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.782986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-22T06:08:13.868816Z","caller":"traceutil/trace.go:171","msg":"trace[1287227435] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:614; }","duration":"375.855408ms","start":"2026-04-22T06:08:13.492942Z","end":"2026-04-22T06:08:13.868797Z","steps":["trace[1287227435] 'agreement among raft nodes before linearized reading' (duration: 375.650974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:13.868795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.848498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-22T06:08:13.868877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:13.492904Z","time spent":"375.961711ms","remote":"127.0.0.1:50202","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":"info","ts":"2026-04-22T06:08:13.868898Z","caller":"traceutil/trace.go:171","msg":"trace[2019629692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:614; }","duration":"376.002072ms","start":"2026-04-22T06:08:13.49287Z","end":"2026-04-22T06:08:13.868872Z","steps":["trace[2019629692] 'agreement among raft nodes before linearized reading' (duration: 375.679615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:13.868955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:13.492847Z","time spent":"376.094863ms","remote":"127.0.0.1:50202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T06:08:13.868977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.853107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-22T06:08:13.869088Z","caller":"traceutil/trace.go:171","msg":"trace[974562707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"375.998961ms","start":"2026-04-22T06:08:13.49306Z","end":"2026-04-22T06:08:13.869059Z","steps":["trace[974562707] 'agreement among raft nodes before linearized reading' (duration: 375.683414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:13.86915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:13.493018Z","time spent":"376.119973ms","remote":"127.0.0.1:50202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T06:08:13.869485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.907059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-22T06:08:13.869555Z","caller":"traceutil/trace.go:171","msg":"trace[1931088654] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:614; }","duration":"376.01156ms","start":"2026-04-22T06:08:13.493526Z","end":"2026-04-22T06:08:13.869538Z","steps":["trace[1931088654] 'agreement among raft nodes before linearized reading' (duration: 375.213903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:08:13.869594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:13.493454Z","time spent":"376.131643ms","remote":"127.0.0.1:50202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T06:08:15.377882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.102385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:08:15.378075Z","caller":"traceutil/trace.go:171","msg":"trace[1272436917] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"246.132878ms","start":"2026-04-22T06:08:15.131925Z","end":"2026-04-22T06:08:15.378058Z","steps":["trace[1272436917] 'process raft request' (duration: 246.073997ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:15.378153Z","caller":"traceutil/trace.go:171","msg":"trace[490893793] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"309.0782ms","start":"2026-04-22T06:08:15.069043Z","end":"2026-04-22T06:08:15.378121Z","steps":["trace[490893793] 'process raft request' (duration: 100.599666ms)","trace[490893793] 'compare' (duration: 207.964482ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:08:15.378306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:08:15.069033Z","time spent":"309.195742ms","remote":"127.0.0.1:50020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4059,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:08:15.394222Z","caller":"traceutil/trace.go:171","msg":"trace[672016547] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"157.629744ms","start":"2026-04-22T06:08:15.236576Z","end":"2026-04-22T06:08:15.394206Z","steps":["trace[672016547] 'process raft request' (duration: 157.559212ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:16.135103Z","caller":"traceutil/trace.go:171","msg":"trace[1821795821] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"102.189561ms","start":"2026-04-22T06:08:16.03287Z","end":"2026-04-22T06:08:16.135059Z","steps":["trace[1821795821] 'process raft request' (duration: 86.288395ms)","trace[1821795821] 'compare' (duration: 15.706252ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:08:16.309423Z","caller":"traceutil/trace.go:171","msg":"trace[1211202924] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:687; }","duration":"132.855269ms","start":"2026-04-22T06:08:16.176547Z","end":"2026-04-22T06:08:16.309402Z","steps":["trace[1211202924] 'read index received' (duration: 46.610125ms)","trace[1211202924] 'applied index is now lower than readState.Index' (duration: 86.244244ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:08:16.309554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.997053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:08:16.309585Z","caller":"traceutil/trace.go:171","msg":"trace[2105571808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:665; }","duration":"133.087575ms","start":"2026-04-22T06:08:16.176489Z","end":"2026-04-22T06:08:16.309576Z","steps":["trace[2105571808] 'agreement among raft nodes before linearized reading' (duration: 133.001623ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:16.309668Z","caller":"traceutil/trace.go:171","msg":"trace[1476572657] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"162.021383ms","start":"2026-04-22T06:08:16.14762Z","end":"2026-04-22T06:08:16.309641Z","steps":["trace[1476572657] 'process raft request' (duration: 161.648634ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:16.398154Z","caller":"traceutil/trace.go:171","msg":"trace[1476949323] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"169.0023ms","start":"2026-04-22T06:08:16.229113Z","end":"2026-04-22T06:08:16.398115Z","steps":["trace[1476949323] 'process raft request' (duration: 146.465953ms)","trace[1476949323] 'compare' (duration: 22.337741ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:08:16.398236Z","caller":"traceutil/trace.go:171","msg":"trace[649923632] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"160.262484ms","start":"2026-04-22T06:08:16.237941Z","end":"2026-04-22T06:08:16.398204Z","steps":["trace[649923632] 'process raft request' (duration: 160.11094ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:16.537916Z","caller":"traceutil/trace.go:171","msg":"trace[438678383] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:694; }","duration":"102.567749ms","start":"2026-04-22T06:08:16.435324Z","end":"2026-04-22T06:08:16.537891Z","steps":["trace[438678383] 'read index received' (duration: 78.097331ms)","trace[438678383] 'applied index is now lower than readState.Index' (duration: 24.469138ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:08:16.538006Z","caller":"traceutil/trace.go:171","msg":"trace[151125048] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"121.902852ms","start":"2026-04-22T06:08:16.416065Z","end":"2026-04-22T06:08:16.537968Z","steps":["trace[151125048] 'process raft request' (duration: 97.254351ms)","trace[151125048] 'compare' (duration: 24.464237ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:08:16.538042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.691661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:08:16.538091Z","caller":"traceutil/trace.go:171","msg":"trace[1545033379] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:671; }","duration":"102.792124ms","start":"2026-04-22T06:08:16.435286Z","end":"2026-04-22T06:08:16.538078Z","steps":["trace[1545033379] 'agreement among raft nodes before linearized reading' (duration: 102.699192ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:16.552519Z","caller":"traceutil/trace.go:171","msg":"trace[1846077688] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"110.791495ms","start":"2026-04-22T06:08:16.441701Z","end":"2026-04-22T06:08:16.552493Z","steps":["trace[1846077688] 'process raft request' (duration: 110.627181ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:31.374546Z","caller":"traceutil/trace.go:171","msg":"trace[870524421] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"158.087675ms","start":"2026-04-22T06:08:31.216427Z","end":"2026-04-22T06:08:31.374514Z","steps":["trace[870524421] 'process raft request' (duration: 94.786785ms)","trace[870524421] 'compare' (duration: 63.039965ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:08:31.374625Z","caller":"traceutil/trace.go:171","msg":"trace[395659795] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"157.707657ms","start":"2026-04-22T06:08:31.216894Z","end":"2026-04-22T06:08:31.374601Z","steps":["trace[395659795] 'process raft request' (duration: 157.535603ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:08:55.477081Z","caller":"traceutil/trace.go:171","msg":"trace[202669102] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"224.760929ms","start":"2026-04-22T06:08:55.252294Z","end":"2026-04-22T06:08:55.477055Z","steps":["trace[202669102] 'process raft request' (duration: 224.612896ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:09:38.740259Z","caller":"traceutil/trace.go:171","msg":"trace[1462987269] linearizableReadLoop","detail":"{readStateIndex:1856; appliedIndex:1855; }","duration":"406.14701ms","start":"2026-04-22T06:09:38.334086Z","end":"2026-04-22T06:09:38.740233Z","steps":["trace[1462987269] 'read index received' (duration: 406.042517ms)","trace[1462987269] 'applied index is now lower than readState.Index' (duration: 103.563µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:09:38.740368Z","caller":"traceutil/trace.go:171","msg":"trace[1589023251] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"419.328946ms","start":"2026-04-22T06:09:38.321006Z","end":"2026-04-22T06:09:38.740335Z","steps":["trace[1589023251] 'process raft request' (duration: 419.092371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:09:38.74053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.505261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-22T06:09:38.740523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:09:38.320979Z","time spent":"419.459558ms","remote":"127.0.0.1:50066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:09:38.740581Z","caller":"traceutil/trace.go:171","msg":"trace[901862108] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1802; }","duration":"403.584372ms","start":"2026-04-22T06:09:38.336985Z","end":"2026-04-22T06:09:38.74057Z","steps":["trace[901862108] 'agreement among raft nodes before linearized reading' (duration: 403.405188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:09:38.740567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.467396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-22T06:09:38.740618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:09:38.336977Z","time spent":"403.633083ms","remote":"127.0.0.1:50202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-22T06:09:38.740652Z","caller":"traceutil/trace.go:171","msg":"trace[893519481] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1802; }","duration":"406.602589ms","start":"2026-04-22T06:09:38.334027Z","end":"2026-04-22T06:09:38.740629Z","steps":["trace[893519481] 'agreement among raft nodes before linearized reading' (duration: 406.354564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:09:38.740702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:09:38.333996Z","time spent":"406.698042ms","remote":"127.0.0.1:50202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T06:09:38.74075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.580371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-22T06:09:38.740799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.738835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1fb128c9-da78-4946-b63e-d87d4c9ebf75\" ","response":"range_response_count:1 size:3625"} {"level":"info","ts":"2026-04-22T06:09:38.740938Z","caller":"traceutil/trace.go:171","msg":"trace[1152913016] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1fb128c9-da78-4946-b63e-d87d4c9ebf75; range_end:; response_count:1; response_revision:1802; }","duration":"300.952179ms","start":"2026-04-22T06:09:38.439956Z","end":"2026-04-22T06:09:38.740908Z","steps":["trace[1152913016] 'agreement among raft nodes before linearized reading' (duration: 300.585941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:09:38.741025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:09:38.43994Z","time spent":"301.066413ms","remote":"127.0.0.1:50120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1fb128c9-da78-4946-b63e-d87d4c9ebf75\" "} {"level":"info","ts":"2026-04-22T06:09:38.740855Z","caller":"traceutil/trace.go:171","msg":"trace[1853494728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1802; }","duration":"394.711513ms","start":"2026-04-22T06:09:38.346107Z","end":"2026-04-22T06:09:38.740819Z","steps":["trace[1853494728] 'agreement among raft nodes before linearized reading' (duration: 394.462208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:09:38.740901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.147663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-22T06:09:38.741215Z","caller":"traceutil/trace.go:171","msg":"trace[1402565296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1802; }","duration":"395.518501ms","start":"2026-04-22T06:09:38.345631Z","end":"2026-04-22T06:09:38.74115Z","steps":["trace[1402565296] 'agreement among raft nodes before linearized reading' (duration: 395.04218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:09:38.741298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:09:38.345619Z","time spent":"395.624524ms","remote":"127.0.0.1:50202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T06:09:38.741119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:09:38.346087Z","time spent":"395.01076ms","remote":"127.0.0.1:50202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T06:09:38.740762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.007207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-04-22T06:09:38.741555Z","caller":"traceutil/trace.go:171","msg":"trace[384442891] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1802; }","duration":"279.816935ms","start":"2026-04-22T06:09:38.461695Z","end":"2026-04-22T06:09:38.741512Z","steps":["trace[384442891] 'agreement among raft nodes before linearized reading' (duration: 278.942165ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:09:44.440467Z","caller":"traceutil/trace.go:171","msg":"trace[1937114255] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"183.754792ms","start":"2026-04-22T06:09:44.256679Z","end":"2026-04-22T06:09:44.440434Z","steps":["trace[1937114255] 'process raft request' (duration: 120.801409ms)","trace[1937114255] 'compare' (duration: 62.80958ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:09:47.027419Z","caller":"traceutil/trace.go:171","msg":"trace[242788917] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"185.92789ms","start":"2026-04-22T06:09:46.841461Z","end":"2026-04-22T06:09:47.027389Z","steps":["trace[242788917] 'process raft request' (duration: 184.890697ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:09:47.027919Z","caller":"traceutil/trace.go:171","msg":"trace[287085536] linearizableReadLoop","detail":"{readStateIndex:1958; appliedIndex:1955; }","duration":"159.429705ms","start":"2026-04-22T06:09:46.868466Z","end":"2026-04-22T06:09:47.027896Z","steps":["trace[287085536] 'read index received' (duration: 157.903181ms)","trace[287085536] 'applied index is now lower than readState.Index' (duration: 1.525654ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:09:47.028007Z","caller":"traceutil/trace.go:171","msg":"trace[1048430960] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"186.192205ms","start":"2026-04-22T06:09:46.841794Z","end":"2026-04-22T06:09:47.027986Z","steps":["trace[1048430960] 'process raft request' (duration: 185.93613ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:09:47.028042Z","caller":"traceutil/trace.go:171","msg":"trace[1001637907] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"185.486911ms","start":"2026-04-22T06:09:46.842541Z","end":"2026-04-22T06:09:47.028027Z","steps":["trace[1001637907] 'process raft request' (duration: 185.318016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:09:47.028363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.885796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-04-22T06:09:47.028425Z","caller":"traceutil/trace.go:171","msg":"trace[497195512] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1900; }","duration":"160.005169ms","start":"2026-04-22T06:09:46.868402Z","end":"2026-04-22T06:09:47.028407Z","steps":["trace[497195512] 'agreement among raft nodes before linearized reading' (duration: 159.822415ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:21.497699Z","caller":"traceutil/trace.go:171","msg":"trace[195707142] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"217.821676ms","start":"2026-04-22T06:10:21.279853Z","end":"2026-04-22T06:10:21.497674Z","steps":["trace[195707142] 'process raft request' (duration: 217.684233ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:21.813343Z","caller":"traceutil/trace.go:171","msg":"trace[263837285] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"533.18496ms","start":"2026-04-22T06:10:21.280132Z","end":"2026-04-22T06:10:21.813317Z","steps":["trace[263837285] 'process raft request' (duration: 533.004896ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:10:21.813475Z","caller":"traceutil/trace.go:171","msg":"trace[1850549180] linearizableReadLoop","detail":"{readStateIndex:2218; appliedIndex:2215; }","duration":"400.909912ms","start":"2026-04-22T06:10:21.412524Z","end":"2026-04-22T06:10:21.813434Z","steps":["trace[1850549180] 'read index received' (duration: 85.070368ms)","trace[1850549180] 'applied index is now lower than readState.Index' (duration: 315.837774ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:10:21.813508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:21.280114Z","time spent":"533.320172ms","remote":"127.0.0.1:50202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:10:21.813572Z","caller":"traceutil/trace.go:171","msg":"trace[1916534778] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"533.374894ms","start":"2026-04-22T06:10:21.280176Z","end":"2026-04-22T06:10:21.813551Z","steps":["trace[1916534778] 'process raft request' (duration: 533.096448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:21.813719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.175678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-04-22T06:10:21.813837Z","caller":"traceutil/trace.go:171","msg":"trace[520648562] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2146; }","duration":"401.309941ms","start":"2026-04-22T06:10:21.412478Z","end":"2026-04-22T06:10:21.813788Z","steps":["trace[520648562] 'agreement among raft nodes before linearized reading' (duration: 401.074156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:10:21.813844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:21.280159Z","time spent":"533.610099ms","remote":"127.0.0.1:50202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:10:21.813892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:10:21.412462Z","time spent":"401.414963ms","remote":"127.0.0.1:50066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":667,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-22T06:10:21.929302Z","caller":"traceutil/trace.go:171","msg":"trace[2384659] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"108.35007ms","start":"2026-04-22T06:10:21.820914Z","end":"2026-04-22T06:10:21.929264Z","steps":["trace[2384659] 'process raft request' (duration: 91.42375ms)","trace[2384659] 'compare' (duration: 16.737146ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:11:00.536565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.519196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.4\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-04-22T06:11:00.536753Z","caller":"traceutil/trace.go:171","msg":"trace[527708566] range","detail":"{range_begin:/registry/masterleases/199.204.45.4; range_end:; response_count:1; response_revision:2457; }","duration":"179.761692ms","start":"2026-04-22T06:11:00.356967Z","end":"2026-04-22T06:11:00.536729Z","steps":["trace[527708566] 'range keys from in-memory index tree' (duration: 179.342003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:00.537029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.516952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-04-22T06:11:00.537124Z","caller":"traceutil/trace.go:171","msg":"trace[1335278841] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2457; }","duration":"218.664915ms","start":"2026-04-22T06:11:00.318436Z","end":"2026-04-22T06:11:00.537101Z","steps":["trace[1335278841] 'range keys from in-memory index tree' (duration: 218.312577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:00.537209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.279722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-22T06:11:00.537304Z","caller":"traceutil/trace.go:171","msg":"trace[1612401268] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2457; }","duration":"389.430096ms","start":"2026-04-22T06:11:00.147861Z","end":"2026-04-22T06:11:00.537291Z","steps":["trace[1612401268] 'range keys from in-memory index tree' (duration: 388.829002ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:00.53739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:00.147842Z","time spent":"389.540388ms","remote":"127.0.0.1:50202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-22T06:11:34.421326Z","caller":"traceutil/trace.go:171","msg":"trace[727980625] transaction","detail":"{read_only:false; response_revision:2695; number_of_response:1; }","duration":"234.419224ms","start":"2026-04-22T06:11:34.186878Z","end":"2026-04-22T06:11:34.421298Z","steps":["trace[727980625] 'process raft request' (duration: 234.289981ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:34.421356Z","caller":"traceutil/trace.go:171","msg":"trace[1040627923] transaction","detail":"{read_only:false; response_revision:2694; number_of_response:1; }","duration":"234.897035ms","start":"2026-04-22T06:11:34.1864Z","end":"2026-04-22T06:11:34.421297Z","steps":["trace[1040627923] 'process raft request' (duration: 149.747423ms)","trace[1040627923] 'compare' (duration: 84.62461ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:11:34.421964Z","caller":"traceutil/trace.go:171","msg":"trace[809618059] transaction","detail":"{read_only:false; response_revision:2696; number_of_response:1; }","duration":"234.614579ms","start":"2026-04-22T06:11:34.187282Z","end":"2026-04-22T06:11:34.421897Z","steps":["trace[809618059] 'process raft request' (duration: 233.977754ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:35.009602Z","caller":"traceutil/trace.go:171","msg":"trace[1643515817] linearizableReadLoop","detail":"{readStateIndex:2799; appliedIndex:2798; }","duration":"126.851658ms","start":"2026-04-22T06:11:34.882722Z","end":"2026-04-22T06:11:35.009574Z","steps":["trace[1643515817] 'read index received' (duration: 126.49241ms)","trace[1643515817] 'applied index is now lower than readState.Index' (duration: 358.018µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:11:35.009829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.092025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-04-22T06:11:35.009889Z","caller":"traceutil/trace.go:171","msg":"trace[1598953302] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2697; }","duration":"127.223898ms","start":"2026-04-22T06:11:34.882647Z","end":"2026-04-22T06:11:35.009871Z","steps":["trace[1598953302] 'agreement among raft nodes before linearized reading' (duration: 127.032572ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:35.009852Z","caller":"traceutil/trace.go:171","msg":"trace[1526016309] transaction","detail":"{read_only:false; response_revision:2697; number_of_response:1; }","duration":"301.534422ms","start":"2026-04-22T06:11:34.708215Z","end":"2026-04-22T06:11:35.009749Z","steps":["trace[1526016309] 'process raft request' (duration: 301.170143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:11:35.010148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:11:34.708198Z","time spent":"301.830659ms","remote":"127.0.0.1:50202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:11:35.143023Z","caller":"traceutil/trace.go:171","msg":"trace[486545394] linearizableReadLoop","detail":"{readStateIndex:2800; appliedIndex:2799; }","duration":"124.178859ms","start":"2026-04-22T06:11:35.018817Z","end":"2026-04-22T06:11:35.142996Z","steps":["trace[486545394] 'read index received' (duration: 120.688331ms)","trace[486545394] 'applied index is now lower than readState.Index' (duration: 3.489588ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:11:35.143357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.416664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T06:11:35.143458Z","caller":"traceutil/trace.go:171","msg":"trace[1192202116] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:2698; }","duration":"124.648279ms","start":"2026-04-22T06:11:35.018785Z","end":"2026-04-22T06:11:35.143433Z","steps":["trace[1192202116] 'agreement among raft nodes before linearized reading' (duration: 124.371763ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:11:35.143685Z","caller":"traceutil/trace.go:171","msg":"trace[1618890447] transaction","detail":"{read_only:false; response_revision:2698; number_of_response:1; }","duration":"125.686983ms","start":"2026-04-22T06:11:35.017947Z","end":"2026-04-22T06:11:35.143634Z","steps":["trace[1618890447] 'process raft request' (duration: 121.648753ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:43.727847Z","caller":"traceutil/trace.go:171","msg":"trace[1357731300] transaction","detail":"{read_only:false; response_revision:4229; number_of_response:1; }","duration":"100.093995ms","start":"2026-04-22T06:13:43.627709Z","end":"2026-04-22T06:13:43.727803Z","steps":["trace[1357731300] 'process raft request' (duration: 96.688149ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:49.940869Z","caller":"traceutil/trace.go:171","msg":"trace[1717648095] transaction","detail":"{read_only:false; response_revision:4373; number_of_response:1; }","duration":"101.499977ms","start":"2026-04-22T06:13:49.839352Z","end":"2026-04-22T06:13:49.940851Z","steps":["trace[1717648095] 'process raft request' (duration: 101.364924ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:49.942014Z","caller":"traceutil/trace.go:171","msg":"trace[1344600560] transaction","detail":"{read_only:false; response_revision:4374; number_of_response:1; }","duration":"101.784203ms","start":"2026-04-22T06:13:49.84019Z","end":"2026-04-22T06:13:49.941974Z","steps":["trace[1344600560] 'process raft request' (duration: 101.536157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:51.446696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.592512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:13:51.446802Z","caller":"traceutil/trace.go:171","msg":"trace[548856427] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:4460; }","duration":"217.730686ms","start":"2026-04-22T06:13:51.229049Z","end":"2026-04-22T06:13:51.446779Z","steps":["trace[548856427] 'count revisions from in-memory index tree' (duration: 217.50722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:51.44682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.307382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f06e3f28-53be-4c54-8f13-826c17bd8d04\" ","response":"range_response_count:1 size:3599"} {"level":"info","ts":"2026-04-22T06:13:51.446892Z","caller":"traceutil/trace.go:171","msg":"trace[1336132791] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f06e3f28-53be-4c54-8f13-826c17bd8d04; range_end:; response_count:1; response_revision:4460; }","duration":"196.458776ms","start":"2026-04-22T06:13:51.250418Z","end":"2026-04-22T06:13:51.446877Z","steps":["trace[1336132791] 'range keys from in-memory index tree' (duration: 196.084158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:13:51.44684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.245666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:13:51.447025Z","caller":"traceutil/trace.go:171","msg":"trace[375854851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4460; }","duration":"269.48689ms","start":"2026-04-22T06:13:51.177512Z","end":"2026-04-22T06:13:51.446999Z","steps":["trace[375854851] 'range keys from in-memory index tree' (duration: 269.172464ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:53.162102Z","caller":"traceutil/trace.go:171","msg":"trace[436940972] transaction","detail":"{read_only:false; response_revision:4490; number_of_response:1; }","duration":"161.243761ms","start":"2026-04-22T06:13:53.000815Z","end":"2026-04-22T06:13:53.162059Z","steps":["trace[436940972] 'process raft request' (duration: 153.189061ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:55.456846Z","caller":"traceutil/trace.go:171","msg":"trace[1368614173] transaction","detail":"{read_only:false; response_revision:4548; number_of_response:1; }","duration":"172.397814ms","start":"2026-04-22T06:13:55.28443Z","end":"2026-04-22T06:13:55.456828Z","steps":["trace[1368614173] 'process raft request' (duration: 172.184659ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:13:55.456841Z","caller":"traceutil/trace.go:171","msg":"trace[1061376801] linearizableReadLoop","detail":"{readStateIndex:4737; appliedIndex:4736; }","duration":"115.398811ms","start":"2026-04-22T06:13:55.341411Z","end":"2026-04-22T06:13:55.45681Z","steps":["trace[1061376801] 'read index received' (duration: 115.260538ms)","trace[1061376801] 'applied index is now lower than readState.Index' (duration: 136.813µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:13:55.457037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.612005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-04-22T06:13:55.457086Z","caller":"traceutil/trace.go:171","msg":"trace[307548617] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:4548; }","duration":"115.717738ms","start":"2026-04-22T06:13:55.341359Z","end":"2026-04-22T06:13:55.457077Z","steps":["trace[307548617] 'agreement among raft nodes before linearized reading' (duration: 115.530674ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:06.974175Z","caller":"traceutil/trace.go:171","msg":"trace[1735680376] linearizableReadLoop","detail":"{readStateIndex:4937; appliedIndex:4936; }","duration":"148.899114ms","start":"2026-04-22T06:14:06.825257Z","end":"2026-04-22T06:14:06.974156Z","steps":["trace[1735680376] 'read index received' (duration: 148.860893ms)","trace[1735680376] 'applied index is now lower than readState.Index' (duration: 37.561µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:14:06.97425Z","caller":"traceutil/trace.go:171","msg":"trace[518127069] transaction","detail":"{read_only:false; response_revision:4739; number_of_response:1; }","duration":"299.083687ms","start":"2026-04-22T06:14:06.675134Z","end":"2026-04-22T06:14:06.974218Z","steps":["trace[518127069] 'process raft request' (duration: 298.887143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:06.974359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.094528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-c47d949f5-fjcwl\" ","response":"range_response_count:1 size:7811"} {"level":"info","ts":"2026-04-22T06:14:06.974394Z","caller":"traceutil/trace.go:171","msg":"trace[1337652006] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-c47d949f5-fjcwl; range_end:; response_count:1; response_revision:4739; }","duration":"149.17963ms","start":"2026-04-22T06:14:06.825205Z","end":"2026-04-22T06:14:06.974384Z","steps":["trace[1337652006] 'agreement among raft nodes before linearized reading' (duration: 149.048547ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:07.18335Z","caller":"traceutil/trace.go:171","msg":"trace[1546389557] linearizableReadLoop","detail":"{readStateIndex:4938; appliedIndex:4937; }","duration":"209.08902ms","start":"2026-04-22T06:14:06.974237Z","end":"2026-04-22T06:14:07.183326Z","steps":["trace[1546389557] 'read index received' (duration: 191.531484ms)","trace[1546389557] 'applied index is now lower than readState.Index' (duration: 17.556316ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:14:07.183577Z","caller":"traceutil/trace.go:171","msg":"trace[1490224499] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"321.340179ms","start":"2026-04-22T06:14:06.862175Z","end":"2026-04-22T06:14:07.183515Z","steps":["trace[1490224499] 'process raft request' (duration: 303.612079ms)","trace[1490224499] 'compare' (duration: 17.436284ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:14:07.183832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:14:06.862158Z","time spent":"321.526553ms","remote":"127.0.0.1:50202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:14:07.183869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.053977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-22T06:14:07.183909Z","caller":"traceutil/trace.go:171","msg":"trace[965451665] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4740; }","duration":"331.124659ms","start":"2026-04-22T06:14:06.852772Z","end":"2026-04-22T06:14:07.183896Z","steps":["trace[965451665] 'agreement among raft nodes before linearized reading' (duration: 331.006936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:07.18394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:14:06.85276Z","time spent":"331.171429ms","remote":"127.0.0.1:50066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-22T06:14:07.184082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.94794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-04-22T06:14:07.184586Z","caller":"traceutil/trace.go:171","msg":"trace[1644290230] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4740; }","duration":"329.498173ms","start":"2026-04-22T06:14:06.854993Z","end":"2026-04-22T06:14:07.184491Z","steps":["trace[1644290230] 'agreement among raft nodes before linearized reading' (duration: 328.671374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:07.184939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:14:06.854973Z","time spent":"329.792539ms","remote":"127.0.0.1:50066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-04-22T06:14:07.187074Z","caller":"traceutil/trace.go:171","msg":"trace[484765686] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"175.746269ms","start":"2026-04-22T06:14:07.011308Z","end":"2026-04-22T06:14:07.187054Z","steps":["trace[484765686] 'process raft request' (duration: 175.229467ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:07.187125Z","caller":"traceutil/trace.go:171","msg":"trace[1327799180] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"203.476334ms","start":"2026-04-22T06:14:06.983581Z","end":"2026-04-22T06:14:07.187058Z","steps":["trace[1327799180] 'process raft request' (duration: 202.823859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:09.16776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.8089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-22T06:14:09.16785Z","caller":"traceutil/trace.go:171","msg":"trace[1546034795] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4767; }","duration":"187.953033ms","start":"2026-04-22T06:14:08.979878Z","end":"2026-04-22T06:14:09.167831Z","steps":["trace[1546034795] 'range keys from in-memory index tree' (duration: 187.578695ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:14.162052Z","caller":"traceutil/trace.go:171","msg":"trace[924108053] transaction","detail":"{read_only:false; response_revision:4901; number_of_response:1; }","duration":"275.25214ms","start":"2026-04-22T06:14:13.886767Z","end":"2026-04-22T06:14:14.162019Z","steps":["trace[924108053] 'process raft request' (duration: 275.080136ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:14.308312Z","caller":"traceutil/trace.go:171","msg":"trace[842879108] transaction","detail":"{read_only:false; response_revision:4903; number_of_response:1; }","duration":"139.515483ms","start":"2026-04-22T06:14:14.16877Z","end":"2026-04-22T06:14:14.308285Z","steps":["trace[842879108] 'process raft request' (duration: 47.582642ms)","trace[842879108] 'compare' (duration: 91.711696ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:14:14.308317Z","caller":"traceutil/trace.go:171","msg":"trace[152341318] linearizableReadLoop","detail":"{readStateIndex:5107; appliedIndex:5104; }","duration":"127.455622ms","start":"2026-04-22T06:14:14.180835Z","end":"2026-04-22T06:14:14.308291Z","steps":["trace[152341318] 'read index received' (duration: 8.537263ms)","trace[152341318] 'applied index is now lower than readState.Index' (duration: 118.917319ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:14:14.308463Z","caller":"traceutil/trace.go:171","msg":"trace[730207791] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"135.637465ms","start":"2026-04-22T06:14:14.172814Z","end":"2026-04-22T06:14:14.308451Z","steps":["trace[730207791] 'process raft request' (duration: 135.41056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:14.308828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.973713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-22T06:14:14.308935Z","caller":"traceutil/trace.go:171","msg":"trace[2102800077] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4904; }","duration":"128.129067ms","start":"2026-04-22T06:14:14.180784Z","end":"2026-04-22T06:14:14.308913Z","steps":["trace[2102800077] 'agreement among raft nodes before linearized reading' (duration: 127.886451ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:25.234178Z","caller":"traceutil/trace.go:171","msg":"trace[864763542] linearizableReadLoop","detail":"{readStateIndex:5257; appliedIndex:5254; }","duration":"131.711427ms","start":"2026-04-22T06:14:25.102441Z","end":"2026-04-22T06:14:25.234152Z","steps":["trace[864763542] 'read index received' (duration: 92.676878ms)","trace[864763542] 'applied index is now lower than readState.Index' (duration: 39.033429ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:14:25.23429Z","caller":"traceutil/trace.go:171","msg":"trace[1778253408] transaction","detail":"{read_only:false; response_revision:5049; number_of_response:1; }","duration":"176.140048ms","start":"2026-04-22T06:14:25.058111Z","end":"2026-04-22T06:14:25.234251Z","steps":["trace[1778253408] 'process raft request' (duration: 175.945093ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:25.234337Z","caller":"traceutil/trace.go:171","msg":"trace[112688437] transaction","detail":"{read_only:false; response_revision:5050; number_of_response:1; }","duration":"175.046693ms","start":"2026-04-22T06:14:25.05926Z","end":"2026-04-22T06:14:25.234307Z","steps":["trace[112688437] 'process raft request' (duration: 174.850968ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:25.234363Z","caller":"traceutil/trace.go:171","msg":"trace[312099660] transaction","detail":"{read_only:false; response_revision:5048; number_of_response:1; }","duration":"176.422604ms","start":"2026-04-22T06:14:25.057894Z","end":"2026-04-22T06:14:25.234317Z","steps":["trace[312099660] 'process raft request' (duration: 137.293092ms)","trace[312099660] 'compare' (duration: 38.750584ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:14:25.234561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.105336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-22T06:14:25.234647Z","caller":"traceutil/trace.go:171","msg":"trace[1111690962] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5050; }","duration":"132.254449ms","start":"2026-04-22T06:14:25.102374Z","end":"2026-04-22T06:14:25.234628Z","steps":["trace[1111690962] 'agreement among raft nodes before linearized reading' (duration: 131.953812ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:25.592793Z","caller":"traceutil/trace.go:171","msg":"trace[145655425] linearizableReadLoop","detail":"{readStateIndex:5261; appliedIndex:5260; }","duration":"147.273017ms","start":"2026-04-22T06:14:25.445471Z","end":"2026-04-22T06:14:25.592744Z","steps":["trace[145655425] 'read index received' (duration: 70.292623ms)","trace[145655425] 'applied index is now lower than readState.Index' (duration: 76.978804ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:14:25.592828Z","caller":"traceutil/trace.go:171","msg":"trace[1655725061] transaction","detail":"{read_only:false; response_revision:5054; number_of_response:1; }","duration":"177.651512ms","start":"2026-04-22T06:14:25.415153Z","end":"2026-04-22T06:14:25.592805Z","steps":["trace[1655725061] 'process raft request' (duration: 100.594066ms)","trace[1655725061] 'compare' (duration: 76.879532ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:14:25.593092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.593934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-04-22T06:14:25.593171Z","caller":"traceutil/trace.go:171","msg":"trace[2062184784] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5054; }","duration":"147.682426ms","start":"2026-04-22T06:14:25.445465Z","end":"2026-04-22T06:14:25.593147Z","steps":["trace[2062184784] 'agreement among raft nodes before linearized reading' (duration: 147.454771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:25.593188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.995976ms","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-04-22T06:14:25.593317Z","caller":"traceutil/trace.go:171","msg":"trace[325097545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5054; }","duration":"145.17336ms","start":"2026-04-22T06:14:25.448116Z","end":"2026-04-22T06:14:25.59329Z","steps":["trace[325097545] 'agreement among raft nodes before linearized reading' (duration: 144.881194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:14:25.593724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.412251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-22T06:14:25.593796Z","caller":"traceutil/trace.go:171","msg":"trace[15955841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5054; }","duration":"143.844841ms","start":"2026-04-22T06:14:25.449936Z","end":"2026-04-22T06:14:25.593781Z","steps":["trace[15955841] 'agreement among raft nodes before linearized reading' (duration: 143.352559ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:25.834802Z","caller":"traceutil/trace.go:171","msg":"trace[1809975669] transaction","detail":"{read_only:false; response_revision:5055; number_of_response:1; }","duration":"236.563389ms","start":"2026-04-22T06:14:25.598216Z","end":"2026-04-22T06:14:25.834779Z","steps":["trace[1809975669] 'process raft request' (duration: 154.458059ms)","trace[1809975669] 'compare' (duration: 81.905445ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:14:25.834948Z","caller":"traceutil/trace.go:171","msg":"trace[1125783767] transaction","detail":"{read_only:false; response_revision:5056; number_of_response:1; }","duration":"235.511015ms","start":"2026-04-22T06:14:25.599427Z","end":"2026-04-22T06:14:25.834938Z","steps":["trace[1125783767] 'process raft request' (duration: 235.28991ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:25.835086Z","caller":"traceutil/trace.go:171","msg":"trace[1570622145] transaction","detail":"{read_only:false; response_revision:5057; number_of_response:1; }","duration":"235.574817ms","start":"2026-04-22T06:14:25.599474Z","end":"2026-04-22T06:14:25.835049Z","steps":["trace[1570622145] 'process raft request' (duration: 235.433184ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:14:25.835151Z","caller":"traceutil/trace.go:171","msg":"trace[795964472] transaction","detail":"{read_only:false; response_revision:5058; number_of_response:1; }","duration":"234.975703ms","start":"2026-04-22T06:14:25.600169Z","end":"2026-04-22T06:14:25.835144Z","steps":["trace[795964472] 'process raft request' (duration: 234.814129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:15:01.613643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.608819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-22T06:15:01.613727Z","caller":"traceutil/trace.go:171","msg":"trace[747544296] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5418; }","duration":"127.740152ms","start":"2026-04-22T06:15:01.485969Z","end":"2026-04-22T06:15:01.613709Z","steps":["trace[747544296] 'range keys from in-memory index tree' (duration: 127.375453ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:17:37.335028Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3290} {"level":"info","ts":"2026-04-22T06:17:37.421992Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3290,"took":"85.467787ms","hash":2254731760,"current-db-size-bytes":60510208,"current-db-size":"60 MB","current-db-size-in-use-bytes":33726464,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-22T06:17:37.422059Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2254731760,"revision":3290,"compact-revision":-1} {"level":"info","ts":"2026-04-22T06:20:04.90907Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002107.wal"} {"level":"info","ts":"2026-04-22T06:20:08.99824Z","caller":"traceutil/trace.go:171","msg":"trace[783431134] transaction","detail":"{read_only:false; response_revision:8123; number_of_response:1; }","duration":"446.510256ms","start":"2026-04-22T06:20:08.551702Z","end":"2026-04-22T06:20:08.998212Z","steps":["trace[783431134] 'process raft request' (duration: 446.332552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:08.998412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:08.551678Z","time spent":"446.644919ms","remote":"127.0.0.1:50202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:20:18.321147Z","caller":"traceutil/trace.go:171","msg":"trace[1457075512] linearizableReadLoop","detail":"{readStateIndex:8544; appliedIndex:8543; }","duration":"187.43967ms","start":"2026-04-22T06:20:18.133678Z","end":"2026-04-22T06:20:18.321117Z","steps":["trace[1457075512] 'read index received' (duration: 187.271066ms)","trace[1457075512] 'applied index is now lower than readState.Index' (duration: 167.804µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:20:18.321208Z","caller":"traceutil/trace.go:171","msg":"trace[1280078606] transaction","detail":"{read_only:false; response_revision:8183; number_of_response:1; }","duration":"255.752561ms","start":"2026-04-22T06:20:18.065427Z","end":"2026-04-22T06:20:18.32118Z","steps":["trace[1280078606] 'process raft request' (duration: 255.518826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:18.321373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.671135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:20:18.321453Z","caller":"traceutil/trace.go:171","msg":"trace[2078557151] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:8183; }","duration":"187.814088ms","start":"2026-04-22T06:20:18.133622Z","end":"2026-04-22T06:20:18.321436Z","steps":["trace[2078557151] 'agreement among raft nodes before linearized reading' (duration: 187.649064ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:18.734324Z","caller":"traceutil/trace.go:171","msg":"trace[693489877] linearizableReadLoop","detail":"{readStateIndex:8545; appliedIndex:8544; }","duration":"412.949829ms","start":"2026-04-22T06:20:18.321348Z","end":"2026-04-22T06:20:18.734298Z","steps":["trace[693489877] 'read index received' (duration: 375.887572ms)","trace[693489877] 'applied index is now lower than readState.Index' (duration: 37.061307ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:20:18.734492Z","caller":"traceutil/trace.go:171","msg":"trace[217120700] transaction","detail":"{read_only:false; response_revision:8184; number_of_response:1; }","duration":"503.44787ms","start":"2026-04-22T06:20:18.231014Z","end":"2026-04-22T06:20:18.734462Z","steps":["trace[217120700] 'process raft request' (duration: 466.18213ms)","trace[217120700] 'compare' (duration: 36.161456ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:20:18.734597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:18.230979Z","time spent":"503.543213ms","remote":"127.0.0.1:50202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:20:18.73479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.631993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-22T06:20:18.734903Z","caller":"traceutil/trace.go:171","msg":"trace[1696919575] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8184; }","duration":"490.760995ms","start":"2026-04-22T06:20:18.244117Z","end":"2026-04-22T06:20:18.734878Z","steps":["trace[1696919575] 'agreement among raft nodes before linearized reading' (duration: 490.472078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:18.734959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:18.244075Z","time spent":"490.874868ms","remote":"127.0.0.1:50202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T06:20:18.73496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.689231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-22T06:20:18.735024Z","caller":"traceutil/trace.go:171","msg":"trace[1367523844] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8184; }","duration":"310.784153ms","start":"2026-04-22T06:20:18.424218Z","end":"2026-04-22T06:20:18.735003Z","steps":["trace[1367523844] 'agreement among raft nodes before linearized reading' (duration: 310.609999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:18.735079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:18.424197Z","time spent":"310.871156ms","remote":"127.0.0.1:50202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-22T06:20:18.73516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.640372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-22T06:20:18.735195Z","caller":"traceutil/trace.go:171","msg":"trace[612144080] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8184; }","duration":"474.697953ms","start":"2026-04-22T06:20:18.260488Z","end":"2026-04-22T06:20:18.735186Z","steps":["trace[612144080] 'agreement among raft nodes before linearized reading' (duration: 474.602521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:18.735227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:18.260475Z","time spent":"474.745064ms","remote":"127.0.0.1:50202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T06:20:18.735227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.963779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:20:18.735264Z","caller":"traceutil/trace.go:171","msg":"trace[1063351292] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8184; }","duration":"557.035801ms","start":"2026-04-22T06:20:18.178216Z","end":"2026-04-22T06:20:18.735252Z","steps":["trace[1063351292] 'agreement among raft nodes before linearized reading' (duration: 556.967299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:18.735308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:18.178196Z","time spent":"557.103432ms","remote":"127.0.0.1:49970","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-04-22T06:20:18.735371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.921783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-04-22T06:20:18.735405Z","caller":"traceutil/trace.go:171","msg":"trace[1305560106] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8184; }","duration":"480.980674ms","start":"2026-04-22T06:20:18.254415Z","end":"2026-04-22T06:20:18.735395Z","steps":["trace[1305560106] 'agreement among raft nodes before linearized reading' (duration: 480.880632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:18.735439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:18.254403Z","time spent":"481.023626ms","remote":"127.0.0.1:50202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":547,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-04-22T06:20:19.37993Z","caller":"traceutil/trace.go:171","msg":"trace[651143141] transaction","detail":"{read_only:false; response_revision:8190; number_of_response:1; }","duration":"277.836779ms","start":"2026-04-22T06:20:19.102071Z","end":"2026-04-22T06:20:19.379908Z","steps":["trace[651143141] 'process raft request' (duration: 277.647655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.969137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.604554ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:20:19.969374Z","caller":"traceutil/trace.go:171","msg":"trace[89017728] linearizableReadLoop","detail":"{readStateIndex:8553; appliedIndex:8551; }","duration":"581.702806ms","start":"2026-04-22T06:20:19.387652Z","end":"2026-04-22T06:20:19.969355Z","steps":["trace[89017728] 'read index received' (duration: 163.792996ms)","trace[89017728] 'applied index is now lower than readState.Index' (duration: 417.9088ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:20:19.969376Z","caller":"traceutil/trace.go:171","msg":"trace[849209517] transaction","detail":"{read_only:false; response_revision:8191; number_of_response:1; }","duration":"585.680807ms","start":"2026-04-22T06:20:19.383657Z","end":"2026-04-22T06:20:19.969338Z","steps":["trace[849209517] 'process raft request' (duration: 167.768706ms)","trace[849209517] 'compare' (duration: 417.376419ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:20:19.969567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.383637Z","time spent":"585.826049ms","remote":"127.0.0.1:50202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:20:19.969547Z","caller":"traceutil/trace.go:171","msg":"trace[1698263171] transaction","detail":"{read_only:false; response_revision:8192; number_of_response:1; }","duration":"583.342323ms","start":"2026-04-22T06:20:19.386164Z","end":"2026-04-22T06:20:19.969506Z","steps":["trace[1698263171] 'process raft request' (duration: 583.093248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.969702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.386147Z","time spent":"583.481347ms","remote":"127.0.0.1:50202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:20:19.969757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.078294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/key-manager.18a899779acc5061\" ","response":"range_response_count:1 size:679"} {"level":"info","ts":"2026-04-22T06:20:19.969846Z","caller":"traceutil/trace.go:171","msg":"trace[141892921] range","detail":"{range_begin:/registry/events/openstack/key-manager.18a899779acc5061; range_end:; response_count:1; response_revision:8192; }","duration":"582.197307ms","start":"2026-04-22T06:20:19.387625Z","end":"2026-04-22T06:20:19.969822Z","steps":["trace[141892921] 'agreement among raft nodes before linearized reading' (duration: 581.907591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.969893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.387614Z","time spent":"582.268879ms","remote":"127.0.0.1:50042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":702,"request content":"key:\"/registry/events/openstack/key-manager.18a899779acc5061\" "} {"level":"info","ts":"2026-04-22T06:20:19.979819Z","caller":"traceutil/trace.go:171","msg":"trace[130130493] transaction","detail":"{read_only:false; response_revision:8193; number_of_response:1; }","duration":"226.314757ms","start":"2026-04-22T06:20:19.753475Z","end":"2026-04-22T06:20:19.979789Z","steps":["trace[130130493] 'process raft request' (duration: 226.124963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.980082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.566809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:20:19.980133Z","caller":"traceutil/trace.go:171","msg":"trace[867535857] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8193; }","duration":"591.646111ms","start":"2026-04-22T06:20:19.388475Z","end":"2026-04-22T06:20:19.980121Z","steps":["trace[867535857] 'agreement among raft nodes before linearized reading' (duration: 591.553949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.980176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.388465Z","time spent":"591.702192ms","remote":"127.0.0.1:51480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-22T06:20:19.980227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.943424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.980352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.328798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.98036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.953225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:20:19.9804Z","caller":"traceutil/trace.go:171","msg":"trace[1778712044] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8193; }","duration":"472.389219ms","start":"2026-04-22T06:20:19.507993Z","end":"2026-04-22T06:20:19.980382Z","steps":["trace[1778712044] 'agreement among raft nodes before linearized reading' (duration: 472.313157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.980424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.131159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-04-22T06:20:19.980431Z","caller":"traceutil/trace.go:171","msg":"trace[677176241] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8193; }","duration":"517.052758ms","start":"2026-04-22T06:20:19.463361Z","end":"2026-04-22T06:20:19.980414Z","steps":["trace[677176241] 'agreement among raft nodes before linearized reading' (duration: 516.919735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.980426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.488544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.980449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.019911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:20:19.980487Z","caller":"traceutil/trace.go:171","msg":"trace[752134600] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8193; }","duration":"391.568436ms","start":"2026-04-22T06:20:19.588905Z","end":"2026-04-22T06:20:19.980473Z","steps":["trace[752134600] 'agreement among raft nodes before linearized reading' (duration: 391.447873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.980488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.508949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.980435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.507978Z","time spent":"472.44681ms","remote":"127.0.0.1:51554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:10000 "} {"level":"info","ts":"2026-04-22T06:20:19.980509Z","caller":"traceutil/trace.go:171","msg":"trace[798818467] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8193; }","duration":"456.106332ms","start":"2026-04-22T06:20:19.524385Z","end":"2026-04-22T06:20:19.980492Z","steps":["trace[798818467] 'agreement among raft nodes before linearized reading' (duration: 455.99714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.980499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.571059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:20:19.980538Z","caller":"traceutil/trace.go:171","msg":"trace[888887380] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8193; }","duration":"471.555981ms","start":"2026-04-22T06:20:19.50896Z","end":"2026-04-22T06:20:19.980516Z","steps":["trace[888887380] 'agreement among raft nodes before linearized reading' (duration: 471.50203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.980562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.947424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.980523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.588902Z","time spent":"391.614517ms","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true "} {"level":"warn","ts":"2026-04-22T06:20:19.980571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.508953Z","time spent":"471.609942ms","remote":"127.0.0.1:51554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:500 "} {"level":"info","ts":"2026-04-22T06:20:19.980569Z","caller":"traceutil/trace.go:171","msg":"trace[1824479522] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8193; }","duration":"486.695722ms","start":"2026-04-22T06:20:19.493859Z","end":"2026-04-22T06:20:19.980555Z","steps":["trace[1824479522] 'agreement among raft nodes before linearized reading' (duration: 486.552429ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:19.980594Z","caller":"traceutil/trace.go:171","msg":"trace[79444072] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8193; }","duration":"371.974264ms","start":"2026-04-22T06:20:19.608608Z","end":"2026-04-22T06:20:19.980583Z","steps":["trace[79444072] 'agreement among raft nodes before linearized reading' (duration: 371.936024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.980615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.608602Z","time spent":"372.009015ms","remote":"127.0.0.1:51594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:500 "} {"level":"warn","ts":"2026-04-22T06:20:19.980598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.696488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.980656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.660691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:20:19.980659Z","caller":"traceutil/trace.go:171","msg":"trace[732416923] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:8193; }","duration":"332.79088ms","start":"2026-04-22T06:20:19.64785Z","end":"2026-04-22T06:20:19.980641Z","steps":["trace[732416923] 'agreement among raft nodes before linearized reading' (duration: 332.681967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.980678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.633654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:20:19.980686Z","caller":"traceutil/trace.go:171","msg":"trace[1283491085] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8193; }","duration":"486.684781ms","start":"2026-04-22T06:20:19.493988Z","end":"2026-04-22T06:20:19.980673Z","steps":["trace[1283491085] 'agreement among raft nodes before linearized reading' (duration: 486.647791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.980701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.647838Z","time spent":"332.853222ms","remote":"127.0.0.1:51606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" limit:500 "} {"level":"warn","ts":"2026-04-22T06:20:19.980713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.493974Z","time spent":"486.732883ms","remote":"127.0.0.1:51542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:500 "} {"level":"warn","ts":"2026-04-22T06:20:19.980486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.463356Z","time spent":"517.118629ms","remote":"127.0.0.1:51530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true "} {"level":"warn","ts":"2026-04-22T06:20:19.980688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.179374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.980737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.785633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:20:19.980464Z","caller":"traceutil/trace.go:171","msg":"trace[391716235] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8193; }","duration":"357.18554ms","start":"2026-04-22T06:20:19.623266Z","end":"2026-04-22T06:20:19.980451Z","steps":["trace[391716235] 'agreement among raft nodes before linearized reading' (duration: 357.063917ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:19.980781Z","caller":"traceutil/trace.go:171","msg":"trace[192977279] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:8193; }","duration":"333.831104ms","start":"2026-04-22T06:20:19.646939Z","end":"2026-04-22T06:20:19.98077Z","steps":["trace[192977279] 'agreement among raft nodes before linearized reading' (duration: 333.764822ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:19.980782Z","caller":"traceutil/trace.go:171","msg":"trace[1373281814] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8193; }","duration":"456.317007ms","start":"2026-04-22T06:20:19.524443Z","end":"2026-04-22T06:20:19.98076Z","steps":["trace[1373281814] 'agreement among raft nodes before linearized reading' (duration: 456.142554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.98081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.623255Z","time spent":"357.542457ms","remote":"127.0.0.1:50066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-22T06:20:19.980788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.547698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.980839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.15409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.980833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.811919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.980793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.998997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:20:19.980878Z","caller":"traceutil/trace.go:171","msg":"trace[982011316] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8193; }","duration":"556.662971ms","start":"2026-04-22T06:20:19.424193Z","end":"2026-04-22T06:20:19.980856Z","steps":["trace[982011316] 'agreement among raft nodes before linearized reading' (duration: 556.532668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.98088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.998657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:20:19.980888Z","caller":"traceutil/trace.go:171","msg":"trace[27147419] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8193; }","duration":"316.87107ms","start":"2026-04-22T06:20:19.664004Z","end":"2026-04-22T06:20:19.980875Z","steps":["trace[27147419] 'agreement among raft nodes before linearized reading' (duration: 316.783618ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:19.980907Z","caller":"traceutil/trace.go:171","msg":"trace[996755453] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8193; }","duration":"373.108769ms","start":"2026-04-22T06:20:19.607787Z","end":"2026-04-22T06:20:19.980895Z","steps":["trace[996755453] 'agreement among raft nodes before linearized reading' (duration: 372.984907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.980921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.424182Z","time spent":"556.728033ms","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:10000 "} {"level":"info","ts":"2026-04-22T06:20:19.980924Z","caller":"traceutil/trace.go:171","msg":"trace[260644851] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:8193; }","duration":"334.052528ms","start":"2026-04-22T06:20:19.646858Z","end":"2026-04-22T06:20:19.98091Z","steps":["trace[260644851] 'agreement among raft nodes before linearized reading' (duration: 333.985966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.980942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.60777Z","time spent":"373.16361ms","remote":"127.0.0.1:51594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"warn","ts":"2026-04-22T06:20:19.980968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.274348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"warn","ts":"2026-04-22T06:20:19.980972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.935062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.980952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.207796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:20:19.981002Z","caller":"traceutil/trace.go:171","msg":"trace[165996042] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8193; }","duration":"316.973363ms","start":"2026-04-22T06:20:19.664021Z","end":"2026-04-22T06:20:19.980994Z","steps":["trace[165996042] 'agreement among raft nodes before linearized reading' (duration: 316.928562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.981021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.097321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.981037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.664017Z","time spent":"317.012593ms","remote":"127.0.0.1:51616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true "} {"level":"info","ts":"2026-04-22T06:20:19.981048Z","caller":"traceutil/trace.go:171","msg":"trace[135955430] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8193; }","duration":"487.133092ms","start":"2026-04-22T06:20:19.493908Z","end":"2026-04-22T06:20:19.981041Z","steps":["trace[135955430] 'agreement among raft nodes before linearized reading' (duration: 487.083491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.981047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.080023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:20:19.980712Z","caller":"traceutil/trace.go:171","msg":"trace[2134733227] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8193; }","duration":"472.690096ms","start":"2026-04-22T06:20:19.508012Z","end":"2026-04-22T06:20:19.980702Z","steps":["trace[2134733227] 'agreement among raft nodes before linearized reading' (duration: 472.637435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.981073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.493904Z","time spent":"487.163602ms","remote":"127.0.0.1:51542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true "} {"level":"info","ts":"2026-04-22T06:20:19.981051Z","caller":"traceutil/trace.go:171","msg":"trace[2125058085] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8193; }","duration":"316.335948ms","start":"2026-04-22T06:20:19.664691Z","end":"2026-04-22T06:20:19.981027Z","steps":["trace[2125058085] 'agreement among raft nodes before linearized reading' (duration: 316.135464ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:19.98108Z","caller":"traceutil/trace.go:171","msg":"trace[564430513] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8193; }","duration":"351.121143ms","start":"2026-04-22T06:20:19.629949Z","end":"2026-04-22T06:20:19.98107Z","steps":["trace[564430513] 'agreement among raft nodes before linearized reading' (duration: 351.072662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.981079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.794401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-22T06:20:19.981109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.497749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.981113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.664684Z","time spent":"316.41711ms","remote":"127.0.0.1:51616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:500 "} {"level":"info","ts":"2026-04-22T06:20:19.981138Z","caller":"traceutil/trace.go:171","msg":"trace[1469974057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8193; }","duration":"317.869883ms","start":"2026-04-22T06:20:19.663257Z","end":"2026-04-22T06:20:19.981127Z","steps":["trace[1469974057] 'agreement among raft nodes before linearized reading' (duration: 317.693749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.980616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.49384Z","time spent":"486.768194ms","remote":"127.0.0.1:51542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:10000 "} {"level":"info","ts":"2026-04-22T06:20:19.981149Z","caller":"traceutil/trace.go:171","msg":"trace[2023772037] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8193; }","duration":"350.549571ms","start":"2026-04-22T06:20:19.630587Z","end":"2026-04-22T06:20:19.981137Z","steps":["trace[2023772037] 'agreement among raft nodes before linearized reading' (duration: 350.4936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.98056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.524373Z","time spent":"456.175694ms","remote":"127.0.0.1:51560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:10000 "} {"level":"warn","ts":"2026-04-22T06:20:19.981153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.801254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.981171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.663249Z","time spent":"317.914563ms","remote":"127.0.0.1:50202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T06:20:19.981159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.508Z","time spent":"473.098655ms","remote":"127.0.0.1:51554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true "} {"level":"warn","ts":"2026-04-22T06:20:19.981183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.630581Z","time spent":"350.593032ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:500 "} {"level":"info","ts":"2026-04-22T06:20:19.981197Z","caller":"traceutil/trace.go:171","msg":"trace[1534887640] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8193; }","duration":"517.864035ms","start":"2026-04-22T06:20:19.463322Z","end":"2026-04-22T06:20:19.981186Z","steps":["trace[1534887640] 'agreement among raft nodes before linearized reading' (duration: 517.788683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.98122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.212042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.981232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.463313Z","time spent":"517.910597ms","remote":"127.0.0.1:51530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:10000 "} {"level":"info","ts":"2026-04-22T06:20:19.981254Z","caller":"traceutil/trace.go:171","msg":"trace[434512910] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:8193; }","duration":"502.268954ms","start":"2026-04-22T06:20:19.478976Z","end":"2026-04-22T06:20:19.981245Z","steps":["trace[434512910] 'agreement among raft nodes before linearized reading' (duration: 502.222253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.981024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.499104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.980926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.663987Z","time spent":"316.930402ms","remote":"127.0.0.1:51616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:10000 "} {"level":"warn","ts":"2026-04-22T06:20:19.981283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.47896Z","time spent":"502.315466ms","remote":"127.0.0.1:51536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true "} {"level":"warn","ts":"2026-04-22T06:20:19.981304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.308015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.981178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.199213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.981325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.604276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.981115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.629942Z","time spent":"351.163364ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:10000 "} {"level":"info","ts":"2026-04-22T06:20:19.981351Z","caller":"traceutil/trace.go:171","msg":"trace[1867884768] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8193; }","duration":"410.658317ms","start":"2026-04-22T06:20:19.570686Z","end":"2026-04-22T06:20:19.981345Z","steps":["trace[1867884768] 'agreement among raft nodes before linearized reading' (duration: 410.616966ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:19.981349Z","caller":"traceutil/trace.go:171","msg":"trace[1989070891] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:8193; }","duration":"502.393097ms","start":"2026-04-22T06:20:19.478944Z","end":"2026-04-22T06:20:19.981337Z","steps":["trace[1989070891] 'agreement among raft nodes before linearized reading' (duration: 502.211272ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:19.981308Z","caller":"traceutil/trace.go:171","msg":"trace[325539143] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8193; }","duration":"391.770721ms","start":"2026-04-22T06:20:19.589514Z","end":"2026-04-22T06:20:19.981285Z","steps":["trace[325539143] 'agreement among raft nodes before linearized reading' (duration: 391.495324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.981362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.265773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.981379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.57067Z","time spent":"410.703868ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":113,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:10000 "} {"level":"warn","ts":"2026-04-22T06:20:19.981383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.478926Z","time spent":"502.446259ms","remote":"127.0.0.1:51536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" limit:500 "} {"level":"warn","ts":"2026-04-22T06:20:19.981387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.589511Z","time spent":"391.866423ms","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:500 "} {"level":"info","ts":"2026-04-22T06:20:19.981396Z","caller":"traceutil/trace.go:171","msg":"trace[231596389] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8193; }","duration":"427.301173ms","start":"2026-04-22T06:20:19.554085Z","end":"2026-04-22T06:20:19.981387Z","steps":["trace[231596389] 'agreement among raft nodes before linearized reading' (duration: 427.244372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.981402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.366997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.981426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.554077Z","time spent":"427.341644ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:500 "} {"level":"info","ts":"2026-04-22T06:20:19.980867Z","caller":"traceutil/trace.go:171","msg":"trace[2115429981] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8193; }","duration":"373.193402ms","start":"2026-04-22T06:20:19.607666Z","end":"2026-04-22T06:20:19.98086Z","steps":["trace[2115429981] 'agreement among raft nodes before linearized reading' (duration: 373.156631ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:19.980994Z","caller":"traceutil/trace.go:171","msg":"trace[392167425] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8193; }","duration":"379.299128ms","start":"2026-04-22T06:20:19.601689Z","end":"2026-04-22T06:20:19.980988Z","steps":["trace[392167425] 'agreement among raft nodes before linearized reading' (duration: 379.193346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.981447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.600252ms","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-04-22T06:20:19.980967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.64685Z","time spent":"334.10452ms","remote":"127.0.0.1:51606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" limit:10000 "} {"level":"warn","ts":"2026-04-22T06:20:19.981472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.985458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.98148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.601665Z","time spent":"379.806541ms","remote":"127.0.0.1:50066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-22T06:20:19.981488Z","caller":"traceutil/trace.go:171","msg":"trace[1857783088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8193; }","duration":"323.649294ms","start":"2026-04-22T06:20:19.657826Z","end":"2026-04-22T06:20:19.981476Z","steps":["trace[1857783088] 'agreement among raft nodes before linearized reading' (duration: 323.480201ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:19.981349Z","caller":"traceutil/trace.go:171","msg":"trace[340563057] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:8193; }","duration":"502.377947ms","start":"2026-04-22T06:20:19.478954Z","end":"2026-04-22T06:20:19.981332Z","steps":["trace[340563057] 'agreement among raft nodes before linearized reading' (duration: 502.313286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.98151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.61066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.98152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.657819Z","time spent":"323.694125ms","remote":"127.0.0.1:50202","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-04-22T06:20:19.98153Z","caller":"traceutil/trace.go:171","msg":"trace[940095310] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8193; }","duration":"392.640091ms","start":"2026-04-22T06:20:19.588884Z","end":"2026-04-22T06:20:19.981524Z","steps":["trace[940095310] 'agreement among raft nodes before linearized reading' (duration: 392.604031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.980838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.524432Z","time spent":"456.394039ms","remote":"127.0.0.1:51560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true "} {"level":"warn","ts":"2026-04-22T06:20:19.981537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.478942Z","time spent":"502.582981ms","remote":"127.0.0.1:51536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" limit:10000 "} {"level":"warn","ts":"2026-04-22T06:20:19.981576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.89108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.981576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.881427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.98159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.972935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:20:19.981601Z","caller":"traceutil/trace.go:171","msg":"trace[1342213691] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8193; }","duration":"409.924621ms","start":"2026-04-22T06:20:19.571669Z","end":"2026-04-22T06:20:19.981593Z","steps":["trace[1342213691] 'agreement among raft nodes before linearized reading' (duration: 409.894ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:19.981606Z","caller":"traceutil/trace.go:171","msg":"trace[2042078699] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8193; }","duration":"537.911348ms","start":"2026-04-22T06:20:19.443687Z","end":"2026-04-22T06:20:19.981598Z","steps":["trace[2042078699] 'agreement among raft nodes before linearized reading' (duration: 537.869717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.98162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.571661Z","time spent":"409.954031ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":113,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:500 "} {"level":"info","ts":"2026-04-22T06:20:19.981619Z","caller":"traceutil/trace.go:171","msg":"trace[1478238720] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8193; }","duration":"411.013075ms","start":"2026-04-22T06:20:19.570597Z","end":"2026-04-22T06:20:19.981611Z","steps":["trace[1478238720] 'agreement among raft nodes before linearized reading' (duration: 410.967894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.981636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.443654Z","time spent":"537.97498ms","remote":"127.0.0.1:51516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:10000 "} {"level":"warn","ts":"2026-04-22T06:20:19.98165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.570592Z","time spent":"411.048626ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true "} {"level":"warn","ts":"2026-04-22T06:20:19.981051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.058712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.981463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.607659Z","time spent":"373.795746ms","remote":"127.0.0.1:51594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:10000 "} {"level":"info","ts":"2026-04-22T06:20:19.981434Z","caller":"traceutil/trace.go:171","msg":"trace[2128667603] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8193; }","duration":"518.415409ms","start":"2026-04-22T06:20:19.46301Z","end":"2026-04-22T06:20:19.981425Z","steps":["trace[2128667603] 'agreement among raft nodes before linearized reading' (duration: 518.366008ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:19.981701Z","caller":"traceutil/trace.go:171","msg":"trace[2000099106] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8193; }","duration":"351.712377ms","start":"2026-04-22T06:20:19.629973Z","end":"2026-04-22T06:20:19.981685Z","steps":["trace[2000099106] 'agreement among raft nodes before linearized reading' (duration: 351.039581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.981733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.421138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.981737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.089722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.98155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.588878Z","time spent":"392.666672ms","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:10000 "} {"level":"info","ts":"2026-04-22T06:20:19.981763Z","caller":"traceutil/trace.go:171","msg":"trace[31029618] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8193; }","duration":"557.118782ms","start":"2026-04-22T06:20:19.424636Z","end":"2026-04-22T06:20:19.981755Z","steps":["trace[31029618] 'agreement among raft nodes before linearized reading' (duration: 557.078892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.981751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.463Z","time spent":"518.734797ms","remote":"127.0.0.1:51530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:500 "} {"level":"info","ts":"2026-04-22T06:20:19.981759Z","caller":"traceutil/trace.go:171","msg":"trace[1850932433] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8193; }","duration":"428.465188ms","start":"2026-04-22T06:20:19.553284Z","end":"2026-04-22T06:20:19.981749Z","steps":["trace[1850932433] 'agreement among raft nodes before linearized reading' (duration: 428.428038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.981788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.424561Z","time spent":"557.221864ms","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:500 "} {"level":"info","ts":"2026-04-22T06:20:19.9815Z","caller":"traceutil/trace.go:171","msg":"trace[1904804816] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8193; }","duration":"537.030039ms","start":"2026-04-22T06:20:19.444462Z","end":"2026-04-22T06:20:19.981492Z","steps":["trace[1904804816] 'agreement among raft nodes before linearized reading' (duration: 536.990318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.981783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.629969Z","time spent":"351.783348ms","remote":"127.0.0.1:51602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true "} {"level":"warn","ts":"2026-04-22T06:20:19.9818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.553272Z","time spent":"428.52069ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:10000 "} {"level":"warn","ts":"2026-04-22T06:20:19.980818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.646925Z","time spent":"333.882974ms","remote":"127.0.0.1:51606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true "} {"level":"warn","ts":"2026-04-22T06:20:19.981826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.444455Z","time spent":"537.360126ms","remote":"127.0.0.1:51516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:500 "} {"level":"warn","ts":"2026-04-22T06:20:19.98109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.944369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.981996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.574576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:20:19.981999Z","caller":"traceutil/trace.go:171","msg":"trace[1121180115] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8193; }","duration":"456.854899ms","start":"2026-04-22T06:20:19.525124Z","end":"2026-04-22T06:20:19.981979Z","steps":["trace[1121180115] 'agreement among raft nodes before linearized reading' (duration: 455.941838ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:19.982017Z","caller":"traceutil/trace.go:171","msg":"trace[1078538203] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8193; }","duration":"574.616846ms","start":"2026-04-22T06:20:19.407393Z","end":"2026-04-22T06:20:19.98201Z","steps":["trace[1078538203] 'agreement among raft nodes before linearized reading' (duration: 574.579795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.982014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.158326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.982037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.407379Z","time spent":"574.653078ms","remote":"127.0.0.1:51490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:10000 "} {"level":"warn","ts":"2026-04-22T06:20:19.982042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.525116Z","time spent":"456.910631ms","remote":"127.0.0.1:51560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:500 "} {"level":"info","ts":"2026-04-22T06:20:19.982052Z","caller":"traceutil/trace.go:171","msg":"trace[1149940074] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8193; }","duration":"593.234467ms","start":"2026-04-22T06:20:19.388805Z","end":"2026-04-22T06:20:19.98204Z","steps":["trace[1149940074] 'agreement among raft nodes before linearized reading' (duration: 593.173625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.982064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.895879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.982085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.388782Z","time spent":"593.294979ms","remote":"127.0.0.1:51480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" limit:500 "} {"level":"info","ts":"2026-04-22T06:20:19.982096Z","caller":"traceutil/trace.go:171","msg":"trace[547205151] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8193; }","duration":"557.93385ms","start":"2026-04-22T06:20:19.424151Z","end":"2026-04-22T06:20:19.982085Z","steps":["trace[547205151] 'agreement among raft nodes before linearized reading' (duration: 557.890659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.982111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.901341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.982128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.424143Z","time spent":"557.980022ms","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true "} {"level":"warn","ts":"2026-04-22T06:20:19.982057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.496913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.982116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.77492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:20:19.98215Z","caller":"traceutil/trace.go:171","msg":"trace[1524531725] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8193; }","duration":"573.960212ms","start":"2026-04-22T06:20:19.408179Z","end":"2026-04-22T06:20:19.98214Z","steps":["trace[1524531725] 'agreement among raft nodes before linearized reading' (duration: 573.892701ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:19.982164Z","caller":"traceutil/trace.go:171","msg":"trace[1172342117] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8193; }","duration":"593.623786ms","start":"2026-04-22T06:20:19.388528Z","end":"2026-04-22T06:20:19.982151Z","steps":["trace[1172342117] 'agreement among raft nodes before linearized reading' (duration: 593.492493ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:19.982169Z","caller":"traceutil/trace.go:171","msg":"trace[1356870671] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8193; }","duration":"574.841971ms","start":"2026-04-22T06:20:19.407317Z","end":"2026-04-22T06:20:19.982159Z","steps":["trace[1356870671] 'agreement among raft nodes before linearized reading' (duration: 574.76024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.982181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.408171Z","time spent":"574.002483ms","remote":"127.0.0.1:51490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:500 "} {"level":"warn","ts":"2026-04-22T06:20:19.982204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.407307Z","time spent":"574.889582ms","remote":"127.0.0.1:51490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"warn","ts":"2026-04-22T06:20:19.981538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.766426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:19.982205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.388521Z","time spent":"593.672847ms","remote":"127.0.0.1:51480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" limit:10000 "} {"level":"info","ts":"2026-04-22T06:20:19.98224Z","caller":"traceutil/trace.go:171","msg":"trace[497095697] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8193; }","duration":"538.504721ms","start":"2026-04-22T06:20:19.443726Z","end":"2026-04-22T06:20:19.982231Z","steps":["trace[497095697] 'agreement among raft nodes before linearized reading' (duration: 537.792955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.98227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.443702Z","time spent":"538.560103ms","remote":"127.0.0.1:51516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true "} {"level":"info","ts":"2026-04-22T06:20:19.980285Z","caller":"traceutil/trace.go:171","msg":"trace[1253828314] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8193; }","duration":"427.021426ms","start":"2026-04-22T06:20:19.553249Z","end":"2026-04-22T06:20:19.980271Z","steps":["trace[1253828314] 'agreement among raft nodes before linearized reading' (duration: 426.915073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:19.982546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:20:19.553239Z","time spent":"429.287897ms","remote":"127.0.0.1:51566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true "} {"level":"info","ts":"2026-04-22T06:20:44.625556Z","caller":"traceutil/trace.go:171","msg":"trace[1341284256] transaction","detail":"{read_only:false; response_revision:8409; number_of_response:1; }","duration":"118.056224ms","start":"2026-04-22T06:20:44.507474Z","end":"2026-04-22T06:20:44.62553Z","steps":["trace[1341284256] 'process raft request' (duration: 117.87927ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:44.627044Z","caller":"traceutil/trace.go:171","msg":"trace[1365470380] transaction","detail":"{read_only:false; response_revision:8410; number_of_response:1; }","duration":"117.088732ms","start":"2026-04-22T06:20:44.50992Z","end":"2026-04-22T06:20:44.627009Z","steps":["trace[1365470380] 'process raft request' (duration: 116.868757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:46.315947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.913932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T06:20:46.316002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.15518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-22T06:20:46.316044Z","caller":"traceutil/trace.go:171","msg":"trace[2118162053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8422; }","duration":"138.069596ms","start":"2026-04-22T06:20:46.177952Z","end":"2026-04-22T06:20:46.316021Z","steps":["trace[2118162053] 'range keys from in-memory index tree' (duration: 137.81425ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:20:46.316058Z","caller":"traceutil/trace.go:171","msg":"trace[199889483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8422; }","duration":"104.270063ms","start":"2026-04-22T06:20:46.211772Z","end":"2026-04-22T06:20:46.316042Z","steps":["trace[199889483] 'range keys from in-memory index tree' (duration: 103.907085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:20:46.316362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.899395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T06:20:46.316482Z","caller":"traceutil/trace.go:171","msg":"trace[1788572126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8422; }","duration":"112.188651ms","start":"2026-04-22T06:20:46.204266Z","end":"2026-04-22T06:20:46.316455Z","steps":["trace[1788572126] 'range keys from in-memory index tree' (duration: 111.607188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:01.516511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.588051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:21:01.516713Z","caller":"traceutil/trace.go:171","msg":"trace[288730947] linearizableReadLoop","detail":"{readStateIndex:8948; appliedIndex:8945; }","duration":"339.87473ms","start":"2026-04-22T06:21:01.176825Z","end":"2026-04-22T06:21:01.5167Z","steps":["trace[288730947] 'read index received' (duration: 184.109805ms)","trace[288730947] 'applied index is now lower than readState.Index' (duration: 155.764304ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:21:01.516827Z","caller":"traceutil/trace.go:171","msg":"trace[1586821291] transaction","detail":"{read_only:false; response_revision:8565; number_of_response:1; }","duration":"412.794905ms","start":"2026-04-22T06:21:01.103972Z","end":"2026-04-22T06:21:01.516766Z","steps":["trace[1586821291] 'process raft request' (duration: 412.638632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:01.516966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.154045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:21:01.517045Z","caller":"traceutil/trace.go:171","msg":"trace[1887596169] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8566; }","duration":"340.231197ms","start":"2026-04-22T06:21:01.176755Z","end":"2026-04-22T06:21:01.516987Z","steps":["trace[1887596169] 'agreement among raft nodes before linearized reading' (duration: 340.187096ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:21:01.516903Z","caller":"traceutil/trace.go:171","msg":"trace[89445255] transaction","detail":"{read_only:false; response_revision:8566; number_of_response:1; }","duration":"410.957303ms","start":"2026-04-22T06:21:01.105933Z","end":"2026-04-22T06:21:01.51689Z","steps":["trace[89445255] 'process raft request' (duration: 410.729978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:01.51707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:21:01.103958Z","time spent":"412.986469ms","remote":"127.0.0.1:50202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:21:01.517109Z","caller":"traceutil/trace.go:171","msg":"trace[1413918326] transaction","detail":"{read_only:false; response_revision:8564; number_of_response:1; }","duration":"415.440645ms","start":"2026-04-22T06:21:01.101622Z","end":"2026-04-22T06:21:01.517062Z","steps":["trace[1413918326] 'process raft request' (duration: 259.2283ms)","trace[1413918326] 'compare' (duration: 155.504359ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:21:01.517078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:21:01.176727Z","time spent":"340.34361ms","remote":"127.0.0.1:49970","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-04-22T06:21:01.517218Z","caller":"traceutil/trace.go:171","msg":"trace[605138273] transaction","detail":"{read_only:false; response_revision:8567; number_of_response:1; }","duration":"296.700174ms","start":"2026-04-22T06:21:01.220479Z","end":"2026-04-22T06:21:01.517179Z","steps":["trace[605138273] 'process raft request' (duration: 296.573281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:01.517252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:21:01.10592Z","time spent":"411.213608ms","remote":"127.0.0.1:50120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4598,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:21:01.517283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:21:01.101596Z","time spent":"415.62065ms","remote":"127.0.0.1:50202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:21:01.706509Z","caller":"traceutil/trace.go:171","msg":"trace[38118534] linearizableReadLoop","detail":"{readStateIndex:8950; appliedIndex:8949; }","duration":"189.750213ms","start":"2026-04-22T06:21:01.516736Z","end":"2026-04-22T06:21:01.706486Z","steps":["trace[38118534] 'read index received' (duration: 189.573529ms)","trace[38118534] 'applied index is now lower than readState.Index' (duration: 175.804µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:21:01.706553Z","caller":"traceutil/trace.go:171","msg":"trace[1310096306] transaction","detail":"{read_only:false; response_revision:8568; number_of_response:1; }","duration":"234.963663ms","start":"2026-04-22T06:21:01.471566Z","end":"2026-04-22T06:21:01.70653Z","steps":["trace[1310096306] 'process raft request' (duration: 234.769748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:01.706682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.608981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:21:01.706726Z","caller":"traceutil/trace.go:171","msg":"trace[1314706285] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:8568; }","duration":"217.740924ms","start":"2026-04-22T06:21:01.488972Z","end":"2026-04-22T06:21:01.706713Z","steps":["trace[1314706285] 'agreement among raft nodes before linearized reading' (duration: 217.631151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:01.706861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.153196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:21:01.706974Z","caller":"traceutil/trace.go:171","msg":"trace[1801107703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8568; }","duration":"188.300929ms","start":"2026-04-22T06:21:01.518654Z","end":"2026-04-22T06:21:01.706955Z","steps":["trace[1801107703] 'agreement among raft nodes before linearized reading' (duration: 188.003532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:01.706853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.635272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11498"} {"level":"info","ts":"2026-04-22T06:21:01.707119Z","caller":"traceutil/trace.go:171","msg":"trace[366614684] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8568; }","duration":"389.969229ms","start":"2026-04-22T06:21:01.317135Z","end":"2026-04-22T06:21:01.707104Z","steps":["trace[366614684] 'agreement among raft nodes before linearized reading' (duration: 389.56446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:01.707165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:21:01.317103Z","time spent":"390.048141ms","remote":"127.0.0.1:50120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11521,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-22T06:21:01.707377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.528727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-22T06:21:01.707427Z","caller":"traceutil/trace.go:171","msg":"trace[1361032441] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:8568; }","duration":"197.611149ms","start":"2026-04-22T06:21:01.509801Z","end":"2026-04-22T06:21:01.707412Z","steps":["trace[1361032441] 'agreement among raft nodes before linearized reading' (duration: 196.75351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:02.725489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.870381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-22T06:21:02.725836Z","caller":"traceutil/trace.go:171","msg":"trace[865048991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8571; }","duration":"325.27506ms","start":"2026-04-22T06:21:02.400544Z","end":"2026-04-22T06:21:02.725819Z","steps":["trace[865048991] 'range keys from in-memory index tree' (duration: 324.636806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:02.725844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.243639ms","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-04-22T06:21:02.725874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:21:02.400518Z","time spent":"325.348312ms","remote":"127.0.0.1:50202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T06:21:02.72573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.303337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bwsp2\" ","response":"range_response_count:1 size:6516"} {"level":"info","ts":"2026-04-22T06:21:02.7259Z","caller":"traceutil/trace.go:171","msg":"trace[1534852391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8571; }","duration":"325.327621ms","start":"2026-04-22T06:21:02.400554Z","end":"2026-04-22T06:21:02.725882Z","steps":["trace[1534852391] 'range keys from in-memory index tree' (duration: 325.049995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:02.725968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:21:02.400541Z","time spent":"325.417593ms","remote":"127.0.0.1:50202","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-04-22T06:21:02.725991Z","caller":"traceutil/trace.go:171","msg":"trace[1385876655] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bwsp2; range_end:; response_count:1; response_revision:8571; }","duration":"316.594424ms","start":"2026-04-22T06:21:02.409364Z","end":"2026-04-22T06:21:02.725958Z","steps":["trace[1385876655] 'range keys from in-memory index tree' (duration: 316.044362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:21:02.726094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:21:02.409347Z","time spent":"316.714346ms","remote":"127.0.0.1:50120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6539,"request content":"key:\"/registry/pods/openstack/keepalived-bwsp2\" "} {"level":"info","ts":"2026-04-22T06:21:03.750202Z","caller":"traceutil/trace.go:171","msg":"trace[1764352017] transaction","detail":"{read_only:false; response_revision:8583; number_of_response:1; }","duration":"223.320779ms","start":"2026-04-22T06:21:03.526856Z","end":"2026-04-22T06:21:03.750177Z","steps":["trace[1764352017] 'process raft request' (duration: 223.279218ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:21:03.750285Z","caller":"traceutil/trace.go:171","msg":"trace[474806899] transaction","detail":"{read_only:false; response_revision:8582; number_of_response:1; }","duration":"225.060799ms","start":"2026-04-22T06:21:03.525174Z","end":"2026-04-22T06:21:03.750235Z","steps":["trace[474806899] 'process raft request' (duration: 224.810704ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:22:00.757699Z","caller":"traceutil/trace.go:171","msg":"trace[2048999334] transaction","detail":"{read_only:false; response_revision:9234; number_of_response:1; }","duration":"194.270993ms","start":"2026-04-22T06:22:00.563403Z","end":"2026-04-22T06:22:00.757674Z","steps":["trace[2048999334] 'process raft request' (duration: 194.094169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:22:11.121454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.314383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:22:11.121813Z","caller":"traceutil/trace.go:171","msg":"trace[180905268] transaction","detail":"{read_only:false; response_revision:9304; number_of_response:1; }","duration":"565.43467ms","start":"2026-04-22T06:22:10.556339Z","end":"2026-04-22T06:22:11.121774Z","steps":["trace[180905268] 'process raft request' (duration: 285.713958ms)","trace[180905268] 'compare' (duration: 279.036987ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:22:11.122012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:22:10.556316Z","time spent":"565.602584ms","remote":"127.0.0.1:50202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T06:22:11.122032Z","caller":"traceutil/trace.go:171","msg":"trace[1323336902] transaction","detail":"{read_only:false; response_revision:9305; number_of_response:1; }","duration":"491.359678ms","start":"2026-04-22T06:22:10.630629Z","end":"2026-04-22T06:22:11.121988Z","steps":["trace[1323336902] 'process raft request' (duration: 490.936939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:22:11.122235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:22:10.630607Z","time spent":"491.516391ms","remote":"127.0.0.1:50014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":116,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T06:22:11.122312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.105106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:22:11.122421Z","caller":"traceutil/trace.go:171","msg":"trace[377851185] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:9305; }","duration":"489.2599ms","start":"2026-04-22T06:22:10.633132Z","end":"2026-04-22T06:22:11.122391Z","steps":["trace[377851185] 'agreement among raft nodes before linearized reading' (duration: 488.966934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:22:11.122486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:22:10.633113Z","time spent":"489.358392ms","remote":"127.0.0.1:34776","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/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-04-22T06:22:11.121682Z","caller":"traceutil/trace.go:171","msg":"trace[71239202] linearizableReadLoop","detail":"{readStateIndex:9723; appliedIndex:9721; }","duration":"488.488003ms","start":"2026-04-22T06:22:10.633177Z","end":"2026-04-22T06:22:11.121665Z","steps":["trace[71239202] 'read index received' (duration: 208.893423ms)","trace[71239202] 'applied index is now lower than readState.Index' (duration: 279.59364ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:22:11.12344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.407273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-22T06:22:11.123549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.900782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:22:11.123634Z","caller":"traceutil/trace.go:171","msg":"trace[964400889] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:9305; }","duration":"177.090216ms","start":"2026-04-22T06:22:10.946524Z","end":"2026-04-22T06:22:11.123614Z","steps":["trace[964400889] 'agreement among raft nodes before linearized reading' (duration: 176.850431ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:22:11.123566Z","caller":"traceutil/trace.go:171","msg":"trace[398987676] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9305; }","duration":"321.553116ms","start":"2026-04-22T06:22:10.801977Z","end":"2026-04-22T06:22:11.12353Z","steps":["trace[398987676] 'agreement among raft nodes before linearized reading' (duration: 321.318351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:22:11.123734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T06:22:10.801957Z","time spent":"321.758101ms","remote":"127.0.0.1:50202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T06:22:11.123442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.375187ms","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-04-22T06:22:11.123815Z","caller":"traceutil/trace.go:171","msg":"trace[2116575780] 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:9305; }","duration":"163.782526ms","start":"2026-04-22T06:22:10.960011Z","end":"2026-04-22T06:22:11.123794Z","steps":["trace[2116575780] 'agreement among raft nodes before linearized reading' (duration: 163.308886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:22:11.385372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.207644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T06:22:11.385502Z","caller":"traceutil/trace.go:171","msg":"trace[1461510030] linearizableReadLoop","detail":"{readStateIndex:9726; appliedIndex:9725; }","duration":"157.76395ms","start":"2026-04-22T06:22:11.227719Z","end":"2026-04-22T06:22:11.385483Z","steps":["trace[1461510030] 'read index received' (duration: 13.330171ms)","trace[1461510030] 'applied index is now lower than readState.Index' (duration: 144.432259ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T06:22:11.385525Z","caller":"traceutil/trace.go:171","msg":"trace[1547739198] transaction","detail":"{read_only:false; response_revision:9308; number_of_response:1; }","duration":"200.447492ms","start":"2026-04-22T06:22:11.185049Z","end":"2026-04-22T06:22:11.385496Z","steps":["trace[1547739198] 'process raft request' (duration: 56.038734ms)","trace[1547739198] 'compare' (duration: 144.072251ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T06:22:11.385786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.033446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bwsp2\" ","response":"range_response_count:1 size:6516"} {"level":"info","ts":"2026-04-22T06:22:11.38583Z","caller":"traceutil/trace.go:171","msg":"trace[1045328294] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bwsp2; range_end:; response_count:1; response_revision:9308; }","duration":"158.114437ms","start":"2026-04-22T06:22:11.227704Z","end":"2026-04-22T06:22:11.385818Z","steps":["trace[1045328294] 'agreement among raft nodes before linearized reading' (duration: 157.829741ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:22:37.341989Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6645} {"level":"info","ts":"2026-04-22T06:22:37.451183Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6645,"took":"107.662071ms","hash":1131273253,"current-db-size-bytes":60510208,"current-db-size":"60 MB","current-db-size-in-use-bytes":26861568,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-22T06:22:37.451262Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1131273253,"revision":6645,"compact-revision":3290} {"level":"info","ts":"2026-04-22T06:22:42.205272Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"66f7b57d56ad567d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-22T06:22:42.211399Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-22T06:22:42.211522Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-04-22T06:24:28.040217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.665685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T06:24:28.040312Z","caller":"traceutil/trace.go:171","msg":"trace[609157498] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:10589; }","duration":"215.818209ms","start":"2026-04-22T06:24:27.824475Z","end":"2026-04-22T06:24:28.040293Z","steps":["trace[609157498] 'count revisions from in-memory index tree' (duration: 215.513322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:24:28.040286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.359202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-22T06:24:28.040371Z","caller":"traceutil/trace.go:171","msg":"trace[2085460542] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10589; }","duration":"189.486655ms","start":"2026-04-22T06:24:27.850861Z","end":"2026-04-22T06:24:28.040347Z","steps":["trace[2085460542] 'range keys from in-memory index tree' (duration: 189.000364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T06:24:28.040452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.508447ms","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-04-22T06:24:28.040527Z","caller":"traceutil/trace.go:171","msg":"trace[320002274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10589; }","duration":"158.615679ms","start":"2026-04-22T06:24:27.881894Z","end":"2026-04-22T06:24:28.040509Z","steps":["trace[320002274] 'range keys from in-memory index tree' (duration: 158.004495ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:24:47.774972Z","caller":"traceutil/trace.go:171","msg":"trace[1666871423] transaction","detail":"{read_only:false; response_revision:10769; number_of_response:1; }","duration":"130.572875ms","start":"2026-04-22T06:24:47.644372Z","end":"2026-04-22T06:24:47.774945Z","steps":["trace[1666871423] 'process raft request' (duration: 130.226768ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:24:47.775517Z","caller":"traceutil/trace.go:171","msg":"trace[1023265747] transaction","detail":"{read_only:false; response_revision:10770; number_of_response:1; }","duration":"130.500664ms","start":"2026-04-22T06:24:47.64496Z","end":"2026-04-22T06:24:47.775461Z","steps":["trace[1023265747] 'process raft request' (duration: 130.358361ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T06:27:37.349173Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9533} {"level":"info","ts":"2026-04-22T06:27:37.434407Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9533,"took":"82.194993ms","hash":135191439,"current-db-size-bytes":60510208,"current-db-size":"60 MB","current-db-size-in-use-bytes":25231360,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-22T06:27:37.434568Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":135191439,"revision":9533,"compact-revision":6645}