{"level":"warn","ts":"2026-10-03T22:08:24.661437Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-03T22:08:24.661556Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.206: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://162.253.55.206:2380","--initial-cluster=instance=https://162.253.55.206:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.206:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.206: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-10-03T22:08:24.661658Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-03T22:08:24.661669Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.206:2380"]} {"level":"info","ts":"2026-10-03T22:08:24.661688Z","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-10-03T22:08:24.662312Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"]} {"level":"info","ts":"2026-10-03T22:08:24.662387Z","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://162.253.55.206:2380"],"listen-peer-urls":["https://162.253.55.206:2380"],"advertise-client-urls":["https://162.253.55.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.206: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-10-03T22:08:24.665712Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.060071ms"} {"level":"info","ts":"2026-10-03T22:08:24.669408Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cfc9fec265e3384a","cluster-id":"9866fe2b0d49ed88"} {"level":"info","ts":"2026-10-03T22:08:24.669473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=()"} {"level":"info","ts":"2026-10-03T22:08:24.669489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became follower at term 0"} {"level":"info","ts":"2026-10-03T22:08:24.669498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cfc9fec265e3384a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-03T22:08:24.669506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became follower at term 1"} {"level":"info","ts":"2026-10-03T22:08:24.669539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=(14972778547056425034)"} {"level":"warn","ts":"2026-10-03T22:08:24.676073Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-03T22:08:24.678918Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-03T22:08:24.680276Z","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-10-03T22:08:24.682456Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cfc9fec265e3384a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-03T22:08:24.682811Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cfc9fec265e3384a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-03T22:08:24.682838Z","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-10-03T22:08:24.682963Z","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-10-03T22:08:24.682981Z","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-10-03T22:08:24.685234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=(14972778547056425034)"} {"level":"info","ts":"2026-10-03T22:08:24.685418Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9866fe2b0d49ed88","local-member-id":"cfc9fec265e3384a","added-peer-id":"cfc9fec265e3384a","added-peer-peer-urls":["https://162.253.55.206:2380"]} {"level":"info","ts":"2026-10-03T22:08:24.689256Z","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-10-03T22:08:24.689456Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.206:2380"} {"level":"info","ts":"2026-10-03T22:08:24.689594Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.206:2380"} {"level":"info","ts":"2026-10-03T22:08:24.689775Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cfc9fec265e3384a","initial-advertise-peer-urls":["https://162.253.55.206:2380"],"listen-peer-urls":["https://162.253.55.206:2380"],"advertise-client-urls":["https://162.253.55.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-03T22:08:24.689828Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-03T22:08:25.070209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a is starting a new election at term 1"} {"level":"info","ts":"2026-10-03T22:08:25.070303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became pre-candidate at term 1"} {"level":"info","ts":"2026-10-03T22:08:25.070352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a received MsgPreVoteResp from cfc9fec265e3384a at term 1"} {"level":"info","ts":"2026-10-03T22:08:25.070375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became candidate at term 2"} {"level":"info","ts":"2026-10-03T22:08:25.070399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a received MsgVoteResp from cfc9fec265e3384a at term 2"} {"level":"info","ts":"2026-10-03T22:08:25.07042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became leader at term 2"} {"level":"info","ts":"2026-10-03T22:08:25.07044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cfc9fec265e3384a elected leader cfc9fec265e3384a at term 2"} {"level":"info","ts":"2026-10-03T22:08:25.078276Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T22:08:25.080591Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-03T22:08:25.080599Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cfc9fec265e3384a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.206:2379]}","request-path":"/0/members/cfc9fec265e3384a/attributes","cluster-id":"9866fe2b0d49ed88","publish-timeout":"7s"} {"level":"info","ts":"2026-10-03T22:08:25.08069Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-03T22:08:25.081055Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-03T22:08:25.081149Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-03T22:08:25.082466Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-03T22:08:25.084425Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9866fe2b0d49ed88","local-member-id":"cfc9fec265e3384a","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T22:08:25.084647Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T22:08:25.084701Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T22:08:25.090645Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.206:2379"} {"level":"warn","ts":"2026-10-03T22:08:45.106616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.7545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T22:08:45.106771Z","caller":"traceutil/trace.go:171","msg":"trace[369875647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:423; }","duration":"156.017986ms","start":"2026-10-03T22:08:44.950731Z","end":"2026-10-03T22:08:45.106749Z","steps":["trace[369875647] 'range keys from in-memory index tree' (duration: 155.595815ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:09:09.012177Z","caller":"traceutil/trace.go:171","msg":"trace[375833632] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"124.72408ms","start":"2026-10-03T22:09:08.887405Z","end":"2026-10-03T22:09:09.012129Z","steps":["trace[375833632] 'read index received' (duration: 31.563858ms)","trace[375833632] 'applied index is now lower than readState.Index' (duration: 93.158612ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:09:09.012676Z","caller":"traceutil/trace.go:171","msg":"trace[1384456508] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"238.657541ms","start":"2026-10-03T22:09:08.773993Z","end":"2026-10-03T22:09:09.012651Z","steps":["trace[1384456508] 'process raft request' (duration: 144.901482ms)","trace[1384456508] 'compare' (duration: 93.028699ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:09:09.013095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.641746ms","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-10-03T22:09:09.013146Z","caller":"traceutil/trace.go:171","msg":"trace[2018839289] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"125.803211ms","start":"2026-10-03T22:09:08.887328Z","end":"2026-10-03T22:09:09.013131Z","steps":["trace[2018839289] 'agreement among raft nodes before linearized reading' (duration: 124.963228ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:09:09.116371Z","caller":"traceutil/trace.go:171","msg":"trace[1584385845] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"224.473926ms","start":"2026-10-03T22:09:08.891848Z","end":"2026-10-03T22:09:09.116322Z","steps":["trace[1584385845] 'process raft request' (duration: 217.674557ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:09:09.180048Z","caller":"traceutil/trace.go:171","msg":"trace[145884840] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"162.228425ms","start":"2026-10-03T22:09:09.017789Z","end":"2026-10-03T22:09:09.180017Z","steps":["trace[145884840] 'process raft request' (duration: 148.843362ms)","trace[145884840] 'compare' (duration: 13.248989ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:09:09.68637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.463207ms","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-10-03T22:09:09.686466Z","caller":"traceutil/trace.go:171","msg":"trace[2105610901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:637; }","duration":"334.598771ms","start":"2026-10-03T22:09:09.351844Z","end":"2026-10-03T22:09:09.686443Z","steps":["trace[2105610901] 'range keys from in-memory index tree' (duration: 334.264642ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:09:09.686533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:09:09.35183Z","time spent":"334.692213ms","remote":"127.0.0.1:54974","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-10-03T22:09:09.686802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.728587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-03T22:09:09.686839Z","caller":"traceutil/trace.go:171","msg":"trace[306745796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:637; }","duration":"328.817909ms","start":"2026-10-03T22:09:09.358009Z","end":"2026-10-03T22:09:09.686827Z","steps":["trace[306745796] 'range keys from in-memory index tree' (duration: 328.576243ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:09:09.686864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:09:09.357979Z","time spent":"328.877751ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-03T22:10:44.378404Z","caller":"traceutil/trace.go:171","msg":"trace[278392761] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"120.299103ms","start":"2026-10-03T22:10:44.258073Z","end":"2026-10-03T22:10:44.378372Z","steps":["trace[278392761] 'process raft request' (duration: 120.106338ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:10:44.391481Z","caller":"traceutil/trace.go:171","msg":"trace[1160586083] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"133.319548ms","start":"2026-10-03T22:10:44.258146Z","end":"2026-10-03T22:10:44.391465Z","steps":["trace[1160586083] 'process raft request' (duration: 133.152483ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:10:44.391669Z","caller":"traceutil/trace.go:171","msg":"trace[307173430] linearizableReadLoop","detail":"{readStateIndex:1947; appliedIndex:1945; }","duration":"110.387116ms","start":"2026-10-03T22:10:44.281246Z","end":"2026-10-03T22:10:44.391633Z","steps":["trace[307173430] 'read index received' (duration: 96.9268ms)","trace[307173430] 'applied index is now lower than readState.Index' (duration: 13.458466ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:10:44.391972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.713465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-03T22:10:44.392044Z","caller":"traceutil/trace.go:171","msg":"trace[959374761] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1875; }","duration":"110.865399ms","start":"2026-10-03T22:10:44.28116Z","end":"2026-10-03T22:10:44.392026Z","steps":["trace[959374761] 'agreement among raft nodes before linearized reading' (duration: 110.629523ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:10:44.569404Z","caller":"traceutil/trace.go:171","msg":"trace[908297108] linearizableReadLoop","detail":"{readStateIndex:1949; appliedIndex:1948; }","duration":"128.313586ms","start":"2026-10-03T22:10:44.441067Z","end":"2026-10-03T22:10:44.569381Z","steps":["trace[908297108] 'read index received' (duration: 79.068481ms)","trace[908297108] 'applied index is now lower than readState.Index' (duration: 49.244025ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:10:44.569629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.540092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c097fa23-ed56-4ccc-8cd5-13c7133738cd\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-10-03T22:10:44.569612Z","caller":"traceutil/trace.go:171","msg":"trace[1066941139] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"170.775463ms","start":"2026-10-03T22:10:44.398729Z","end":"2026-10-03T22:10:44.569504Z","steps":["trace[1066941139] 'process raft request' (duration: 121.505326ms)","trace[1066941139] 'compare' (duration: 49.03367ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:10:44.569695Z","caller":"traceutil/trace.go:171","msg":"trace[1987010870] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c097fa23-ed56-4ccc-8cd5-13c7133738cd; range_end:; response_count:1; response_revision:1876; }","duration":"128.676707ms","start":"2026-10-03T22:10:44.440998Z","end":"2026-10-03T22:10:44.569674Z","steps":["trace[1987010870] 'agreement among raft nodes before linearized reading' (duration: 128.519522ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:11:34.265071Z","caller":"traceutil/trace.go:171","msg":"trace[397001796] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"116.51937ms","start":"2026-10-03T22:11:34.148522Z","end":"2026-10-03T22:11:34.265041Z","steps":["trace[397001796] 'process raft request' (duration: 116.359555ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:13:05.370416Z","caller":"traceutil/trace.go:171","msg":"trace[1642201777] transaction","detail":"{read_only:false; response_revision:2884; number_of_response:1; }","duration":"100.292638ms","start":"2026-10-03T22:13:05.270097Z","end":"2026-10-03T22:13:05.370389Z","steps":["trace[1642201777] 'process raft request' (duration: 100.128984ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:17:59.294401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.617023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T22:17:59.294548Z","caller":"traceutil/trace.go:171","msg":"trace[1130188135] linearizableReadLoop","detail":"{readStateIndex:5569; appliedIndex:5568; }","duration":"197.441425ms","start":"2026-10-03T22:17:59.097085Z","end":"2026-10-03T22:17:59.294526Z","steps":["trace[1130188135] 'read index received' (duration: 68.944108ms)","trace[1130188135] 'applied index is now lower than readState.Index' (duration: 128.495387ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:17:59.294595Z","caller":"traceutil/trace.go:171","msg":"trace[130912835] transaction","detail":"{read_only:false; response_revision:5361; number_of_response:1; }","duration":"209.977052ms","start":"2026-10-03T22:17:59.084579Z","end":"2026-10-03T22:17:59.294556Z","steps":["trace[130912835] 'process raft request' (duration: 81.424644ms)","trace[130912835] 'compare' (duration: 127.448517ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:17:59.294783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.699362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-de97ac74-a15f-4216-a445-4741d53ffa5b\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-03T22:17:59.294838Z","caller":"traceutil/trace.go:171","msg":"trace[1357243055] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-de97ac74-a15f-4216-a445-4741d53ffa5b; range_end:; response_count:1; response_revision:5361; }","duration":"197.790484ms","start":"2026-10-03T22:17:59.097031Z","end":"2026-10-03T22:17:59.294821Z","steps":["trace[1357243055] 'agreement among raft nodes before linearized reading' (duration: 197.605549ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:17:59.780287Z","caller":"traceutil/trace.go:171","msg":"trace[1415457138] transaction","detail":"{read_only:false; response_revision:5363; number_of_response:1; }","duration":"111.790785ms","start":"2026-10-03T22:17:59.668435Z","end":"2026-10-03T22:17:59.780226Z","steps":["trace[1415457138] 'process raft request' (duration: 111.565018ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:01.997162Z","caller":"traceutil/trace.go:171","msg":"trace[243680061] transaction","detail":"{read_only:false; response_revision:5409; number_of_response:1; }","duration":"134.490063ms","start":"2026-10-03T22:18:01.862621Z","end":"2026-10-03T22:18:01.997111Z","steps":["trace[243680061] 'process raft request' (duration: 57.897153ms)","trace[243680061] 'compare' (duration: 76.199559ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:18:01.997236Z","caller":"traceutil/trace.go:171","msg":"trace[1171562230] transaction","detail":"{read_only:false; response_revision:5410; number_of_response:1; }","duration":"133.621029ms","start":"2026-10-03T22:18:01.863592Z","end":"2026-10-03T22:18:01.997213Z","steps":["trace[1171562230] 'process raft request' (duration: 133.279869ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:13.679775Z","caller":"traceutil/trace.go:171","msg":"trace[901104887] transaction","detail":"{read_only:false; response_revision:5608; number_of_response:1; }","duration":"299.207092ms","start":"2026-10-03T22:18:13.380532Z","end":"2026-10-03T22:18:13.679739Z","steps":["trace[901104887] 'process raft request' (duration: 298.948035ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:21.880724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.983156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls-4q8xz\" ","response":"range_response_count:1 size:2596"} {"level":"info","ts":"2026-10-03T22:18:21.880841Z","caller":"traceutil/trace.go:171","msg":"trace[678364815] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls-4q8xz; range_end:; response_count:1; response_revision:5806; }","duration":"109.1231ms","start":"2026-10-03T22:18:21.771697Z","end":"2026-10-03T22:18:21.88082Z","steps":["trace[678364815] 'range keys from in-memory index tree' (duration: 108.845722ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:21.886255Z","caller":"traceutil/trace.go:171","msg":"trace[514288400] linearizableReadLoop","detail":"{readStateIndex:6025; appliedIndex:6024; }","duration":"112.046961ms","start":"2026-10-03T22:18:21.774189Z","end":"2026-10-03T22:18:21.886236Z","steps":["trace[514288400] 'read index received' (duration: 111.915878ms)","trace[514288400] 'applied index is now lower than readState.Index' (duration: 126.183µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:18:21.886284Z","caller":"traceutil/trace.go:171","msg":"trace[706877320] transaction","detail":"{read_only:false; response_revision:5807; number_of_response:1; }","duration":"114.404557ms","start":"2026-10-03T22:18:21.771847Z","end":"2026-10-03T22:18:21.886252Z","steps":["trace[706877320] 'process raft request' (duration: 114.264263ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:21.886513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.328459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:111887"} {"level":"info","ts":"2026-10-03T22:18:21.886546Z","caller":"traceutil/trace.go:171","msg":"trace[1224929171] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:5807; }","duration":"112.396351ms","start":"2026-10-03T22:18:21.774142Z","end":"2026-10-03T22:18:21.886538Z","steps":["trace[1224929171] 'agreement among raft nodes before linearized reading' (duration: 112.173344ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:22.132863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.413391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-10-03T22:18:22.132981Z","caller":"traceutil/trace.go:171","msg":"trace[1961189617] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5809; }","duration":"151.634318ms","start":"2026-10-03T22:18:21.981325Z","end":"2026-10-03T22:18:22.132959Z","steps":["trace[1961189617] 'range keys from in-memory index tree' (duration: 151.221175ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:22.132988Z","caller":"traceutil/trace.go:171","msg":"trace[1983776232] linearizableReadLoop","detail":"{readStateIndex:6028; appliedIndex:6027; }","duration":"128.226749ms","start":"2026-10-03T22:18:22.00472Z","end":"2026-10-03T22:18:22.132947Z","steps":["trace[1983776232] 'read index received' (duration: 111.846796ms)","trace[1983776232] 'applied index is now lower than readState.Index' (duration: 16.378073ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:18:22.133041Z","caller":"traceutil/trace.go:171","msg":"trace[334408055] transaction","detail":"{read_only:false; response_revision:5810; number_of_response:1; }","duration":"151.121833ms","start":"2026-10-03T22:18:21.981849Z","end":"2026-10-03T22:18:22.132971Z","steps":["trace[334408055] 'process raft request' (duration: 134.651828ms)","trace[334408055] 'compare' (duration: 16.086045ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:18:22.133163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.652515ms","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-10-03T22:18:22.133226Z","caller":"traceutil/trace.go:171","msg":"trace[1931336341] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:5810; }","duration":"118.735677ms","start":"2026-10-03T22:18:22.014468Z","end":"2026-10-03T22:18:22.133204Z","steps":["trace[1931336341] 'agreement among raft nodes before linearized reading' (duration: 118.632304ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:22.133281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.538569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-10-03T22:18:22.133394Z","caller":"traceutil/trace.go:171","msg":"trace[2073295169] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5810; }","duration":"128.714343ms","start":"2026-10-03T22:18:22.004651Z","end":"2026-10-03T22:18:22.133365Z","steps":["trace[2073295169] 'agreement among raft nodes before linearized reading' (duration: 128.411785ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:22.376726Z","caller":"traceutil/trace.go:171","msg":"trace[231763249] linearizableReadLoop","detail":"{readStateIndex:6030; appliedIndex:6029; }","duration":"196.068188ms","start":"2026-10-03T22:18:22.180622Z","end":"2026-10-03T22:18:22.376691Z","steps":["trace[231763249] 'read index received' (duration: 195.709648ms)","trace[231763249] 'applied index is now lower than readState.Index' (duration: 356.86µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:18:22.376841Z","caller":"traceutil/trace.go:171","msg":"trace[256680596] transaction","detail":"{read_only:false; response_revision:5812; number_of_response:1; }","duration":"234.303296ms","start":"2026-10-03T22:18:22.142505Z","end":"2026-10-03T22:18:22.376808Z","steps":["trace[256680596] 'process raft request' (duration: 233.877214ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:22.377097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.230611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-10-03T22:18:22.377151Z","caller":"traceutil/trace.go:171","msg":"trace[1892327293] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5812; }","duration":"235.355185ms","start":"2026-10-03T22:18:22.141779Z","end":"2026-10-03T22:18:22.377134Z","steps":["trace[1892327293] 'agreement among raft nodes before linearized reading' (duration: 235.098818ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:18:22.377171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.201255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-03T22:18:22.377194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.878899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-10-03T22:18:22.37729Z","caller":"traceutil/trace.go:171","msg":"trace[1727570508] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:5812; }","duration":"193.025124ms","start":"2026-10-03T22:18:22.184242Z","end":"2026-10-03T22:18:22.377267Z","steps":["trace[1727570508] 'agreement among raft nodes before linearized reading' (duration: 192.705555ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:22.377254Z","caller":"traceutil/trace.go:171","msg":"trace[1966637245] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5812; }","duration":"160.36264ms","start":"2026-10-03T22:18:22.216873Z","end":"2026-10-03T22:18:22.377236Z","steps":["trace[1966637245] 'agreement among raft nodes before linearized reading' (duration: 160.100222ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:18:25.117398Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2992} {"level":"info","ts":"2026-10-03T22:18:25.193302Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2992,"took":"74.06425ms","hash":591947684,"current-db-size-bytes":55574528,"current-db-size":"56 MB","current-db-size-in-use-bytes":32141312,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-03T22:18:25.193368Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":591947684,"revision":2992,"compact-revision":-1} {"level":"info","ts":"2026-10-03T22:23:25.124342Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5848} {"level":"info","ts":"2026-10-03T22:23:25.218836Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5848,"took":"91.88773ms","hash":1315170676,"current-db-size-bytes":55574528,"current-db-size":"56 MB","current-db-size-in-use-bytes":26468352,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-03T22:23:25.218965Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1315170676,"revision":5848,"compact-revision":2992} {"level":"info","ts":"2026-10-03T22:24:30.152248Z","caller":"traceutil/trace.go:171","msg":"trace[1192765000] transaction","detail":"{read_only:false; response_revision:8624; number_of_response:1; }","duration":"128.257485ms","start":"2026-10-03T22:24:30.023957Z","end":"2026-10-03T22:24:30.152215Z","steps":["trace[1192765000] 'process raft request' (duration: 128.032859ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:24:30.460698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.211442ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T22:24:30.460863Z","caller":"traceutil/trace.go:171","msg":"trace[2045399880] linearizableReadLoop","detail":"{readStateIndex:8935; appliedIndex:8934; }","duration":"253.287352ms","start":"2026-10-03T22:24:30.207552Z","end":"2026-10-03T22:24:30.460839Z","steps":["trace[2045399880] 'read index received' (duration: 102.689079ms)","trace[2045399880] 'applied index is now lower than readState.Index' (duration: 150.596823ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:24:30.46091Z","caller":"traceutil/trace.go:171","msg":"trace[68728705] transaction","detail":"{read_only:false; response_revision:8625; number_of_response:1; }","duration":"400.09959ms","start":"2026-10-03T22:24:30.060782Z","end":"2026-10-03T22:24:30.460882Z","steps":["trace[68728705] 'process raft request' (duration: 249.576619ms)","trace[68728705] 'compare' (duration: 150.040898ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:24:30.461015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:24:30.060735Z","time spent":"400.223573ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:24:30.461036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.482397ms","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-10-03T22:24:30.461074Z","caller":"traceutil/trace.go:171","msg":"trace[1713123667] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8625; }","duration":"253.561159ms","start":"2026-10-03T22:24:30.207501Z","end":"2026-10-03T22:24:30.461062Z","steps":["trace[1713123667] 'agreement among raft nodes before linearized reading' (duration: 253.389565ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:24:42.72205Z","caller":"traceutil/trace.go:171","msg":"trace[290891340] transaction","detail":"{read_only:false; response_revision:8688; number_of_response:1; }","duration":"148.007692ms","start":"2026-10-03T22:24:42.57401Z","end":"2026-10-03T22:24:42.722018Z","steps":["trace[290891340] 'process raft request' (duration: 147.830637ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:24:42.727509Z","caller":"traceutil/trace.go:171","msg":"trace[826819466] transaction","detail":"{read_only:false; response_revision:8689; number_of_response:1; }","duration":"152.042923ms","start":"2026-10-03T22:24:42.575429Z","end":"2026-10-03T22:24:42.727472Z","steps":["trace[826819466] 'process raft request' (duration: 151.808916ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:24:45.709366Z","caller":"traceutil/trace.go:171","msg":"trace[1332799360] transaction","detail":"{read_only:false; response_revision:8705; number_of_response:1; }","duration":"118.505585ms","start":"2026-10-03T22:24:45.590815Z","end":"2026-10-03T22:24:45.709321Z","steps":["trace[1332799360] 'process raft request' (duration: 118.281338ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:25:27.159431Z","caller":"traceutil/trace.go:171","msg":"trace[1839857096] linearizableReadLoop","detail":"{readStateIndex:9341; appliedIndex:9340; }","duration":"166.035027ms","start":"2026-10-03T22:25:26.993366Z","end":"2026-10-03T22:25:27.159401Z","steps":["trace[1839857096] 'read index received' (duration: 146.368637ms)","trace[1839857096] 'applied index is now lower than readState.Index' (duration: 19.66512ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:25:27.159541Z","caller":"traceutil/trace.go:171","msg":"trace[1868519668] transaction","detail":"{read_only:false; response_revision:9014; number_of_response:1; }","duration":"186.266842ms","start":"2026-10-03T22:25:26.973237Z","end":"2026-10-03T22:25:27.159504Z","steps":["trace[1868519668] 'process raft request' (duration: 166.577832ms)","trace[1868519668] 'compare' (duration: 19.405423ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:25:27.159733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.345126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-03T22:25:27.159778Z","caller":"traceutil/trace.go:171","msg":"trace[1711913314] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9014; }","duration":"166.435828ms","start":"2026-10-03T22:25:26.993328Z","end":"2026-10-03T22:25:27.159764Z","steps":["trace[1711913314] 'agreement among raft nodes before linearized reading' (duration: 166.236762ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:25:27.159778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.948604ms","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-10-03T22:25:27.159814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.459446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-03T22:25:27.159839Z","caller":"traceutil/trace.go:171","msg":"trace[947572945] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9014; }","duration":"131.055257ms","start":"2026-10-03T22:25:27.028769Z","end":"2026-10-03T22:25:27.159824Z","steps":["trace[947572945] 'agreement among raft nodes before linearized reading' (duration: 130.840851ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:25:27.159851Z","caller":"traceutil/trace.go:171","msg":"trace[807564728] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9014; }","duration":"128.540319ms","start":"2026-10-03T22:25:27.031295Z","end":"2026-10-03T22:25:27.159835Z","steps":["trace[807564728] 'agreement among raft nodes before linearized reading' (duration: 128.434146ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:25:27.159948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.393882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-03T22:25:27.160064Z","caller":"traceutil/trace.go:171","msg":"trace[517028858] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9014; }","duration":"121.536076ms","start":"2026-10-03T22:25:27.038513Z","end":"2026-10-03T22:25:27.160049Z","steps":["trace[517028858] 'agreement among raft nodes before linearized reading' (duration: 121.158656ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:25:38.952612Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024e6.wal"} {"level":"info","ts":"2026-10-03T22:26:34.451469Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cfc9fec265e3384a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-03T22:26:34.458074Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-03T22:26:34.458205Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-03T22:28:25.133078Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8048} {"level":"info","ts":"2026-10-03T22:28:25.204474Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8048,"took":"68.542048ms","hash":926151848,"current-db-size-bytes":55574528,"current-db-size":"56 MB","current-db-size-in-use-bytes":28602368,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-03T22:28:25.204555Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":926151848,"revision":8048,"compact-revision":5848} {"level":"info","ts":"2026-10-03T22:30:30.311793Z","caller":"traceutil/trace.go:171","msg":"trace[149837462] transaction","detail":"{read_only:false; response_revision:11664; number_of_response:1; }","duration":"140.169001ms","start":"2026-10-03T22:30:30.171571Z","end":"2026-10-03T22:30:30.31174Z","steps":["trace[149837462] 'process raft request' (duration: 139.918045ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:30:31.136198Z","caller":"traceutil/trace.go:171","msg":"trace[1852333488] linearizableReadLoop","detail":"{readStateIndex:12074; appliedIndex:12073; }","duration":"103.397244ms","start":"2026-10-03T22:30:31.032763Z","end":"2026-10-03T22:30:31.13616Z","steps":["trace[1852333488] 'read index received' (duration: 32.178132ms)","trace[1852333488] 'applied index is now lower than readState.Index' (duration: 71.217952ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:30:31.136391Z","caller":"traceutil/trace.go:171","msg":"trace[1859900327] transaction","detail":"{read_only:false; response_revision:11667; number_of_response:1; }","duration":"113.405707ms","start":"2026-10-03T22:30:31.022959Z","end":"2026-10-03T22:30:31.136365Z","steps":["trace[1859900327] 'process raft request' (duration: 42.094803ms)","trace[1859900327] 'compare' (duration: 70.923864ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:30:31.136434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.647661ms","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-10-03T22:30:31.136469Z","caller":"traceutil/trace.go:171","msg":"trace[1333878901] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11667; }","duration":"103.734293ms","start":"2026-10-03T22:30:31.032723Z","end":"2026-10-03T22:30:31.136457Z","steps":["trace[1333878901] 'agreement among raft nodes before linearized reading' (duration: 103.562878ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:33:25.146905Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10509} {"level":"info","ts":"2026-10-03T22:33:25.236456Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10509,"took":"86.211139ms","hash":4069907563,"current-db-size-bytes":55574528,"current-db-size":"56 MB","current-db-size-in-use-bytes":28143616,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-03T22:33:25.236546Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4069907563,"revision":10509,"compact-revision":8048} {"level":"info","ts":"2026-10-03T22:36:46.740086Z","caller":"traceutil/trace.go:171","msg":"trace[639424339] transaction","detail":"{read_only:false; response_revision:14194; number_of_response:1; }","duration":"132.542268ms","start":"2026-10-03T22:36:46.607486Z","end":"2026-10-03T22:36:46.740028Z","steps":["trace[639424339] 'process raft request' (duration: 132.307162ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:37:03.830816Z","caller":"traceutil/trace.go:171","msg":"trace[1619869631] linearizableReadLoop","detail":"{readStateIndex:14795; appliedIndex:14794; }","duration":"242.04931ms","start":"2026-10-03T22:37:03.588727Z","end":"2026-10-03T22:37:03.830776Z","steps":["trace[1619869631] 'read index received' (duration: 241.798223ms)","trace[1619869631] 'applied index is now lower than readState.Index' (duration: 249.967µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:37:03.830893Z","caller":"traceutil/trace.go:171","msg":"trace[2053467688] transaction","detail":"{read_only:false; response_revision:14296; number_of_response:1; }","duration":"243.528981ms","start":"2026-10-03T22:37:03.587311Z","end":"2026-10-03T22:37:03.83084Z","steps":["trace[2053467688] 'process raft request' (duration: 243.245843ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:37:03.831166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.41348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-03T22:37:03.83122Z","caller":"traceutil/trace.go:171","msg":"trace[2044325463] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14296; }","duration":"242.524514ms","start":"2026-10-03T22:37:03.588677Z","end":"2026-10-03T22:37:03.831201Z","steps":["trace[2044325463] 'agreement among raft nodes before linearized reading' (duration: 242.303517ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:37:03.993173Z","caller":"traceutil/trace.go:171","msg":"trace[602575792] transaction","detail":"{read_only:false; response_revision:14297; number_of_response:1; }","duration":"401.525781ms","start":"2026-10-03T22:37:03.591596Z","end":"2026-10-03T22:37:03.993122Z","steps":["trace[602575792] 'process raft request' (duration: 401.006217ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:37:03.993391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:37:03.591572Z","time spent":"401.712436ms","remote":"127.0.0.1:54896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12483,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:37:03.995911Z","caller":"traceutil/trace.go:171","msg":"trace[1193475758] linearizableReadLoop","detail":"{readStateIndex:14797; appliedIndex:14795; }","duration":"164.937202ms","start":"2026-10-03T22:37:03.830945Z","end":"2026-10-03T22:37:03.995882Z","steps":["trace[1193475758] 'read index received' (duration: 161.58714ms)","trace[1193475758] 'applied index is now lower than readState.Index' (duration: 3.348742ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:37:03.9962Z","caller":"traceutil/trace.go:171","msg":"trace[978954725] transaction","detail":"{read_only:false; response_revision:14298; number_of_response:1; }","duration":"158.850274ms","start":"2026-10-03T22:37:03.837241Z","end":"2026-10-03T22:37:03.996092Z","steps":["trace[978954725] 'process raft request' (duration: 158.559856ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:37:03.996413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.810774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-k27pm\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-03T22:37:03.996491Z","caller":"traceutil/trace.go:171","msg":"trace[1927678987] range","detail":"{range_begin:/registry/pods/openstack/keepalived-k27pm; range_end:; response_count:1; response_revision:14297; }","duration":"324.911066ms","start":"2026-10-03T22:37:03.671558Z","end":"2026-10-03T22:37:03.996469Z","steps":["trace[1927678987] 'agreement among raft nodes before linearized reading' (duration: 324.358941ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:37:03.996555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:37:03.671546Z","time spent":"324.995929ms","remote":"127.0.0.1:54896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6555,"request content":"key:\"/registry/pods/openstack/keepalived-k27pm\" "} {"level":"warn","ts":"2026-10-03T22:37:03.997183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.666898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-03T22:37:03.998546Z","caller":"traceutil/trace.go:171","msg":"trace[1441002430] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:14298; }","duration":"327.806357ms","start":"2026-10-03T22:37:03.670714Z","end":"2026-10-03T22:37:03.99852Z","steps":["trace[1441002430] 'agreement among raft nodes before linearized reading' (duration: 325.478943ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:37:03.99868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:37:03.670663Z","time spent":"327.992572ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":66,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-10-03T22:37:18.560896Z","caller":"traceutil/trace.go:171","msg":"trace[1461458647] transaction","detail":"{read_only:false; response_revision:14447; number_of_response:1; }","duration":"174.584948ms","start":"2026-10-03T22:37:18.386287Z","end":"2026-10-03T22:37:18.560872Z","steps":["trace[1461458647] 'process raft request' (duration: 174.448384ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:38:25.154532Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12830} {"level":"info","ts":"2026-10-03T22:38:25.23806Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12830,"took":"79.761681ms","hash":2948325981,"current-db-size-bytes":55574528,"current-db-size":"56 MB","current-db-size-in-use-bytes":30494720,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-03T22:38:25.238139Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2948325981,"revision":12830,"compact-revision":10509} {"level":"info","ts":"2026-10-03T22:43:25.199797Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15271} {"level":"info","ts":"2026-10-03T22:43:25.340506Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15271,"took":"136.097597ms","hash":2792370759,"current-db-size-bytes":55574528,"current-db-size":"56 MB","current-db-size-in-use-bytes":31801344,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-03T22:43:25.340591Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2792370759,"revision":15271,"compact-revision":12830} {"level":"warn","ts":"2026-10-03T22:44:32.962281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.377911ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T22:44:32.962649Z","caller":"traceutil/trace.go:171","msg":"trace[455549800] transaction","detail":"{read_only:false; response_revision:17953; number_of_response:1; }","duration":"189.438043ms","start":"2026-10-03T22:44:32.773142Z","end":"2026-10-03T22:44:32.96258Z","steps":["trace[455549800] 'process raft request' (duration: 36.63558ms)","trace[455549800] 'compare' (duration: 152.240508ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:44:32.963407Z","caller":"traceutil/trace.go:171","msg":"trace[10493630] transaction","detail":"{read_only:false; response_revision:17954; number_of_response:1; }","duration":"171.01125ms","start":"2026-10-03T22:44:32.792356Z","end":"2026-10-03T22:44:32.963367Z","steps":["trace[10493630] 'process raft request' (duration: 170.068395ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:44:38.911439Z","caller":"traceutil/trace.go:171","msg":"trace[1486616068] linearizableReadLoop","detail":"{readStateIndex:18613; appliedIndex:18612; }","duration":"403.218572ms","start":"2026-10-03T22:44:38.508189Z","end":"2026-10-03T22:44:38.911407Z","steps":["trace[1486616068] 'read index received' (duration: 403.046067ms)","trace[1486616068] 'applied index is now lower than readState.Index' (duration: 170.895µs)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:44:38.911606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.383836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T22:44:38.911649Z","caller":"traceutil/trace.go:171","msg":"trace[635693855] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:17989; }","duration":"403.51103ms","start":"2026-10-03T22:44:38.508126Z","end":"2026-10-03T22:44:38.911637Z","steps":["trace[635693855] 'agreement among raft nodes before linearized reading' (duration: 403.409447ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:44:38.91159Z","caller":"traceutil/trace.go:171","msg":"trace[1442174466] transaction","detail":"{read_only:false; response_revision:17989; number_of_response:1; }","duration":"413.410111ms","start":"2026-10-03T22:44:38.4981Z","end":"2026-10-03T22:44:38.91151Z","steps":["trace[1442174466] 'process raft request' (duration: 413.134873ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:44:38.91169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:44:38.508095Z","time spent":"403.583731ms","remote":"127.0.0.1:33890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-10-03T22:44:38.91229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:44:38.498074Z","time spent":"413.681817ms","remote":"127.0.0.1:54896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20878,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:44:39.115428Z","caller":"traceutil/trace.go:171","msg":"trace[522286312] linearizableReadLoop","detail":"{readStateIndex:18614; appliedIndex:18613; }","duration":"203.883188ms","start":"2026-10-03T22:44:38.911511Z","end":"2026-10-03T22:44:39.115394Z","steps":["trace[522286312] 'read index received' (duration: 145.064781ms)","trace[522286312] 'applied index is now lower than readState.Index' (duration: 58.817297ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:44:39.115536Z","caller":"traceutil/trace.go:171","msg":"trace[1993414905] transaction","detail":"{read_only:false; response_revision:17990; number_of_response:1; }","duration":"526.236991ms","start":"2026-10-03T22:44:38.589286Z","end":"2026-10-03T22:44:39.115523Z","steps":["trace[1993414905] 'process raft request' (duration: 467.287991ms)","trace[1993414905] 'compare' (duration: 58.457327ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:44:39.115644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:44:38.589255Z","time spent":"526.299073ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:44:39.115732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.619237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-03T22:44:39.115845Z","caller":"traceutil/trace.go:171","msg":"trace[1509509103] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17990; }","duration":"446.766761ms","start":"2026-10-03T22:44:38.669051Z","end":"2026-10-03T22:44:39.115818Z","steps":["trace[1509509103] 'agreement among raft nodes before linearized reading' (duration: 446.501564ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:44:39.115916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.970852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.206\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-10-03T22:44:39.115925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:44:38.669039Z","time spent":"446.871164ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-03T22:44:39.115944Z","caller":"traceutil/trace.go:171","msg":"trace[337641986] range","detail":"{range_begin:/registry/masterleases/162.253.55.206; range_end:; response_count:1; response_revision:17990; }","duration":"460.060384ms","start":"2026-10-03T22:44:38.655878Z","end":"2026-10-03T22:44:39.115938Z","steps":["trace[337641986] 'agreement among raft nodes before linearized reading' (duration: 459.91182ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:44:39.115962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:44:38.655827Z","time spent":"460.132116ms","remote":"127.0.0.1:54778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":164,"request content":"key:\"/registry/masterleases/162.253.55.206\" "} {"level":"warn","ts":"2026-10-03T22:44:39.116074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.963523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T22:44:39.116093Z","caller":"traceutil/trace.go:171","msg":"trace[878909860] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:17990; }","duration":"593.008315ms","start":"2026-10-03T22:44:38.52308Z","end":"2026-10-03T22:44:39.116088Z","steps":["trace[878909860] 'agreement among raft nodes before linearized reading' (duration: 592.972904ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:44:39.116105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:44:38.523064Z","time spent":"593.038366ms","remote":"127.0.0.1:55158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":30,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-10-03T22:44:39.116156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.081492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T22:44:39.116207Z","caller":"traceutil/trace.go:171","msg":"trace[1953349444] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:17990; }","duration":"420.157404ms","start":"2026-10-03T22:44:38.696033Z","end":"2026-10-03T22:44:39.11619Z","steps":["trace[1953349444] 'agreement among raft nodes before linearized reading' (duration: 420.081992ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:44:39.116252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:44:38.696022Z","time spent":"420.218176ms","remote":"127.0.0.1:33730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-10-03T22:44:39.135333Z","caller":"traceutil/trace.go:171","msg":"trace[780800395] transaction","detail":"{read_only:false; response_revision:17991; number_of_response:1; }","duration":"152.869965ms","start":"2026-10-03T22:44:38.982415Z","end":"2026-10-03T22:44:39.135285Z","steps":["trace[780800395] 'process raft request' (duration: 152.664499ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:44:39.135628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.582104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-03T22:44:39.135732Z","caller":"traceutil/trace.go:171","msg":"trace[595402071] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17991; }","duration":"127.843752ms","start":"2026-10-03T22:44:39.007863Z","end":"2026-10-03T22:44:39.135706Z","steps":["trace[595402071] 'agreement among raft nodes before linearized reading' (duration: 127.43086ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:45:31.567964Z","caller":"traceutil/trace.go:171","msg":"trace[1926012598] transaction","detail":"{read_only:false; response_revision:18622; number_of_response:1; }","duration":"108.874789ms","start":"2026-10-03T22:45:31.459048Z","end":"2026-10-03T22:45:31.567923Z","steps":["trace[1926012598] 'process raft request' (duration: 108.596491ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:47:11.126127Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cfc9fec265e3384a","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-03T22:47:11.135301Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-03T22:47:11.135474Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-03T22:48:25.208072Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17479} {"level":"info","ts":"2026-10-03T22:48:25.324526Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17479,"took":"111.83808ms","hash":1672499156,"current-db-size-bytes":55574528,"current-db-size":"56 MB","current-db-size-in-use-bytes":34672640,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-03T22:48:25.324638Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1672499156,"revision":17479,"compact-revision":15271} {"level":"info","ts":"2026-10-03T22:50:01.42113Z","caller":"traceutil/trace.go:171","msg":"trace[277487130] transaction","detail":"{read_only:false; response_revision:20527; number_of_response:1; }","duration":"139.326758ms","start":"2026-10-03T22:50:01.281763Z","end":"2026-10-03T22:50:01.42109Z","steps":["trace[277487130] 'process raft request' (duration: 139.108462ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:50:01.422785Z","caller":"traceutil/trace.go:171","msg":"trace[1058450720] linearizableReadLoop","detail":"{readStateIndex:21235; appliedIndex:21232; }","duration":"139.187564ms","start":"2026-10-03T22:50:01.283564Z","end":"2026-10-03T22:50:01.422752Z","steps":["trace[1058450720] 'read index received' (duration: 137.485857ms)","trace[1058450720] 'applied index is now lower than readState.Index' (duration: 1.700407ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:50:01.423081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.499802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-03T22:50:01.423132Z","caller":"traceutil/trace.go:171","msg":"trace[183050480] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20530; }","duration":"139.566964ms","start":"2026-10-03T22:50:01.283549Z","end":"2026-10-03T22:50:01.423116Z","steps":["trace[183050480] 'agreement among raft nodes before linearized reading' (duration: 139.361918ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:50:01.423715Z","caller":"traceutil/trace.go:171","msg":"trace[1798654726] transaction","detail":"{read_only:false; response_revision:20528; number_of_response:1; }","duration":"141.461296ms","start":"2026-10-03T22:50:01.282239Z","end":"2026-10-03T22:50:01.4237Z","steps":["trace[1798654726] 'process raft request' (duration: 140.239312ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:50:01.423833Z","caller":"traceutil/trace.go:171","msg":"trace[608441580] transaction","detail":"{read_only:false; response_revision:20529; number_of_response:1; }","duration":"141.109406ms","start":"2026-10-03T22:50:01.282677Z","end":"2026-10-03T22:50:01.423786Z","steps":["trace[608441580] 'process raft request' (duration: 139.969685ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:50:07.58592Z","caller":"traceutil/trace.go:171","msg":"trace[1097791841] linearizableReadLoop","detail":"{readStateIndex:21274; appliedIndex:21273; }","duration":"137.375633ms","start":"2026-10-03T22:50:07.448518Z","end":"2026-10-03T22:50:07.585893Z","steps":["trace[1097791841] 'read index received' (duration: 136.922741ms)","trace[1097791841] 'applied index is now lower than readState.Index' (duration: 451.902µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:50:07.586012Z","caller":"traceutil/trace.go:171","msg":"trace[1171729536] transaction","detail":"{read_only:false; response_revision:20567; number_of_response:1; }","duration":"140.558981ms","start":"2026-10-03T22:50:07.445405Z","end":"2026-10-03T22:50:07.585964Z","steps":["trace[1171729536] 'process raft request' (duration: 140.110529ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:50:07.58633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.733274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-03T22:50:07.586363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.767572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-03T22:50:07.58643Z","caller":"traceutil/trace.go:171","msg":"trace[229936501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20567; }","duration":"137.94982ms","start":"2026-10-03T22:50:07.448465Z","end":"2026-10-03T22:50:07.586415Z","steps":["trace[229936501] 'agreement among raft nodes before linearized reading' (duration: 137.59396ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:50:07.586459Z","caller":"traceutil/trace.go:171","msg":"trace[148789000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20567; }","duration":"134.909466ms","start":"2026-10-03T22:50:07.451526Z","end":"2026-10-03T22:50:07.586436Z","steps":["trace[148789000] 'agreement among raft nodes before linearized reading' (duration: 134.583947ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:50:07.586471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.359953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-03T22:50:07.586594Z","caller":"traceutil/trace.go:171","msg":"trace[1482863756] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20567; }","duration":"129.522298ms","start":"2026-10-03T22:50:07.457045Z","end":"2026-10-03T22:50:07.586567Z","steps":["trace[1482863756] 'agreement among raft nodes before linearized reading' (duration: 129.164298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:50:07.586623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.88499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-03T22:50:07.586741Z","caller":"traceutil/trace.go:171","msg":"trace[530790206] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20567; }","duration":"133.057025ms","start":"2026-10-03T22:50:07.453653Z","end":"2026-10-03T22:50:07.58671Z","steps":["trace[530790206] 'agreement among raft nodes before linearized reading' (duration: 132.664734ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:50:07.763906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.314931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T22:50:07.764048Z","caller":"traceutil/trace.go:171","msg":"trace[2021747278] transaction","detail":"{read_only:false; response_revision:20568; number_of_response:1; }","duration":"171.514834ms","start":"2026-10-03T22:50:07.592506Z","end":"2026-10-03T22:50:07.764021Z","steps":["trace[2021747278] 'process raft request' (duration: 57.969406ms)","trace[2021747278] 'compare' (duration: 113.087295ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:50:07.764962Z","caller":"traceutil/trace.go:171","msg":"trace[152268857] transaction","detail":"{read_only:false; response_revision:20571; number_of_response:1; }","duration":"169.809498ms","start":"2026-10-03T22:50:07.595119Z","end":"2026-10-03T22:50:07.764929Z","steps":["trace[152268857] 'process raft request' (duration: 169.762147ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:50:07.765353Z","caller":"traceutil/trace.go:171","msg":"trace[790352286] transaction","detail":"{read_only:false; response_revision:20570; number_of_response:1; }","duration":"172.130451ms","start":"2026-10-03T22:50:07.593211Z","end":"2026-10-03T22:50:07.765341Z","steps":["trace[790352286] 'process raft request' (duration: 171.620107ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:50:07.765333Z","caller":"traceutil/trace.go:171","msg":"trace[1833231835] transaction","detail":"{read_only:false; response_revision:20569; number_of_response:1; }","duration":"172.371868ms","start":"2026-10-03T22:50:07.59292Z","end":"2026-10-03T22:50:07.765292Z","steps":["trace[1833231835] 'process raft request' (duration: 171.73438ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:50:08.054374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.029131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T22:50:08.054487Z","caller":"traceutil/trace.go:171","msg":"trace[1685948227] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20571; }","duration":"118.204706ms","start":"2026-10-03T22:50:07.936258Z","end":"2026-10-03T22:50:08.054463Z","steps":["trace[1685948227] 'range keys from in-memory index tree' (duration: 117.931678ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:50:08.362047Z","caller":"traceutil/trace.go:171","msg":"trace[1013443348] transaction","detail":"{read_only:false; response_revision:20573; number_of_response:1; }","duration":"104.456528ms","start":"2026-10-03T22:50:08.257509Z","end":"2026-10-03T22:50:08.361965Z","steps":["trace[1013443348] 'process raft request' (duration: 104.108278ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:50:08.384831Z","caller":"traceutil/trace.go:171","msg":"trace[1514912692] transaction","detail":"{read_only:false; response_revision:20574; number_of_response:1; }","duration":"127.006288ms","start":"2026-10-03T22:50:08.257793Z","end":"2026-10-03T22:50:08.384799Z","steps":["trace[1514912692] 'process raft request' (duration: 126.816123ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:53:25.215978Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19829} {"level":"info","ts":"2026-10-03T22:53:25.337668Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19829,"took":"117.291853ms","hash":1901736527,"current-db-size-bytes":55574528,"current-db-size":"56 MB","current-db-size-in-use-bytes":30203904,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-03T22:53:25.337772Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1901736527,"revision":19829,"compact-revision":17479} {"level":"info","ts":"2026-10-03T22:54:17.501704Z","caller":"traceutil/trace.go:171","msg":"trace[137194003] transaction","detail":"{read_only:false; response_revision:23082; number_of_response:1; }","duration":"144.48045ms","start":"2026-10-03T22:54:17.357192Z","end":"2026-10-03T22:54:17.501673Z","steps":["trace[137194003] 'process raft request' (duration: 144.216843ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:56:40.639602Z","caller":"traceutil/trace.go:171","msg":"trace[122623862] transaction","detail":"{read_only:false; response_revision:24552; number_of_response:1; }","duration":"157.013346ms","start":"2026-10-03T22:56:40.482535Z","end":"2026-10-03T22:56:40.639548Z","steps":["trace[122623862] 'process raft request' (duration: 128.704086ms)","trace[122623862] 'compare' (duration: 27.9745ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:56:40.639829Z","caller":"traceutil/trace.go:171","msg":"trace[685454958] transaction","detail":"{read_only:false; response_revision:24553; number_of_response:1; }","duration":"135.802301ms","start":"2026-10-03T22:56:40.50399Z","end":"2026-10-03T22:56:40.639792Z","steps":["trace[685454958] 'process raft request' (duration: 135.38935ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:56:40.639871Z","caller":"traceutil/trace.go:171","msg":"trace[1840861844] linearizableReadLoop","detail":"{readStateIndex:25377; appliedIndex:25375; }","duration":"117.388594ms","start":"2026-10-03T22:56:40.522454Z","end":"2026-10-03T22:56:40.639843Z","steps":["trace[1840861844] 'read index received' (duration: 88.805936ms)","trace[1840861844] 'applied index is now lower than readState.Index' (duration: 28.581098ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:56:40.640107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.636271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-ks-user-vj6vw\" ","response":"range_response_count:1 size:7582"} {"level":"info","ts":"2026-10-03T22:56:40.640154Z","caller":"traceutil/trace.go:171","msg":"trace[1720750285] range","detail":"{range_begin:/registry/pods/openstack/magnum-ks-user-vj6vw; range_end:; response_count:1; response_revision:24554; }","duration":"117.762704ms","start":"2026-10-03T22:56:40.52238Z","end":"2026-10-03T22:56:40.640143Z","steps":["trace[1720750285] 'agreement among raft nodes before linearized reading' (duration: 117.564919ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:56:41.640559Z","caller":"traceutil/trace.go:171","msg":"trace[1332883267] transaction","detail":"{read_only:false; response_revision:24568; number_of_response:1; }","duration":"107.40603ms","start":"2026-10-03T22:56:41.533116Z","end":"2026-10-03T22:56:41.640522Z","steps":["trace[1332883267] 'process raft request' (duration: 107.161463ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:56:43.495304Z","caller":"traceutil/trace.go:171","msg":"trace[2122442964] transaction","detail":"{read_only:false; response_revision:24584; number_of_response:1; }","duration":"188.898915ms","start":"2026-10-03T22:56:43.306299Z","end":"2026-10-03T22:56:43.495198Z","steps":["trace[2122442964] 'process raft request' (duration: 188.69628ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:56:46.513977Z","caller":"traceutil/trace.go:171","msg":"trace[1258034664] transaction","detail":"{read_only:false; response_revision:24605; number_of_response:1; }","duration":"126.144666ms","start":"2026-10-03T22:56:46.387805Z","end":"2026-10-03T22:56:46.51395Z","steps":["trace[1258034664] 'process raft request' (duration: 125.956651ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:56:46.518141Z","caller":"traceutil/trace.go:171","msg":"trace[1203474257] transaction","detail":"{read_only:false; response_revision:24606; number_of_response:1; }","duration":"102.466024ms","start":"2026-10-03T22:56:46.41564Z","end":"2026-10-03T22:56:46.518106Z","steps":["trace[1203474257] 'process raft request' (duration: 102.241418ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:57:29.093119Z","caller":"traceutil/trace.go:171","msg":"trace[360472230] transaction","detail":"{read_only:false; response_revision:24946; number_of_response:1; }","duration":"115.77809ms","start":"2026-10-03T22:57:28.97731Z","end":"2026-10-03T22:57:29.093088Z","steps":["trace[360472230] 'process raft request' (duration: 110.468124ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:57:29.093615Z","caller":"traceutil/trace.go:171","msg":"trace[2000218114] transaction","detail":"{read_only:false; response_revision:24947; number_of_response:1; }","duration":"106.949297ms","start":"2026-10-03T22:57:28.986631Z","end":"2026-10-03T22:57:29.09358Z","steps":["trace[2000218114] 'process raft request' (duration: 106.357221ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:57:36.804136Z","caller":"traceutil/trace.go:171","msg":"trace[1950988642] transaction","detail":"{read_only:false; response_revision:25047; number_of_response:1; }","duration":"104.723765ms","start":"2026-10-03T22:57:36.699385Z","end":"2026-10-03T22:57:36.804109Z","steps":["trace[1950988642] 'process raft request' (duration: 104.292094ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:36.804475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.796856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-conductor-0\" ","response":"range_response_count:1 size:9857"} {"level":"info","ts":"2026-10-03T22:57:36.804504Z","caller":"traceutil/trace.go:171","msg":"trace[410228146] range","detail":"{range_begin:/registry/pods/openstack/magnum-conductor-0; range_end:; response_count:1; response_revision:25047; }","duration":"109.885538ms","start":"2026-10-03T22:57:36.694609Z","end":"2026-10-03T22:57:36.804494Z","steps":["trace[410228146] 'agreement among raft nodes before linearized reading' (duration: 109.732914ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:36.804734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.215358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-10-03T22:57:36.804753Z","caller":"traceutil/trace.go:171","msg":"trace[1186989731] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:25047; }","duration":"110.245118ms","start":"2026-10-03T22:57:36.6945Z","end":"2026-10-03T22:57:36.804745Z","steps":["trace[1186989731] 'agreement among raft nodes before linearized reading' (duration: 110.115874ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:57:37.107985Z","caller":"traceutil/trace.go:171","msg":"trace[867827441] transaction","detail":"{read_only:false; response_revision:25059; number_of_response:1; }","duration":"104.293374ms","start":"2026-10-03T22:57:37.003646Z","end":"2026-10-03T22:57:37.107939Z","steps":["trace[867827441] 'process raft request' (duration: 93.891817ms)","trace[867827441] 'compare' (duration: 10.13596ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:57:37.108145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.017497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/magnum-registry\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T22:57:37.108222Z","caller":"traceutil/trace.go:171","msg":"trace[1736085979] range","detail":"{range_begin:/registry/services/specs/openstack/magnum-registry; range_end:; response_count:0; response_revision:25060; }","duration":"108.111879ms","start":"2026-10-03T22:57:37.000093Z","end":"2026-10-03T22:57:37.108205Z","steps":["trace[1736085979] 'agreement among raft nodes before linearized reading' (duration: 107.977905ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:37.108587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.713685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:208"} {"level":"info","ts":"2026-10-03T22:57:37.108613Z","caller":"traceutil/trace.go:171","msg":"trace[1696810668] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:25060; }","duration":"108.748826ms","start":"2026-10-03T22:57:36.999857Z","end":"2026-10-03T22:57:37.108605Z","steps":["trace[1696810668] 'agreement among raft nodes before linearized reading' (duration: 108.623913ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:37.108874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.356043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/magnum-api-certs\" ","response":"range_response_count:1 size:4355"} {"level":"info","ts":"2026-10-03T22:57:37.108981Z","caller":"traceutil/trace.go:171","msg":"trace[1716275243] range","detail":"{range_begin:/registry/secrets/openstack/magnum-api-certs; range_end:; response_count:1; response_revision:25060; }","duration":"109.408374ms","start":"2026-10-03T22:57:36.999497Z","end":"2026-10-03T22:57:37.108905Z","steps":["trace[1716275243] 'agreement among raft nodes before linearized reading' (duration: 109.306372ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:57:47.403002Z","caller":"traceutil/trace.go:171","msg":"trace[1115384528] transaction","detail":"{read_only:false; response_revision:25195; number_of_response:1; }","duration":"113.423976ms","start":"2026-10-03T22:57:47.289538Z","end":"2026-10-03T22:57:47.402962Z","steps":["trace[1115384528] 'process raft request' (duration: 101.930729ms)","trace[1115384528] 'compare' (duration: 11.333413ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:57:47.712679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.114287ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T22:57:47.712827Z","caller":"traceutil/trace.go:171","msg":"trace[565732711] transaction","detail":"{read_only:false; response_revision:25199; number_of_response:1; }","duration":"176.143344ms","start":"2026-10-03T22:57:47.536653Z","end":"2026-10-03T22:57:47.712797Z","steps":["trace[565732711] 'process raft request' (duration: 70.7877ms)","trace[565732711] 'compare' (duration: 104.774348ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:57:47.942038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.28149ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T22:57:47.94245Z","caller":"traceutil/trace.go:171","msg":"trace[421161010] linearizableReadLoop","detail":"{readStateIndex:26042; appliedIndex:26040; }","duration":"375.59643ms","start":"2026-10-03T22:57:47.566791Z","end":"2026-10-03T22:57:47.942387Z","steps":["trace[421161010] 'read index received' (duration: 40.605208ms)","trace[421161010] 'applied index is now lower than readState.Index' (duration: 334.987192ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:57:47.942456Z","caller":"traceutil/trace.go:171","msg":"trace[994820980] transaction","detail":"{read_only:false; response_revision:25200; number_of_response:1; }","duration":"401.752591ms","start":"2026-10-03T22:57:47.540634Z","end":"2026-10-03T22:57:47.942387Z","steps":["trace[994820980] 'process raft request' (duration: 271.014688ms)","trace[994820980] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-scheduler; req_size:461; } (duration: 125.440687ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:57:47.942666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.869628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-03T22:57:47.9427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:57:47.540613Z","time spent":"401.958647ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:57:47.942709Z","caller":"traceutil/trace.go:171","msg":"trace[200691338] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25201; }","duration":"375.96167ms","start":"2026-10-03T22:57:47.566736Z","end":"2026-10-03T22:57:47.942698Z","steps":["trace[200691338] 'agreement among raft nodes before linearized reading' (duration: 375.776375ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:47.942769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:57:47.566712Z","time spent":"376.047133ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-03T22:57:47.942484Z","caller":"traceutil/trace.go:171","msg":"trace[1703475608] transaction","detail":"{read_only:false; response_revision:25201; number_of_response:1; }","duration":"262.971677ms","start":"2026-10-03T22:57:47.67949Z","end":"2026-10-03T22:57:47.942461Z","steps":["trace[1703475608] 'process raft request' (duration: 262.680069ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:48.24246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.362778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T22:57:48.242628Z","caller":"traceutil/trace.go:171","msg":"trace[1139848088] transaction","detail":"{read_only:false; response_revision:25202; number_of_response:1; }","duration":"290.002951ms","start":"2026-10-03T22:57:47.952599Z","end":"2026-10-03T22:57:48.242602Z","steps":["trace[1139848088] 'process raft request' (duration: 141.275313ms)","trace[1139848088] 'compare' (duration: 148.010209ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:57:48.294293Z","caller":"traceutil/trace.go:171","msg":"trace[1790389716] linearizableReadLoop","detail":"{readStateIndex:26045; appliedIndex:26043; }","duration":"303.541455ms","start":"2026-10-03T22:57:47.990712Z","end":"2026-10-03T22:57:48.294253Z","steps":["trace[1790389716] 'read index received' (duration: 103.195274ms)","trace[1790389716] 'applied index is now lower than readState.Index' (duration: 200.343241ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:57:48.294611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.865293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T22:57:48.294712Z","caller":"traceutil/trace.go:171","msg":"trace[2037130452] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:25203; }","duration":"304.027428ms","start":"2026-10-03T22:57:47.990664Z","end":"2026-10-03T22:57:48.294692Z","steps":["trace[2037130452] 'agreement among raft nodes before linearized reading' (duration: 303.686478ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:48.294766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:57:47.990641Z","time spent":"304.1141ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":32,"request content":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true "} {"level":"info","ts":"2026-10-03T22:57:48.295267Z","caller":"traceutil/trace.go:171","msg":"trace[853786224] transaction","detail":"{read_only:false; response_revision:25203; number_of_response:1; }","duration":"313.733105ms","start":"2026-10-03T22:57:47.98057Z","end":"2026-10-03T22:57:48.294303Z","steps":["trace[853786224] 'process raft request' (duration: 313.419266ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:48.295391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:57:47.98054Z","time spent":"314.775554ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:57:48.604476Z","caller":"traceutil/trace.go:171","msg":"trace[1945690497] transaction","detail":"{read_only:false; response_revision:25205; number_of_response:1; }","duration":"387.000144ms","start":"2026-10-03T22:57:48.217442Z","end":"2026-10-03T22:57:48.604442Z","steps":["trace[1945690497] 'process raft request' (duration: 386.87243ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:48.604665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.950284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-58d446675-xdgzn.18db2545e3ba5dcf\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-10-03T22:57:48.604724Z","caller":"traceutil/trace.go:171","msg":"trace[1617986891] range","detail":"{range_begin:/registry/events/openstack/barbican-api-58d446675-xdgzn.18db2545e3ba5dcf; range_end:; response_count:1; response_revision:25205; }","duration":"333.070707ms","start":"2026-10-03T22:57:48.271639Z","end":"2026-10-03T22:57:48.604709Z","steps":["trace[1617986891] 'agreement among raft nodes before linearized reading' (duration: 332.81645ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:57:48.604463Z","caller":"traceutil/trace.go:171","msg":"trace[1818362170] linearizableReadLoop","detail":"{readStateIndex:26047; appliedIndex:26045; }","duration":"309.951401ms","start":"2026-10-03T22:57:48.294442Z","end":"2026-10-03T22:57:48.604393Z","steps":["trace[1818362170] 'read index received' (duration: 309.539949ms)","trace[1818362170] 'applied index is now lower than readState.Index' (duration: 409.642µs)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:57:48.60476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:57:48.27161Z","time spent":"333.142409ms","remote":"127.0.0.1:54814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1159,"request content":"key:\"/registry/events/openstack/barbican-api-58d446675-xdgzn.18db2545e3ba5dcf\" "} {"level":"warn","ts":"2026-10-03T22:57:48.604705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:57:48.217381Z","time spent":"387.190739ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:57:48.604824Z","caller":"traceutil/trace.go:171","msg":"trace[224560837] transaction","detail":"{read_only:false; response_revision:25204; number_of_response:1; }","duration":"503.524965ms","start":"2026-10-03T22:57:48.100905Z","end":"2026-10-03T22:57:48.60443Z","steps":["trace[224560837] 'process raft request' (duration: 503.115303ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:48.604704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.427168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T22:57:48.605056Z","caller":"traceutil/trace.go:171","msg":"trace[2102157063] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25205; }","duration":"341.86547ms","start":"2026-10-03T22:57:48.263157Z","end":"2026-10-03T22:57:48.605023Z","steps":["trace[2102157063] 'agreement among raft nodes before linearized reading' (duration: 341.397707ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:48.605134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:57:48.263126Z","time spent":"341.986763ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":32,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-03T22:57:48.605258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:57:48.100872Z","time spent":"504.100211ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:57:52.64396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.470844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T22:57:52.644059Z","caller":"traceutil/trace.go:171","msg":"trace[647472522] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:25242; }","duration":"188.588797ms","start":"2026-10-03T22:57:52.455445Z","end":"2026-10-03T22:57:52.644034Z","steps":["trace[647472522] 'count revisions from in-memory index tree' (duration: 188.36452ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:57:52.771978Z","caller":"traceutil/trace.go:171","msg":"trace[1965961848] transaction","detail":"{read_only:false; response_revision:25243; number_of_response:1; }","duration":"141.16346ms","start":"2026-10-03T22:57:52.630759Z","end":"2026-10-03T22:57:52.771923Z","steps":["trace[1965961848] 'process raft request' (duration: 140.709258ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:57:53.104869Z","caller":"traceutil/trace.go:171","msg":"trace[1064822791] linearizableReadLoop","detail":"{readStateIndex:26088; appliedIndex:26087; }","duration":"130.615739ms","start":"2026-10-03T22:57:52.974222Z","end":"2026-10-03T22:57:53.104838Z","steps":["trace[1064822791] 'read index received' (duration: 130.420324ms)","trace[1064822791] 'applied index is now lower than readState.Index' (duration: 194.415µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:57:53.105216Z","caller":"traceutil/trace.go:171","msg":"trace[967532003] transaction","detail":"{read_only:false; response_revision:25244; number_of_response:1; }","duration":"132.82213ms","start":"2026-10-03T22:57:52.97238Z","end":"2026-10-03T22:57:53.105202Z","steps":["trace[967532003] 'process raft request' (duration: 132.286235ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:53.106254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.007797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T22:57:53.106372Z","caller":"traceutil/trace.go:171","msg":"trace[131007042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25244; }","duration":"132.169802ms","start":"2026-10-03T22:57:52.974163Z","end":"2026-10-03T22:57:53.106333Z","steps":["trace[131007042] 'agreement among raft nodes before linearized reading' (duration: 131.994397ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:53.635882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.30836ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T22:57:53.636145Z","caller":"traceutil/trace.go:171","msg":"trace[1743400100] linearizableReadLoop","detail":"{readStateIndex:26091; appliedIndex:26089; }","duration":"189.122562ms","start":"2026-10-03T22:57:53.447002Z","end":"2026-10-03T22:57:53.636124Z","steps":["trace[1743400100] 'read index received' (duration: 29.423751ms)","trace[1743400100] 'applied index is now lower than readState.Index' (duration: 159.697511ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:57:53.636228Z","caller":"traceutil/trace.go:171","msg":"trace[1102891361] transaction","detail":"{read_only:false; response_revision:25247; number_of_response:1; }","duration":"207.94893ms","start":"2026-10-03T22:57:53.428266Z","end":"2026-10-03T22:57:53.636215Z","steps":["trace[1102891361] 'process raft request' (duration: 207.754235ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:57:53.636244Z","caller":"traceutil/trace.go:171","msg":"trace[1527121865] transaction","detail":"{read_only:false; response_revision:25246; number_of_response:1; }","duration":"294.121915ms","start":"2026-10-03T22:57:53.342058Z","end":"2026-10-03T22:57:53.63618Z","steps":["trace[1527121865] 'process raft request' (duration: 134.398104ms)","trace[1527121865] 'compare' (duration: 158.834167ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:57:53.636714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.670337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-03T22:57:53.636847Z","caller":"traceutil/trace.go:171","msg":"trace[1640445058] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25247; }","duration":"189.853912ms","start":"2026-10-03T22:57:53.446958Z","end":"2026-10-03T22:57:53.636812Z","steps":["trace[1640445058] 'agreement among raft nodes before linearized reading' (duration: 189.43327ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:54.041957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.073964ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T22:57:54.042146Z","caller":"traceutil/trace.go:171","msg":"trace[919326791] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25247; }","duration":"122.282839ms","start":"2026-10-03T22:57:53.919829Z","end":"2026-10-03T22:57:54.042112Z","steps":["trace[919326791] 'range keys from in-memory index tree' (duration: 122.041703ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:54.043312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.089453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T22:57:54.043693Z","caller":"traceutil/trace.go:171","msg":"trace[522136017] transaction","detail":"{read_only:false; response_revision:25248; number_of_response:1; }","duration":"500.872573ms","start":"2026-10-03T22:57:53.542768Z","end":"2026-10-03T22:57:54.04364Z","steps":["trace[522136017] 'process raft request' (duration: 230.321537ms)","trace[522136017] 'compare' (duration: 268.88136ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:57:54.043763Z","caller":"traceutil/trace.go:171","msg":"trace[142517078] transaction","detail":"{read_only:false; response_revision:25249; number_of_response:1; }","duration":"433.155376ms","start":"2026-10-03T22:57:53.61058Z","end":"2026-10-03T22:57:54.043735Z","steps":["trace[142517078] 'process raft request' (duration: 432.989992ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:54.043885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:57:53.610547Z","time spent":"433.28485ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:57:54.043903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:57:53.542731Z","time spent":"501.051967ms","remote":"127.0.0.1:41114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419262,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:57:54.044004Z","caller":"traceutil/trace.go:171","msg":"trace[222511046] linearizableReadLoop","detail":"{readStateIndex:26093; appliedIndex:26091; }","duration":"407.770757ms","start":"2026-10-03T22:57:53.636175Z","end":"2026-10-03T22:57:54.043945Z","steps":["trace[222511046] 'read index received' (duration: 136.931314ms)","trace[222511046] 'applied index is now lower than readState.Index' (duration: 270.836213ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:57:54.044041Z","caller":"traceutil/trace.go:171","msg":"trace[1815252999] transaction","detail":"{read_only:false; response_revision:25250; number_of_response:1; }","duration":"402.236054ms","start":"2026-10-03T22:57:53.641759Z","end":"2026-10-03T22:57:54.043995Z","steps":["trace[1815252999] 'process raft request' (duration: 401.912745ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:54.044253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:57:53.641729Z","time spent":"402.415659ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:57:54.044288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.462432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-03T22:57:54.044315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.09005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-03T22:57:54.044327Z","caller":"traceutil/trace.go:171","msg":"trace[263828061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25250; }","duration":"528.585026ms","start":"2026-10-03T22:57:53.51573Z","end":"2026-10-03T22:57:54.044315Z","steps":["trace[263828061] 'agreement among raft nodes before linearized reading' (duration: 528.387871ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:54.044372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:57:53.515696Z","time spent":"528.667438ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-03T22:57:54.044373Z","caller":"traceutil/trace.go:171","msg":"trace[319801599] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25250; }","duration":"484.221673ms","start":"2026-10-03T22:57:53.56013Z","end":"2026-10-03T22:57:54.044352Z","steps":["trace[319801599] 'agreement among raft nodes before linearized reading' (duration: 483.974676ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:54.0445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:57:53.560101Z","time spent":"484.388518ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-03T22:57:54.056463Z","caller":"traceutil/trace.go:171","msg":"trace[175250862] transaction","detail":"{read_only:false; response_revision:25251; number_of_response:1; }","duration":"266.679169ms","start":"2026-10-03T22:57:53.789758Z","end":"2026-10-03T22:57:54.056437Z","steps":["trace[175250862] 'process raft request' (duration: 266.428663ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:54.23844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.047956ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-03T22:57:54.402203Z","caller":"traceutil/trace.go:171","msg":"trace[1509595696] linearizableReadLoop","detail":"{readStateIndex:26097; appliedIndex:26094; }","duration":"358.070777ms","start":"2026-10-03T22:57:54.044082Z","end":"2026-10-03T22:57:54.402152Z","steps":["trace[1509595696] 'read index received' (duration: 12.107024ms)","trace[1509595696] 'applied index is now lower than readState.Index' (duration: 345.962283ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:57:54.402241Z","caller":"traceutil/trace.go:171","msg":"trace[1208739045] transaction","detail":"{read_only:false; response_revision:25254; number_of_response:1; }","duration":"352.051781ms","start":"2026-10-03T22:57:54.050139Z","end":"2026-10-03T22:57:54.402191Z","steps":["trace[1208739045] 'process raft request' (duration: 351.970079ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:54.402399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.025458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-03T22:57:54.402558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.926294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-03T22:57:54.402488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.334013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-03T22:57:54.402538Z","caller":"traceutil/trace.go:171","msg":"trace[2047347805] transaction","detail":"{read_only:false; response_revision:25252; number_of_response:1; }","duration":"429.828665ms","start":"2026-10-03T22:57:53.972638Z","end":"2026-10-03T22:57:54.402467Z","steps":["trace[2047347805] 'process raft request' (duration: 429.224978ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:57:54.402608Z","caller":"traceutil/trace.go:171","msg":"trace[1335264273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25254; }","duration":"649.521038ms","start":"2026-10-03T22:57:53.753074Z","end":"2026-10-03T22:57:54.402595Z","steps":["trace[1335264273] 'agreement among raft nodes before linearized reading' (duration: 649.23089ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:54.402662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:57:53.753047Z","time spent":"649.598731ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-03T22:57:54.402558Z","caller":"traceutil/trace.go:171","msg":"trace[453405817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25254; }","duration":"451.246085ms","start":"2026-10-03T22:57:53.951282Z","end":"2026-10-03T22:57:54.402528Z","steps":["trace[453405817] 'agreement among raft nodes before linearized reading' (duration: 450.931437ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:54.402775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:57:53.97261Z","time spent":"430.042401ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:57:54.40286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:57:54.050125Z","time spent":"352.432892ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:57:54.402883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:57:53.951253Z","time spent":"451.529572ms","remote":"127.0.0.1:54738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-03T22:57:54.402603Z","caller":"traceutil/trace.go:171","msg":"trace[1990727999] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25254; }","duration":"710.028626ms","start":"2026-10-03T22:57:53.692558Z","end":"2026-10-03T22:57:54.402587Z","steps":["trace[1990727999] 'agreement among raft nodes before linearized reading' (duration: 709.958194ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:54.40302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:57:53.692528Z","time spent":"710.462378ms","remote":"127.0.0.1:48274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":32,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-03T22:57:54.402992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.890408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-03T22:57:54.403179Z","caller":"traceutil/trace.go:171","msg":"trace[1282540483] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25254; }","duration":"421.557737ms","start":"2026-10-03T22:57:53.981594Z","end":"2026-10-03T22:57:54.403152Z","steps":["trace[1282540483] 'agreement among raft nodes before linearized reading' (duration: 420.676132ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:54.403232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:57:53.981582Z","time spent":"421.637468ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-03T22:57:54.403584Z","caller":"traceutil/trace.go:171","msg":"trace[1172271705] transaction","detail":"{read_only:false; response_revision:25253; number_of_response:1; }","duration":"353.363287ms","start":"2026-10-03T22:57:54.049202Z","end":"2026-10-03T22:57:54.402565Z","steps":["trace[1172271705] 'process raft request' (duration: 352.858813ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:54.403675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:57:54.04917Z","time spent":"354.463558ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:57:54.474004Z","caller":"traceutil/trace.go:171","msg":"trace[1712401465] transaction","detail":"{read_only:false; response_revision:25255; number_of_response:1; }","duration":"125.681693ms","start":"2026-10-03T22:57:54.348281Z","end":"2026-10-03T22:57:54.473963Z","steps":["trace[1712401465] 'process raft request' (duration: 111.882033ms)","trace[1712401465] 'compare' (duration: 13.557753ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:57:54.474181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.79315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-03T22:57:54.474252Z","caller":"traceutil/trace.go:171","msg":"trace[1446841874] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25255; }","duration":"205.914534ms","start":"2026-10-03T22:57:54.268319Z","end":"2026-10-03T22:57:54.474234Z","steps":["trace[1446841874] 'agreement among raft nodes before linearized reading' (duration: 205.733419ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:54.474102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.529318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T22:57:54.474565Z","caller":"traceutil/trace.go:171","msg":"trace[932190201] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:25255; }","duration":"155.031302ms","start":"2026-10-03T22:57:54.31951Z","end":"2026-10-03T22:57:54.474541Z","steps":["trace[932190201] 'agreement among raft nodes before linearized reading' (duration: 154.454286ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:54.506118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.309262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T22:57:54.506193Z","caller":"traceutil/trace.go:171","msg":"trace[1696191690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25257; }","duration":"101.423005ms","start":"2026-10-03T22:57:54.404751Z","end":"2026-10-03T22:57:54.506174Z","steps":["trace[1696191690] 'agreement among raft nodes before linearized reading' (duration: 101.27124ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:54.506222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.368674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19029"} {"level":"info","ts":"2026-10-03T22:57:54.506267Z","caller":"traceutil/trace.go:171","msg":"trace[1345053150] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25257; }","duration":"101.452456ms","start":"2026-10-03T22:57:54.404803Z","end":"2026-10-03T22:57:54.506255Z","steps":["trace[1345053150] 'agreement among raft nodes before linearized reading' (duration: 101.289331ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:57:54.809724Z","caller":"traceutil/trace.go:171","msg":"trace[1402520948] linearizableReadLoop","detail":"{readStateIndex:26103; appliedIndex:26102; }","duration":"297.753716ms","start":"2026-10-03T22:57:54.511941Z","end":"2026-10-03T22:57:54.809694Z","steps":["trace[1402520948] 'read index received' (duration: 288.85365ms)","trace[1402520948] 'applied index is now lower than readState.Index' (duration: 8.898376ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:57:54.809776Z","caller":"traceutil/trace.go:171","msg":"trace[704464290] transaction","detail":"{read_only:false; response_revision:25258; number_of_response:1; }","duration":"330.508548ms","start":"2026-10-03T22:57:54.479221Z","end":"2026-10-03T22:57:54.809729Z","steps":["trace[704464290] 'process raft request' (duration: 321.556681ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:54.809997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:57:54.479189Z","time spent":"330.682053ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:57:54.810342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.373772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19029"} {"level":"info","ts":"2026-10-03T22:57:54.810444Z","caller":"traceutil/trace.go:171","msg":"trace[907525422] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25258; }","duration":"298.505906ms","start":"2026-10-03T22:57:54.511892Z","end":"2026-10-03T22:57:54.810398Z","steps":["trace[907525422] 'agreement among raft nodes before linearized reading' (duration: 298.222178ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:54.84518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.077693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T22:57:54.845289Z","caller":"traceutil/trace.go:171","msg":"trace[850641026] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25259; }","duration":"139.264258ms","start":"2026-10-03T22:57:54.705997Z","end":"2026-10-03T22:57:54.845261Z","steps":["trace[850641026] 'agreement among raft nodes before linearized reading' (duration: 139.047552ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:57:55.324829Z","caller":"traceutil/trace.go:171","msg":"trace[1625534979] linearizableReadLoop","detail":"{readStateIndex:26105; appliedIndex:26104; }","duration":"142.26433ms","start":"2026-10-03T22:57:55.18253Z","end":"2026-10-03T22:57:55.324794Z","steps":["trace[1625534979] 'read index received' (duration: 142.067995ms)","trace[1625534979] 'applied index is now lower than readState.Index' (duration: 194.235µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:57:55.32489Z","caller":"traceutil/trace.go:171","msg":"trace[370523115] transaction","detail":"{read_only:false; response_revision:25260; number_of_response:1; }","duration":"201.562685ms","start":"2026-10-03T22:57:55.123289Z","end":"2026-10-03T22:57:55.324852Z","steps":["trace[370523115] 'process raft request' (duration: 201.299578ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:55.325164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.60601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T22:57:55.325237Z","caller":"traceutil/trace.go:171","msg":"trace[156694276] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:25260; }","duration":"142.753784ms","start":"2026-10-03T22:57:55.182467Z","end":"2026-10-03T22:57:55.325221Z","steps":["trace[156694276] 'agreement among raft nodes before linearized reading' (duration: 142.561209ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:57:55.515499Z","caller":"traceutil/trace.go:171","msg":"trace[1290115285] transaction","detail":"{read_only:false; response_revision:25261; number_of_response:1; }","duration":"228.062374ms","start":"2026-10-03T22:57:55.287398Z","end":"2026-10-03T22:57:55.51546Z","steps":["trace[1290115285] 'process raft request' (duration: 218.0983ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:55.91048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.864777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T22:57:55.910571Z","caller":"traceutil/trace.go:171","msg":"trace[1035478720] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:25261; }","duration":"290.004491ms","start":"2026-10-03T22:57:55.620546Z","end":"2026-10-03T22:57:55.91055Z","steps":["trace[1035478720] 'count revisions from in-memory index tree' (duration: 289.756553ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:55.911255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.053616ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T22:57:55.91139Z","caller":"traceutil/trace.go:171","msg":"trace[2141655848] transaction","detail":"{read_only:false; response_revision:25262; number_of_response:1; }","duration":"267.823599ms","start":"2026-10-03T22:57:55.643541Z","end":"2026-10-03T22:57:55.911364Z","steps":["trace[2141655848] 'process raft request' (duration: 134.532026ms)","trace[2141655848] 'compare' (duration: 132.321986ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:57:56.126352Z","caller":"traceutil/trace.go:171","msg":"trace[1660611133] transaction","detail":"{read_only:false; response_revision:25263; number_of_response:1; }","duration":"481.854417ms","start":"2026-10-03T22:57:55.644456Z","end":"2026-10-03T22:57:56.126311Z","steps":["trace[1660611133] 'process raft request' (duration: 461.800525ms)","trace[1660611133] 'compare' (duration: 19.270071ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:57:56.126903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:57:55.644442Z","time spent":"482.162995ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:57:56.127607Z","caller":"traceutil/trace.go:171","msg":"trace[1336546061] linearizableReadLoop","detail":"{readStateIndex:26108; appliedIndex:26106; }","duration":"410.829382ms","start":"2026-10-03T22:57:55.71676Z","end":"2026-10-03T22:57:56.127589Z","steps":["trace[1336546061] 'read index received' (duration: 61.329571ms)","trace[1336546061] 'applied index is now lower than readState.Index' (duration: 349.497991ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:57:56.127748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.984885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-03T22:57:56.127899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.527055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T22:57:56.127934Z","caller":"traceutil/trace.go:171","msg":"trace[1422319636] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25263; }","duration":"176.608987ms","start":"2026-10-03T22:57:55.951315Z","end":"2026-10-03T22:57:56.127924Z","steps":["trace[1422319636] 'agreement among raft nodes before linearized reading' (duration: 176.549505ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:56.127908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.395703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-10-03T22:57:56.128017Z","caller":"traceutil/trace.go:171","msg":"trace[2024924095] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:25263; }","duration":"396.602339ms","start":"2026-10-03T22:57:55.731388Z","end":"2026-10-03T22:57:56.12799Z","steps":["trace[2024924095] 'agreement among raft nodes before linearized reading' (duration: 396.375972ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:56.128077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:57:55.731355Z","time spent":"396.711692ms","remote":"127.0.0.1:54880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1876,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" "} {"level":"warn","ts":"2026-10-03T22:57:56.128506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.841271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-10-03T22:57:56.128546Z","caller":"traceutil/trace.go:171","msg":"trace[672017400] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25263; }","duration":"222.922843ms","start":"2026-10-03T22:57:55.905614Z","end":"2026-10-03T22:57:56.128536Z","steps":["trace[672017400] 'agreement among raft nodes before linearized reading' (duration: 222.710087ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:57:56.129747Z","caller":"traceutil/trace.go:171","msg":"trace[960637631] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:25263; }","duration":"411.098708ms","start":"2026-10-03T22:57:55.716701Z","end":"2026-10-03T22:57:56.1278Z","steps":["trace[960637631] 'agreement among raft nodes before linearized reading' (duration: 411.001266ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:56.129827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:57:55.716675Z","time spent":"413.130675ms","remote":"127.0.0.1:48320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true "} {"level":"info","ts":"2026-10-03T22:57:56.6838Z","caller":"traceutil/trace.go:171","msg":"trace[674071396] transaction","detail":"{read_only:false; response_revision:25266; number_of_response:1; }","duration":"270.505234ms","start":"2026-10-03T22:57:56.413251Z","end":"2026-10-03T22:57:56.683756Z","steps":["trace[674071396] 'process raft request' (duration: 234.958544ms)","trace[674071396] 'compare' (duration: 35.012895ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:57:56.683875Z","caller":"traceutil/trace.go:171","msg":"trace[268133512] transaction","detail":"{read_only:false; response_revision:25268; number_of_response:1; }","duration":"203.560789ms","start":"2026-10-03T22:57:56.480293Z","end":"2026-10-03T22:57:56.683853Z","steps":["trace[268133512] 'process raft request' (duration: 203.470917ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:57:56.683869Z","caller":"traceutil/trace.go:171","msg":"trace[530878919] linearizableReadLoop","detail":"{readStateIndex:26112; appliedIndex:26109; }","duration":"260.524159ms","start":"2026-10-03T22:57:56.423313Z","end":"2026-10-03T22:57:56.683837Z","steps":["trace[530878919] 'read index received' (duration: 56.692782ms)","trace[530878919] 'applied index is now lower than readState.Index' (duration: 203.829567ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:57:56.68401Z","caller":"traceutil/trace.go:171","msg":"trace[347122280] transaction","detail":"{read_only:false; response_revision:25267; number_of_response:1; }","duration":"269.846106ms","start":"2026-10-03T22:57:56.414149Z","end":"2026-10-03T22:57:56.683995Z","steps":["trace[347122280] 'process raft request' (duration: 269.461686ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:56.684239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.90068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-10-03T22:57:56.684309Z","caller":"traceutil/trace.go:171","msg":"trace[934911516] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25268; }","duration":"261.013012ms","start":"2026-10-03T22:57:56.423276Z","end":"2026-10-03T22:57:56.684289Z","steps":["trace[934911516] 'agreement among raft nodes before linearized reading' (duration: 260.757555ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:56.684347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.041418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-03T22:57:56.6845Z","caller":"traceutil/trace.go:171","msg":"trace[170420956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25268; }","duration":"173.269524ms","start":"2026-10-03T22:57:56.511192Z","end":"2026-10-03T22:57:56.684462Z","steps":["trace[170420956] 'agreement among raft nodes before linearized reading' (duration: 172.876643ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:56.68475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.330617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-03T22:57:56.684818Z","caller":"traceutil/trace.go:171","msg":"trace[112225877] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25268; }","duration":"173.416088ms","start":"2026-10-03T22:57:56.511385Z","end":"2026-10-03T22:57:56.684801Z","steps":["trace[112225877] 'agreement among raft nodes before linearized reading' (duration: 173.237444ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:57.035838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.960929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T22:57:57.035973Z","caller":"traceutil/trace.go:171","msg":"trace[2027629918] transaction","detail":"{read_only:false; response_revision:25269; number_of_response:1; }","duration":"346.206441ms","start":"2026-10-03T22:57:56.68974Z","end":"2026-10-03T22:57:57.035947Z","steps":["trace[2027629918] 'process raft request' (duration: 219.032017ms)","trace[2027629918] 'compare' (duration: 126.415983ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:57:57.03605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:57:56.689676Z","time spent":"346.345154ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:57:57.040068Z","caller":"traceutil/trace.go:171","msg":"trace[1620202190] linearizableReadLoop","detail":"{readStateIndex:26115; appliedIndex:26113; }","duration":"224.98256ms","start":"2026-10-03T22:57:56.815055Z","end":"2026-10-03T22:57:57.040038Z","steps":["trace[1620202190] 'read index received' (duration: 93.739473ms)","trace[1620202190] 'applied index is now lower than readState.Index' (duration: 131.241487ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:57:57.040995Z","caller":"traceutil/trace.go:171","msg":"trace[118175184] transaction","detail":"{read_only:false; response_revision:25270; number_of_response:1; }","duration":"349.988354ms","start":"2026-10-03T22:57:56.690982Z","end":"2026-10-03T22:57:57.04097Z","steps":["trace[118175184] 'process raft request' (duration: 347.917997ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:57.041153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:57:56.690947Z","time spent":"350.096807ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:57:57.041336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.220907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T22:57:57.041404Z","caller":"traceutil/trace.go:171","msg":"trace[77551662] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:25271; }","duration":"155.32879ms","start":"2026-10-03T22:57:56.886055Z","end":"2026-10-03T22:57:57.041384Z","steps":["trace[77551662] 'agreement among raft nodes before linearized reading' (duration: 155.215467ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:57:57.041693Z","caller":"traceutil/trace.go:171","msg":"trace[621709781] transaction","detail":"{read_only:false; response_revision:25271; number_of_response:1; }","duration":"190.064648ms","start":"2026-10-03T22:57:56.851609Z","end":"2026-10-03T22:57:57.041674Z","steps":["trace[621709781] 'process raft request' (duration: 187.410635ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:57.042044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.996615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-03T22:57:57.042096Z","caller":"traceutil/trace.go:171","msg":"trace[1533708336] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25271; }","duration":"227.046166ms","start":"2026-10-03T22:57:56.815035Z","end":"2026-10-03T22:57:57.042081Z","steps":["trace[1533708336] 'agreement among raft nodes before linearized reading' (duration: 226.885721ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:57.444713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.060292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-03T22:57:57.444952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.352875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T22:57:57.445005Z","caller":"traceutil/trace.go:171","msg":"trace[1798136202] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:25272; }","duration":"141.457148ms","start":"2026-10-03T22:57:57.303535Z","end":"2026-10-03T22:57:57.444992Z","steps":["trace[1798136202] 'count revisions from in-memory index tree' (duration: 141.243812ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:57:57.444827Z","caller":"traceutil/trace.go:171","msg":"trace[1106521416] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:25272; }","duration":"217.250756ms","start":"2026-10-03T22:57:57.22754Z","end":"2026-10-03T22:57:57.444791Z","steps":["trace[1106521416] 'count revisions from in-memory index tree' (duration: 216.922068ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:57:57.485082Z","caller":"traceutil/trace.go:171","msg":"trace[690760788] transaction","detail":"{read_only:false; response_revision:25273; number_of_response:1; }","duration":"151.596577ms","start":"2026-10-03T22:57:57.333438Z","end":"2026-10-03T22:57:57.485035Z","steps":["trace[690760788] 'process raft request' (duration: 151.366711ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:57:57.944641Z","caller":"traceutil/trace.go:171","msg":"trace[1097369587] transaction","detail":"{read_only:false; response_revision:25274; number_of_response:1; }","duration":"423.230983ms","start":"2026-10-03T22:57:57.521372Z","end":"2026-10-03T22:57:57.944603Z","steps":["trace[1097369587] 'process raft request' (duration: 423.013707ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:57:57.944841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:57:57.521311Z","time spent":"423.421328ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:57:58.300692Z","caller":"traceutil/trace.go:171","msg":"trace[2048509539] transaction","detail":"{read_only:false; response_revision:25277; number_of_response:1; }","duration":"104.381676ms","start":"2026-10-03T22:57:58.196275Z","end":"2026-10-03T22:57:58.300657Z","steps":["trace[2048509539] 'process raft request' (duration: 94.405591ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:10.195645Z","caller":"traceutil/trace.go:171","msg":"trace[120259715] transaction","detail":"{read_only:false; response_revision:25364; number_of_response:1; }","duration":"168.456762ms","start":"2026-10-03T22:58:10.02715Z","end":"2026-10-03T22:58:10.195607Z","steps":["trace[120259715] 'process raft request' (duration: 168.245886ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:10.492774Z","caller":"traceutil/trace.go:171","msg":"trace[1474164015] linearizableReadLoop","detail":"{readStateIndex:26213; appliedIndex:26212; }","duration":"408.794945ms","start":"2026-10-03T22:58:10.08394Z","end":"2026-10-03T22:58:10.492735Z","steps":["trace[1474164015] 'read index received' (duration: 112.307695ms)","trace[1474164015] 'applied index is now lower than readState.Index' (duration: 296.48595ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:58:10.492976Z","caller":"traceutil/trace.go:171","msg":"trace[972287041] transaction","detail":"{read_only:false; response_revision:25365; number_of_response:1; }","duration":"464.448719ms","start":"2026-10-03T22:58:10.028507Z","end":"2026-10-03T22:58:10.492956Z","steps":["trace[972287041] 'process raft request' (duration: 436.251662ms)","trace[972287041] 'compare' (duration: 27.623921ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:58:10.493139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:10.028466Z","time spent":"464.548981ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:58:10.493176Z","caller":"traceutil/trace.go:171","msg":"trace[311849156] transaction","detail":"{read_only:false; response_revision:25366; number_of_response:1; }","duration":"395.636842ms","start":"2026-10-03T22:58:10.097505Z","end":"2026-10-03T22:58:10.493142Z","steps":["trace[311849156] 'process raft request' (duration: 395.089757ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:10.49327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:10.097477Z","time spent":"395.739425ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:58:10.4933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.32559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-03T22:58:10.493361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.602739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-03T22:58:10.493395Z","caller":"traceutil/trace.go:171","msg":"trace[2092854231] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:25367; }","duration":"409.492424ms","start":"2026-10-03T22:58:10.083876Z","end":"2026-10-03T22:58:10.493369Z","steps":["trace[2092854231] 'agreement among raft nodes before linearized reading' (duration: 409.221887ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:10.493459Z","caller":"traceutil/trace.go:171","msg":"trace[2136825095] transaction","detail":"{read_only:false; response_revision:25367; number_of_response:1; }","duration":"219.738555ms","start":"2026-10-03T22:58:10.273701Z","end":"2026-10-03T22:58:10.49344Z","steps":["trace[2136825095] 'process raft request' (duration: 218.985904ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:10.493484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:10.083847Z","time spent":"409.625268ms","remote":"127.0.0.1:48420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":30,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true "} {"level":"info","ts":"2026-10-03T22:58:10.493502Z","caller":"traceutil/trace.go:171","msg":"trace[279384806] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25367; }","duration":"126.794344ms","start":"2026-10-03T22:58:10.366668Z","end":"2026-10-03T22:58:10.493462Z","steps":["trace[279384806] 'agreement among raft nodes before linearized reading' (duration: 126.507036ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:11.118532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.048864ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T22:58:11.118716Z","caller":"traceutil/trace.go:171","msg":"trace[1226499604] transaction","detail":"{read_only:false; response_revision:25369; number_of_response:1; }","duration":"568.536407ms","start":"2026-10-03T22:58:10.550132Z","end":"2026-10-03T22:58:11.118668Z","steps":["trace[1226499604] 'process raft request' (duration: 295.190654ms)","trace[1226499604] 'compare' (duration: 272.88831ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:58:11.118834Z","caller":"traceutil/trace.go:171","msg":"trace[837328012] transaction","detail":"{read_only:false; response_revision:25370; number_of_response:1; }","duration":"379.312552ms","start":"2026-10-03T22:58:10.739487Z","end":"2026-10-03T22:58:11.118799Z","steps":["trace[837328012] 'process raft request' (duration: 379.195099ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:11.118935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:10.739457Z","time spent":"379.431896ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:58:11.118938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:10.550097Z","time spent":"568.718662ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:58:11.119097Z","caller":"traceutil/trace.go:171","msg":"trace[118056994] linearizableReadLoop","detail":"{readStateIndex:26217; appliedIndex:26216; }","duration":"537.137002ms","start":"2026-10-03T22:58:10.581949Z","end":"2026-10-03T22:58:11.119086Z","steps":["trace[118056994] 'read index received' (duration: 263.396849ms)","trace[118056994] 'applied index is now lower than readState.Index' (duration: 273.738363ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:58:11.119305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.365528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T22:58:11.119345Z","caller":"traceutil/trace.go:171","msg":"trace[1797307932] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:25370; }","duration":"537.450731ms","start":"2026-10-03T22:58:10.581882Z","end":"2026-10-03T22:58:11.119333Z","steps":["trace[1797307932] 'agreement among raft nodes before linearized reading' (duration: 537.295627ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:11.119373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:10.581839Z","time spent":"537.526693ms","remote":"127.0.0.1:55188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":20,"response size":32,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-10-03T22:58:11.119768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.261755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-03T22:58:11.120329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.419425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T22:58:11.120518Z","caller":"traceutil/trace.go:171","msg":"trace[51253829] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:25370; }","duration":"195.731564ms","start":"2026-10-03T22:58:10.924757Z","end":"2026-10-03T22:58:11.120489Z","steps":["trace[51253829] 'agreement among raft nodes before linearized reading' (duration: 195.311402ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:11.120409Z","caller":"traceutil/trace.go:171","msg":"trace[1607842894] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25370; }","duration":"349.937483ms","start":"2026-10-03T22:58:10.770456Z","end":"2026-10-03T22:58:11.120394Z","steps":["trace[1607842894] 'agreement among raft nodes before linearized reading' (duration: 349.159042ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:11.120758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:10.77043Z","time spent":"350.259182ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-03T22:58:11.120206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.285488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-03T22:58:11.121034Z","caller":"traceutil/trace.go:171","msg":"trace[464044447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25370; }","duration":"341.093199ms","start":"2026-10-03T22:58:10.779886Z","end":"2026-10-03T22:58:11.120979Z","steps":["trace[464044447] 'agreement among raft nodes before linearized reading' (duration: 340.208625ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:11.120159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.252914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-03T22:58:11.12115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:10.779875Z","time spent":"341.229583ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-03T22:58:11.121158Z","caller":"traceutil/trace.go:171","msg":"trace[1248024809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25370; }","duration":"170.278732ms","start":"2026-10-03T22:58:10.950841Z","end":"2026-10-03T22:58:11.12112Z","steps":["trace[1248024809] 'agreement among raft nodes before linearized reading' (duration: 169.250094ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:11.711796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.926768ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T22:58:11.712343Z","caller":"traceutil/trace.go:171","msg":"trace[956115469] transaction","detail":"{read_only:false; response_revision:25371; number_of_response:1; }","duration":"586.327818ms","start":"2026-10-03T22:58:11.125991Z","end":"2026-10-03T22:58:11.712319Z","steps":["trace[956115469] 'process raft request' (duration: 409.783553ms)","trace[956115469] 'compare' (duration: 175.29466ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:58:11.712458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:11.125971Z","time spent":"586.42438ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:58:11.734905Z","caller":"traceutil/trace.go:171","msg":"trace[305613060] transaction","detail":"{read_only:false; response_revision:25372; number_of_response:1; }","duration":"608.780906ms","start":"2026-10-03T22:58:11.126083Z","end":"2026-10-03T22:58:11.734864Z","steps":["trace[305613060] 'process raft request' (duration: 608.503118ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:11.735155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:11.126047Z","time spent":"608.967571ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:58:11.735147Z","caller":"traceutil/trace.go:171","msg":"trace[393715623] linearizableReadLoop","detail":"{readStateIndex:26221; appliedIndex:26218; }","duration":"589.943397ms","start":"2026-10-03T22:58:11.145137Z","end":"2026-10-03T22:58:11.735081Z","steps":["trace[393715623] 'read index received' (duration: 390.657325ms)","trace[393715623] 'applied index is now lower than readState.Index' (duration: 199.282542ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:58:11.735223Z","caller":"traceutil/trace.go:171","msg":"trace[1437144374] transaction","detail":"{read_only:false; response_revision:25373; number_of_response:1; }","duration":"594.337147ms","start":"2026-10-03T22:58:11.140835Z","end":"2026-10-03T22:58:11.735173Z","steps":["trace[1437144374] 'process raft request' (duration: 593.965757ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:11.735497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:11.140815Z","time spent":"594.518673ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:58:11.735526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.380239ms","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-10-03T22:58:11.735597Z","caller":"traceutil/trace.go:171","msg":"trace[373298513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25373; }","duration":"590.483102ms","start":"2026-10-03T22:58:11.145099Z","end":"2026-10-03T22:58:11.735582Z","steps":["trace[373298513] 'agreement among raft nodes before linearized reading' (duration: 590.195374ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:11.73567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:11.145083Z","time spent":"590.575495ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-03T22:58:12.021903Z","caller":"traceutil/trace.go:171","msg":"trace[1111807703] transaction","detail":"{read_only:false; response_revision:25375; number_of_response:1; }","duration":"280.712026ms","start":"2026-10-03T22:58:11.741157Z","end":"2026-10-03T22:58:12.021869Z","steps":["trace[1111807703] 'process raft request' (duration: 280.578162ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:12.022014Z","caller":"traceutil/trace.go:171","msg":"trace[2001552934] linearizableReadLoop","detail":"{readStateIndex:26222; appliedIndex:26221; }","duration":"286.757662ms","start":"2026-10-03T22:58:11.735224Z","end":"2026-10-03T22:58:12.021982Z","steps":["trace[2001552934] 'read index received' (duration: 284.87574ms)","trace[2001552934] 'applied index is now lower than readState.Index' (duration: 1.879772ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:58:12.022148Z","caller":"traceutil/trace.go:171","msg":"trace[1623033185] transaction","detail":"{read_only:false; response_revision:25374; number_of_response:1; }","duration":"456.896991ms","start":"2026-10-03T22:58:11.565214Z","end":"2026-10-03T22:58:12.022111Z","steps":["trace[1623033185] 'process raft request' (duration: 454.869365ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:12.022308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:11.565183Z","time spent":"457.030264ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:58:12.022161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.16303ms","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:6"} {"level":"info","ts":"2026-10-03T22:58:12.02251Z","caller":"traceutil/trace.go:171","msg":"trace[280294115] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:25375; }","duration":"358.53187ms","start":"2026-10-03T22:58:11.663952Z","end":"2026-10-03T22:58:12.022484Z","steps":["trace[280294115] 'agreement among raft nodes before linearized reading' (duration: 358.077507ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:12.022518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.979205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T22:58:12.022569Z","caller":"traceutil/trace.go:171","msg":"trace[802692866] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:25375; }","duration":"861.078809ms","start":"2026-10-03T22:58:11.161473Z","end":"2026-10-03T22:58:12.022552Z","steps":["trace[802692866] 'agreement among raft nodes before linearized reading' (duration: 861.002497ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:12.022605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:11.161449Z","time spent":"861.14715ms","remote":"127.0.0.1:33704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-10-03T22:58:12.022599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:11.663864Z","time spent":"358.691495ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-10-03T22:58:12.022628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.832629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-03T22:58:12.022679Z","caller":"traceutil/trace.go:171","msg":"trace[818308980] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25375; }","duration":"861.924272ms","start":"2026-10-03T22:58:11.160741Z","end":"2026-10-03T22:58:12.022665Z","steps":["trace[818308980] 'agreement among raft nodes before linearized reading' (duration: 861.739567ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:12.02271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:11.160719Z","time spent":"861.983463ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-03T22:58:12.022929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.388453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-03T22:58:12.023031Z","caller":"traceutil/trace.go:171","msg":"trace[534310705] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25375; }","duration":"867.549807ms","start":"2026-10-03T22:58:11.155455Z","end":"2026-10-03T22:58:12.023005Z","steps":["trace[534310705] 'agreement among raft nodes before linearized reading' (duration: 867.188527ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:12.023085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:11.155441Z","time spent":"867.634559ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-03T22:58:12.650535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.633547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T22:58:12.650674Z","caller":"traceutil/trace.go:171","msg":"trace[2126311057] linearizableReadLoop","detail":"{readStateIndex:26224; appliedIndex:26223; }","duration":"620.497659ms","start":"2026-10-03T22:58:12.03015Z","end":"2026-10-03T22:58:12.650648Z","steps":["trace[2126311057] 'read index received' (duration: 332.525844ms)","trace[2126311057] 'applied index is now lower than readState.Index' (duration: 287.969745ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:58:12.650779Z","caller":"traceutil/trace.go:171","msg":"trace[1364284848] transaction","detail":"{read_only:false; response_revision:25376; number_of_response:1; }","duration":"622.052921ms","start":"2026-10-03T22:58:12.028708Z","end":"2026-10-03T22:58:12.650761Z","steps":["trace[1364284848] 'process raft request' (duration: 334.008294ms)","trace[1364284848] 'compare' (duration: 287.458291ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:58:12.650866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:12.028687Z","time spent":"622.131974ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:58:12.651034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.839688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19029"} {"level":"info","ts":"2026-10-03T22:58:12.651145Z","caller":"traceutil/trace.go:171","msg":"trace[820274242] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25376; }","duration":"620.989792ms","start":"2026-10-03T22:58:12.030127Z","end":"2026-10-03T22:58:12.651117Z","steps":["trace[820274242] 'agreement among raft nodes before linearized reading' (duration: 620.637342ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:12.651202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:12.030104Z","time spent":"621.085325ms","remote":"127.0.0.1:54888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19053,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-10-03T22:58:12.654239Z","caller":"traceutil/trace.go:171","msg":"trace[67966025] transaction","detail":"{read_only:false; response_revision:25377; number_of_response:1; }","duration":"623.735468ms","start":"2026-10-03T22:58:12.030471Z","end":"2026-10-03T22:58:12.654207Z","steps":["trace[67966025] 'process raft request' (duration: 623.498732ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:12.654571Z","caller":"traceutil/trace.go:171","msg":"trace[827237898] transaction","detail":"{read_only:false; response_revision:25378; number_of_response:1; }","duration":"450.769362ms","start":"2026-10-03T22:58:12.203755Z","end":"2026-10-03T22:58:12.654525Z","steps":["trace[827237898] 'process raft request' (duration: 450.396432ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:12.654646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:12.030447Z","time spent":"623.879781ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:58:12.65482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:12.203729Z","time spent":"450.931647ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:58:13.114307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.496424ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T22:58:13.114531Z","caller":"traceutil/trace.go:171","msg":"trace[504845006] linearizableReadLoop","detail":"{readStateIndex:26228; appliedIndex:26224; }","duration":"463.801731ms","start":"2026-10-03T22:58:12.65071Z","end":"2026-10-03T22:58:13.114512Z","steps":["trace[504845006] 'read index received' (duration: 3.302051ms)","trace[504845006] 'applied index is now lower than readState.Index' (duration: 460.49839ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:58:13.114594Z","caller":"traceutil/trace.go:171","msg":"trace[159903335] transaction","detail":"{read_only:false; response_revision:25380; number_of_response:1; }","duration":"613.822985ms","start":"2026-10-03T22:58:12.500727Z","end":"2026-10-03T22:58:13.11455Z","steps":["trace[159903335] 'process raft request' (duration: 613.713002ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:13.11471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.712516ms","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:6"} {"level":"info","ts":"2026-10-03T22:58:13.114751Z","caller":"traceutil/trace.go:171","msg":"trace[88136961] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:25380; }","duration":"943.805138ms","start":"2026-10-03T22:58:12.17093Z","end":"2026-10-03T22:58:13.114736Z","steps":["trace[88136961] 'agreement among raft nodes before linearized reading' (duration: 943.688445ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:13.114776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:12.500691Z","time spent":"613.981229ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:58:13.114786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:12.170866Z","time spent":"943.911401ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"warn","ts":"2026-10-03T22:58:13.114886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.993146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-03T22:58:13.114934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.297993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-03T22:58:13.114869Z","caller":"traceutil/trace.go:171","msg":"trace[489312122] transaction","detail":"{read_only:false; response_revision:25379; number_of_response:1; }","duration":"614.534384ms","start":"2026-10-03T22:58:12.500029Z","end":"2026-10-03T22:58:13.114564Z","steps":["trace[489312122] 'process raft request' (duration: 462.669129ms)","trace[489312122] 'compare' (duration: 151.061253ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:58:13.115006Z","caller":"traceutil/trace.go:171","msg":"trace[2145979855] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25380; }","duration":"571.398636ms","start":"2026-10-03T22:58:12.543582Z","end":"2026-10-03T22:58:13.114981Z","steps":["trace[2145979855] 'agreement among raft nodes before linearized reading' (duration: 571.222631ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:13.115059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:12.54355Z","time spent":"571.501528ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-03T22:58:13.114942Z","caller":"traceutil/trace.go:171","msg":"trace[33087229] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25380; }","duration":"382.099609ms","start":"2026-10-03T22:58:12.732829Z","end":"2026-10-03T22:58:13.114928Z","steps":["trace[33087229] 'agreement among raft nodes before linearized reading' (duration: 381.998976ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:13.115401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:12.732799Z","time spent":"382.584923ms","remote":"127.0.0.1:55158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":30,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-10-03T22:58:13.11475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.449689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T22:58:13.115504Z","caller":"traceutil/trace.go:171","msg":"trace[501898717] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:25380; }","duration":"911.283182ms","start":"2026-10-03T22:58:12.204202Z","end":"2026-10-03T22:58:13.115485Z","steps":["trace[501898717] 'agreement among raft nodes before linearized reading' (duration: 910.394068ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:13.114991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.01805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-03T22:58:13.115561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:12.204167Z","time spent":"911.374764ms","remote":"127.0.0.1:41114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":32,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true "} {"level":"info","ts":"2026-10-03T22:58:13.115614Z","caller":"traceutil/trace.go:171","msg":"trace[139585230] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25380; }","duration":"164.663407ms","start":"2026-10-03T22:58:12.950922Z","end":"2026-10-03T22:58:13.115586Z","steps":["trace[139585230] 'agreement among raft nodes before linearized reading' (duration: 164.03947ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:13.115595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:12.49998Z","time spent":"615.062249ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:58:19.302434Z","caller":"traceutil/trace.go:171","msg":"trace[2100188413] transaction","detail":"{read_only:false; response_revision:25481; number_of_response:1; }","duration":"161.011817ms","start":"2026-10-03T22:58:19.141382Z","end":"2026-10-03T22:58:19.302394Z","steps":["trace[2100188413] 'process raft request' (duration: 160.847782ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:19.342499Z","caller":"traceutil/trace.go:171","msg":"trace[940721091] linearizableReadLoop","detail":"{readStateIndex:26332; appliedIndex:26331; }","duration":"174.345454ms","start":"2026-10-03T22:58:19.168119Z","end":"2026-10-03T22:58:19.342464Z","steps":["trace[940721091] 'read index received' (duration: 134.319911ms)","trace[940721091] 'applied index is now lower than readState.Index' (duration: 40.024173ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:58:19.342905Z","caller":"traceutil/trace.go:171","msg":"trace[217422829] transaction","detail":"{read_only:false; response_revision:25482; number_of_response:1; }","duration":"199.57192ms","start":"2026-10-03T22:58:19.143314Z","end":"2026-10-03T22:58:19.342886Z","steps":["trace[217422829] 'process raft request' (duration: 198.804908ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:19.343263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.602839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-03T22:58:19.34331Z","caller":"traceutil/trace.go:171","msg":"trace[788894355] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25484; }","duration":"171.711532ms","start":"2026-10-03T22:58:19.171585Z","end":"2026-10-03T22:58:19.343296Z","steps":["trace[788894355] 'agreement among raft nodes before linearized reading' (duration: 171.520077ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:19.343266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.107105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-10-03T22:58:19.343387Z","caller":"traceutil/trace.go:171","msg":"trace[1200694478] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25484; }","duration":"175.255699ms","start":"2026-10-03T22:58:19.168096Z","end":"2026-10-03T22:58:19.343352Z","steps":["trace[1200694478] 'agreement among raft nodes before linearized reading' (duration: 174.812997ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:19.343373Z","caller":"traceutil/trace.go:171","msg":"trace[1993285489] transaction","detail":"{read_only:false; response_revision:25483; number_of_response:1; }","duration":"171.658371ms","start":"2026-10-03T22:58:19.171663Z","end":"2026-10-03T22:58:19.343321Z","steps":["trace[1993285489] 'process raft request' (duration: 170.673233ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:19.343448Z","caller":"traceutil/trace.go:171","msg":"trace[1973639750] transaction","detail":"{read_only:false; response_revision:25484; number_of_response:1; }","duration":"170.90248ms","start":"2026-10-03T22:58:19.172496Z","end":"2026-10-03T22:58:19.343398Z","steps":["trace[1973639750] 'process raft request' (duration: 169.889012ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:19.554458Z","caller":"traceutil/trace.go:171","msg":"trace[497771801] transaction","detail":"{read_only:false; response_revision:25485; number_of_response:1; }","duration":"205.467992ms","start":"2026-10-03T22:58:19.348919Z","end":"2026-10-03T22:58:19.554387Z","steps":["trace[497771801] 'process raft request' (duration: 115.135693ms)","trace[497771801] 'compare' (duration: 89.711272ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:58:19.593795Z","caller":"traceutil/trace.go:171","msg":"trace[111665625] transaction","detail":"{read_only:false; response_revision:25486; number_of_response:1; }","duration":"244.803256ms","start":"2026-10-03T22:58:19.348949Z","end":"2026-10-03T22:58:19.593752Z","steps":["trace[111665625] 'process raft request' (duration: 244.577479ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:19.732912Z","caller":"traceutil/trace.go:171","msg":"trace[2059838481] transaction","detail":"{read_only:false; response_revision:25487; number_of_response:1; }","duration":"131.063302ms","start":"2026-10-03T22:58:19.601812Z","end":"2026-10-03T22:58:19.732875Z","steps":["trace[2059838481] 'process raft request' (duration: 130.882567ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:20.021657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.225672ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T22:58:20.021917Z","caller":"traceutil/trace.go:171","msg":"trace[1064754971] transaction","detail":"{read_only:false; response_revision:25489; number_of_response:1; }","duration":"241.067603ms","start":"2026-10-03T22:58:19.780827Z","end":"2026-10-03T22:58:20.021895Z","steps":["trace[1064754971] 'process raft request' (duration: 240.95512ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:20.022071Z","caller":"traceutil/trace.go:171","msg":"trace[976875523] linearizableReadLoop","detail":"{readStateIndex:26338; appliedIndex:26337; }","duration":"277.141397ms","start":"2026-10-03T22:58:19.744915Z","end":"2026-10-03T22:58:20.022056Z","steps":["trace[976875523] 'read index received' (duration: 131.404711ms)","trace[976875523] 'applied index is now lower than readState.Index' (duration: 145.735486ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:58:20.02206Z","caller":"traceutil/trace.go:171","msg":"trace[972208888] transaction","detail":"{read_only:false; response_revision:25488; number_of_response:1; }","duration":"364.764252ms","start":"2026-10-03T22:58:19.657239Z","end":"2026-10-03T22:58:20.022003Z","steps":["trace[972208888] 'process raft request' (duration: 219.055697ms)","trace[972208888] 'compare' (duration: 144.593504ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:58:20.022274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.871981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-03T22:58:20.022269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:19.657204Z","time spent":"364.946877ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:58:20.022313Z","caller":"traceutil/trace.go:171","msg":"trace[262491833] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25489; }","duration":"259.961534ms","start":"2026-10-03T22:58:19.762338Z","end":"2026-10-03T22:58:20.0223Z","steps":["trace[262491833] 'agreement among raft nodes before linearized reading' (duration: 259.80665ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:20.022277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.321732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T22:58:20.022391Z","caller":"traceutil/trace.go:171","msg":"trace[1171770608] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:25489; }","duration":"277.515697ms","start":"2026-10-03T22:58:19.74485Z","end":"2026-10-03T22:58:20.022365Z","steps":["trace[1171770608] 'agreement among raft nodes before linearized reading' (duration: 277.27876ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:20.022388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.038587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-03T22:58:20.02247Z","caller":"traceutil/trace.go:171","msg":"trace[1675859102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25489; }","duration":"235.189711ms","start":"2026-10-03T22:58:19.787265Z","end":"2026-10-03T22:58:20.022455Z","steps":["trace[1675859102] 'agreement among raft nodes before linearized reading' (duration: 234.956685ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:20.022511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.560462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3237"} {"level":"info","ts":"2026-10-03T22:58:20.022545Z","caller":"traceutil/trace.go:171","msg":"trace[1953524466] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25489; }","duration":"145.627583ms","start":"2026-10-03T22:58:19.876909Z","end":"2026-10-03T22:58:20.022537Z","steps":["trace[1953524466] 'agreement among raft nodes before linearized reading' (duration: 145.53104ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:20.022517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.210127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-manila-server-0.18db2720a1a2bd35\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-10-03T22:58:20.022602Z","caller":"traceutil/trace.go:171","msg":"trace[740415462] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-manila-server-0.18db2720a1a2bd35; range_end:; response_count:1; response_revision:25489; }","duration":"241.337001ms","start":"2026-10-03T22:58:19.781241Z","end":"2026-10-03T22:58:20.022578Z","steps":["trace[740415462] 'agreement among raft nodes before linearized reading' (duration: 241.075413ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:20.295563Z","caller":"traceutil/trace.go:171","msg":"trace[270109550] transaction","detail":"{read_only:false; response_revision:25492; number_of_response:1; }","duration":"265.048243ms","start":"2026-10-03T22:58:20.030479Z","end":"2026-10-03T22:58:20.295527Z","steps":["trace[270109550] 'process raft request' (duration: 264.996842ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:20.295699Z","caller":"traceutil/trace.go:171","msg":"trace[1848617183] transaction","detail":"{read_only:false; response_revision:25491; number_of_response:1; }","duration":"265.314271ms","start":"2026-10-03T22:58:20.030342Z","end":"2026-10-03T22:58:20.295656Z","steps":["trace[1848617183] 'process raft request' (duration: 264.92084ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:20.329641Z","caller":"traceutil/trace.go:171","msg":"trace[279806640] linearizableReadLoop","detail":"{readStateIndex:26343; appliedIndex:26342; }","duration":"229.434183ms","start":"2026-10-03T22:58:20.10018Z","end":"2026-10-03T22:58:20.329614Z","steps":["trace[279806640] 'read index received' (duration: 195.559959ms)","trace[279806640] 'applied index is now lower than readState.Index' (duration: 33.873104ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:58:20.329779Z","caller":"traceutil/trace.go:171","msg":"trace[468435708] transaction","detail":"{read_only:false; response_revision:25493; number_of_response:1; }","duration":"233.234277ms","start":"2026-10-03T22:58:20.096508Z","end":"2026-10-03T22:58:20.329742Z","steps":["trace[468435708] 'process raft request' (duration: 232.941609ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:20.329906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.69759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"info","ts":"2026-10-03T22:58:20.329946Z","caller":"traceutil/trace.go:171","msg":"trace[1774145332] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:25493; }","duration":"229.805762ms","start":"2026-10-03T22:58:20.100129Z","end":"2026-10-03T22:58:20.329935Z","steps":["trace[1774145332] 'agreement among raft nodes before linearized reading' (duration: 229.622257ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:20.330104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.777836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1989"} {"level":"info","ts":"2026-10-03T22:58:20.330174Z","caller":"traceutil/trace.go:171","msg":"trace[1033095088] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:25493; }","duration":"166.90653ms","start":"2026-10-03T22:58:20.163253Z","end":"2026-10-03T22:58:20.33016Z","steps":["trace[1033095088] 'agreement among raft nodes before linearized reading' (duration: 166.741075ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:20.330153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.431884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1ac37d97-5893-46e1-a5f4-c197f35b7d52\" ","response":"range_response_count:1 size:3764"} {"level":"warn","ts":"2026-10-03T22:58:20.330194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.80746ms","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-10-03T22:58:20.330228Z","caller":"traceutil/trace.go:171","msg":"trace[1807669984] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1ac37d97-5893-46e1-a5f4-c197f35b7d52; range_end:; response_count:1; response_revision:25493; }","duration":"228.553308ms","start":"2026-10-03T22:58:20.101656Z","end":"2026-10-03T22:58:20.330209Z","steps":["trace[1807669984] 'agreement among raft nodes before linearized reading' (duration: 228.320622ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:20.330245Z","caller":"traceutil/trace.go:171","msg":"trace[1200225442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25493; }","duration":"218.925593ms","start":"2026-10-03T22:58:20.111308Z","end":"2026-10-03T22:58:20.330233Z","steps":["trace[1200225442] 'agreement among raft nodes before linearized reading' (duration: 218.708357ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:25.340492Z","caller":"traceutil/trace.go:171","msg":"trace[1465070625] transaction","detail":"{read_only:false; response_revision:25553; number_of_response:1; }","duration":"122.796865ms","start":"2026-10-03T22:58:25.217657Z","end":"2026-10-03T22:58:25.340454Z","steps":["trace[1465070625] 'process raft request' (duration: 122.597489ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:25.596863Z","caller":"traceutil/trace.go:171","msg":"trace[1158942044] transaction","detail":"{read_only:false; response_revision:25554; number_of_response:1; }","duration":"271.946975ms","start":"2026-10-03T22:58:25.324889Z","end":"2026-10-03T22:58:25.596836Z","steps":["trace[1158942044] 'process raft request' (duration: 271.7663ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:25.688467Z","caller":"traceutil/trace.go:171","msg":"trace[1859814700] compact","detail":"{revision:22162; response_revision:25554; }","duration":"346.5467ms","start":"2026-10-03T22:58:25.341865Z","end":"2026-10-03T22:58:25.688412Z","steps":["trace[1859814700] 'process raft request' (duration: 301.120148ms)","trace[1859814700] 'check and update compact revision' (duration: 45.160504ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:58:25.688558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:25.341773Z","time spent":"346.780656ms","remote":"127.0.0.1:54742","response type":"/etcdserverpb.KV/Compact","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-10-03T22:58:25.688708Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22162} {"level":"info","ts":"2026-10-03T22:58:25.688905Z","caller":"traceutil/trace.go:171","msg":"trace[806665858] transaction","detail":"{read_only:false; response_revision:25555; number_of_response:1; }","duration":"320.143162ms","start":"2026-10-03T22:58:25.368741Z","end":"2026-10-03T22:58:25.688884Z","steps":["trace[806665858] 'process raft request' (duration: 319.545925ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:25.689043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:25.368724Z","time spent":"320.226585ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:58:25.691013Z","caller":"traceutil/trace.go:171","msg":"trace[197631956] transaction","detail":"{read_only:false; response_revision:25556; number_of_response:1; }","duration":"320.831231ms","start":"2026-10-03T22:58:25.370163Z","end":"2026-10-03T22:58:25.690995Z","steps":["trace[197631956] 'process raft request' (duration: 318.710513ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:25.691099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:25.370132Z","time spent":"320.924054ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:58:25.691805Z","caller":"traceutil/trace.go:171","msg":"trace[511150288] transaction","detail":"{read_only:false; response_revision:25557; number_of_response:1; }","duration":"321.16077ms","start":"2026-10-03T22:58:25.370627Z","end":"2026-10-03T22:58:25.691788Z","steps":["trace[511150288] 'process raft request' (duration: 320.314947ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:25.691889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:25.370617Z","time spent":"321.229002ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:58:25.810816Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22162,"took":"116.421669ms","hash":4107363633,"current-db-size-bytes":55574528,"current-db-size":"56 MB","current-db-size-in-use-bytes":44937216,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-03T22:58:25.810902Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4107363633,"revision":22162,"compact-revision":19829} {"level":"info","ts":"2026-10-03T22:58:56.60523Z","caller":"traceutil/trace.go:171","msg":"trace[398914217] transaction","detail":"{read_only:false; response_revision:25804; number_of_response:1; }","duration":"116.86317ms","start":"2026-10-03T22:58:56.488344Z","end":"2026-10-03T22:58:56.605207Z","steps":["trace[398914217] 'process raft request' (duration: 116.376866ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:56.917332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.572296ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T22:58:56.917638Z","caller":"traceutil/trace.go:171","msg":"trace[1669650787] transaction","detail":"{read_only:false; response_revision:25806; number_of_response:1; }","duration":"299.72065ms","start":"2026-10-03T22:58:56.617893Z","end":"2026-10-03T22:58:56.917614Z","steps":["trace[1669650787] 'process raft request' (duration: 299.571976ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:56.917768Z","caller":"traceutil/trace.go:171","msg":"trace[840777554] transaction","detail":"{read_only:false; response_revision:25807; number_of_response:1; }","duration":"299.801982ms","start":"2026-10-03T22:58:56.617935Z","end":"2026-10-03T22:58:56.917737Z","steps":["trace[840777554] 'process raft request' (duration: 299.604727ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:56.917879Z","caller":"traceutil/trace.go:171","msg":"trace[1683351499] transaction","detail":"{read_only:false; response_revision:25805; number_of_response:1; }","duration":"300.513922ms","start":"2026-10-03T22:58:56.61735Z","end":"2026-10-03T22:58:56.917864Z","steps":["trace[1683351499] 'process raft request' (duration: 119.306218ms)","trace[1683351499] 'compare' (duration: 180.421362ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:58:56.917975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:56.617323Z","time spent":"300.593014ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:58:56.944554Z","caller":"traceutil/trace.go:171","msg":"trace[1393217244] transaction","detail":"{read_only:false; response_revision:25808; number_of_response:1; }","duration":"109.055985ms","start":"2026-10-03T22:58:56.835471Z","end":"2026-10-03T22:58:56.944527Z","steps":["trace[1393217244] 'process raft request' (duration: 108.725636ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:57.141759Z","caller":"traceutil/trace.go:171","msg":"trace[343485821] transaction","detail":"{read_only:false; response_revision:25809; number_of_response:1; }","duration":"190.316665ms","start":"2026-10-03T22:58:56.951387Z","end":"2026-10-03T22:58:57.141704Z","steps":["trace[343485821] 'process raft request' (duration: 92.470958ms)","trace[343485821] 'compare' (duration: 96.885471ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:58:57.141797Z","caller":"traceutil/trace.go:171","msg":"trace[533876809] linearizableReadLoop","detail":"{readStateIndex:26668; appliedIndex:26667; }","duration":"189.480291ms","start":"2026-10-03T22:58:56.952273Z","end":"2026-10-03T22:58:57.141753Z","steps":["trace[533876809] 'read index received' (duration: 91.564933ms)","trace[533876809] 'applied index is now lower than readState.Index' (duration: 97.908918ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:58:57.141879Z","caller":"traceutil/trace.go:171","msg":"trace[860551597] transaction","detail":"{read_only:false; response_revision:25810; number_of_response:1; }","duration":"188.726371ms","start":"2026-10-03T22:58:56.952668Z","end":"2026-10-03T22:58:57.141394Z","steps":["trace[860551597] 'process raft request' (duration: 188.651728ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:57.141959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.647586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-03T22:58:57.142037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.387343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T22:58:57.14207Z","caller":"traceutil/trace.go:171","msg":"trace[797230826] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:25810; }","duration":"179.462516ms","start":"2026-10-03T22:58:56.962595Z","end":"2026-10-03T22:58:57.142058Z","steps":["trace[797230826] 'agreement among raft nodes before linearized reading' (duration: 179.329432ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:57.142075Z","caller":"traceutil/trace.go:171","msg":"trace[1525950368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25810; }","duration":"189.782099ms","start":"2026-10-03T22:58:56.952261Z","end":"2026-10-03T22:58:57.142043Z","steps":["trace[1525950368] 'agreement among raft nodes before linearized reading' (duration: 189.576384ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:57.142147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.07546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T22:58:57.142246Z","caller":"traceutil/trace.go:171","msg":"trace[1309524797] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:25810; }","duration":"128.237994ms","start":"2026-10-03T22:58:57.013989Z","end":"2026-10-03T22:58:57.142227Z","steps":["trace[1309524797] 'agreement among raft nodes before linearized reading' (duration: 128.041179ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:58.108734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.183704ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T22:58:58.108895Z","caller":"traceutil/trace.go:171","msg":"trace[1551733125] transaction","detail":"{read_only:false; response_revision:25812; number_of_response:1; }","duration":"266.302329ms","start":"2026-10-03T22:58:57.842566Z","end":"2026-10-03T22:58:58.108868Z","steps":["trace[1551733125] 'process raft request' (duration: 109.802016ms)","trace[1551733125] 'compare' (duration: 155.66738ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:58:58.358198Z","caller":"traceutil/trace.go:171","msg":"trace[1346080218] transaction","detail":"{read_only:false; response_revision:25813; number_of_response:1; }","duration":"512.390819ms","start":"2026-10-03T22:58:57.84577Z","end":"2026-10-03T22:58:58.358161Z","steps":["trace[1346080218] 'process raft request' (duration: 492.768389ms)","trace[1346080218] 'compare' (duration: 19.153928ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:58:58.358241Z","caller":"traceutil/trace.go:171","msg":"trace[2058806543] transaction","detail":"{read_only:false; response_revision:25815; number_of_response:1; }","duration":"184.3346ms","start":"2026-10-03T22:58:58.173875Z","end":"2026-10-03T22:58:58.35821Z","steps":["trace[2058806543] 'process raft request' (duration: 184.289029ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:58.358356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:57.845721Z","time spent":"512.543404ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:58:58.358372Z","caller":"traceutil/trace.go:171","msg":"trace[1889786594] transaction","detail":"{read_only:false; response_revision:25814; number_of_response:1; }","duration":"511.243458ms","start":"2026-10-03T22:58:57.847117Z","end":"2026-10-03T22:58:58.358361Z","steps":["trace[1889786594] 'process raft request' (duration: 510.984031ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:58.358538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.046999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-03T22:58:58.35857Z","caller":"traceutil/trace.go:171","msg":"trace[2124979723] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25815; }","duration":"488.155483ms","start":"2026-10-03T22:58:57.870406Z","end":"2026-10-03T22:58:58.358561Z","steps":["trace[2124979723] 'agreement among raft nodes before linearized reading' (duration: 488.012519ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:58.358588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:57.847091Z","time spent":"511.442014ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T22:58:58.358597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:58:57.870386Z","time spent":"488.202003ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-03T22:58:58.358407Z","caller":"traceutil/trace.go:171","msg":"trace[206225276] linearizableReadLoop","detail":"{readStateIndex:26673; appliedIndex:26670; }","duration":"487.916695ms","start":"2026-10-03T22:58:57.870483Z","end":"2026-10-03T22:58:58.3584Z","steps":["trace[206225276] 'read index received' (duration: 82.198825ms)","trace[206225276] 'applied index is now lower than readState.Index' (duration: 405.71693ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:58:58.569282Z","caller":"traceutil/trace.go:171","msg":"trace[1765246611] transaction","detail":"{read_only:false; response_revision:25816; number_of_response:1; }","duration":"193.842421ms","start":"2026-10-03T22:58:58.3754Z","end":"2026-10-03T22:58:58.569242Z","steps":["trace[1765246611] 'process raft request' (duration: 129.404395ms)","trace[1765246611] 'compare' (duration: 64.080596ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:58:58.850805Z","caller":"traceutil/trace.go:171","msg":"trace[317711329] linearizableReadLoop","detail":"{readStateIndex:26676; appliedIndex:26675; }","duration":"143.808262ms","start":"2026-10-03T22:58:58.706966Z","end":"2026-10-03T22:58:58.850774Z","steps":["trace[317711329] 'read index received' (duration: 137.741235ms)","trace[317711329] 'applied index is now lower than readState.Index' (duration: 6.066427ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:58:58.850839Z","caller":"traceutil/trace.go:171","msg":"trace[2028752800] transaction","detail":"{read_only:false; response_revision:25817; number_of_response:1; }","duration":"233.67923ms","start":"2026-10-03T22:58:58.617111Z","end":"2026-10-03T22:58:58.85079Z","steps":["trace[2028752800] 'process raft request' (duration: 227.663774ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:58.850982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.032259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.206\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-03T22:58:58.851008Z","caller":"traceutil/trace.go:171","msg":"trace[1679032438] range","detail":"{range_begin:/registry/masterleases/162.253.55.206; range_end:; response_count:1; response_revision:25817; }","duration":"144.078451ms","start":"2026-10-03T22:58:58.70692Z","end":"2026-10-03T22:58:58.850999Z","steps":["trace[1679032438] 'agreement among raft nodes before linearized reading' (duration: 143.942867ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:59.018676Z","caller":"traceutil/trace.go:171","msg":"trace[1954381969] transaction","detail":"{read_only:false; response_revision:25818; number_of_response:1; }","duration":"154.118327ms","start":"2026-10-03T22:58:58.864481Z","end":"2026-10-03T22:58:59.018599Z","steps":["trace[1954381969] 'process raft request' (duration: 151.774492ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:59.17862Z","caller":"traceutil/trace.go:171","msg":"trace[861954369] transaction","detail":"{read_only:false; response_revision:25819; number_of_response:1; }","duration":"224.662362ms","start":"2026-10-03T22:58:58.953914Z","end":"2026-10-03T22:58:59.178577Z","steps":["trace[861954369] 'process raft request' (duration: 206.943153ms)","trace[861954369] 'compare' (duration: 17.186534ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:58:59.17874Z","caller":"traceutil/trace.go:171","msg":"trace[1589794241] transaction","detail":"{read_only:false; response_revision:25820; number_of_response:1; }","duration":"154.9565ms","start":"2026-10-03T22:58:59.02374Z","end":"2026-10-03T22:58:59.178697Z","steps":["trace[1589794241] 'process raft request' (duration: 154.503947ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:59.178764Z","caller":"traceutil/trace.go:171","msg":"trace[1947170476] linearizableReadLoop","detail":"{readStateIndex:26679; appliedIndex:26678; }","duration":"160.170144ms","start":"2026-10-03T22:58:59.018556Z","end":"2026-10-03T22:58:59.178726Z","steps":["trace[1947170476] 'read index received' (duration: 142.316612ms)","trace[1947170476] 'applied index is now lower than readState.Index' (duration: 17.849072ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:58:59.178931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.308289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-03T22:58:59.179004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.609503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-03T22:58:59.179079Z","caller":"traceutil/trace.go:171","msg":"trace[556188341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25821; }","duration":"255.744478ms","start":"2026-10-03T22:58:58.92331Z","end":"2026-10-03T22:58:59.179055Z","steps":["trace[556188341] 'agreement among raft nodes before linearized reading' (duration: 255.524372ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:58:59.178954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.490301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-03T22:58:59.179082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.627554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-10-03T22:58:59.179144Z","caller":"traceutil/trace.go:171","msg":"trace[945040036] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25821; }","duration":"157.759118ms","start":"2026-10-03T22:58:59.021372Z","end":"2026-10-03T22:58:59.179131Z","steps":["trace[945040036] 'agreement among raft nodes before linearized reading' (duration: 157.626174ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:59.179148Z","caller":"traceutil/trace.go:171","msg":"trace[738254871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25821; }","duration":"255.707947ms","start":"2026-10-03T22:58:58.923423Z","end":"2026-10-03T22:58:59.179131Z","steps":["trace[738254871] 'agreement among raft nodes before linearized reading' (duration: 255.355137ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:59.179082Z","caller":"traceutil/trace.go:171","msg":"trace[903229014] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25821; }","duration":"225.547085ms","start":"2026-10-03T22:58:58.953494Z","end":"2026-10-03T22:58:59.179041Z","steps":["trace[903229014] 'agreement among raft nodes before linearized reading' (duration: 225.282188ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:59.474302Z","caller":"traceutil/trace.go:171","msg":"trace[148993132] linearizableReadLoop","detail":"{readStateIndex:26685; appliedIndex:26681; }","duration":"280.664974ms","start":"2026-10-03T22:58:59.19361Z","end":"2026-10-03T22:58:59.474275Z","steps":["trace[148993132] 'read index received' (duration: 1.635015ms)","trace[148993132] 'applied index is now lower than readState.Index' (duration: 279.028509ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:58:59.474601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.979172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-10-03T22:58:59.474644Z","caller":"traceutil/trace.go:171","msg":"trace[1768192286] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25825; }","duration":"281.102266ms","start":"2026-10-03T22:58:59.193529Z","end":"2026-10-03T22:58:59.474632Z","steps":["trace[1768192286] 'agreement among raft nodes before linearized reading' (duration: 280.848349ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:59.474607Z","caller":"traceutil/trace.go:171","msg":"trace[1671146747] transaction","detail":"{read_only:false; response_revision:25823; number_of_response:1; }","duration":"290.291189ms","start":"2026-10-03T22:58:59.184279Z","end":"2026-10-03T22:58:59.47457Z","steps":["trace[1671146747] 'process raft request' (duration: 270.017591ms)","trace[1671146747] 'compare' (duration: 19.700702ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:58:59.474796Z","caller":"traceutil/trace.go:171","msg":"trace[1067808919] transaction","detail":"{read_only:false; response_revision:25824; number_of_response:1; }","duration":"289.859047ms","start":"2026-10-03T22:58:59.184927Z","end":"2026-10-03T22:58:59.474786Z","steps":["trace[1067808919] 'process raft request' (duration: 289.23897ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:59.474932Z","caller":"traceutil/trace.go:171","msg":"trace[1515886712] transaction","detail":"{read_only:false; response_revision:25825; number_of_response:1; }","duration":"289.93399ms","start":"2026-10-03T22:58:59.184984Z","end":"2026-10-03T22:58:59.474918Z","steps":["trace[1515886712] 'process raft request' (duration: 289.23689ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:58:59.915682Z","caller":"traceutil/trace.go:171","msg":"trace[966057459] transaction","detail":"{read_only:false; response_revision:25826; number_of_response:1; }","duration":"106.982718ms","start":"2026-10-03T22:58:59.808671Z","end":"2026-10-03T22:58:59.915654Z","steps":["trace[966057459] 'process raft request' (duration: 106.811133ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:59:00.687541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.154811ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T22:59:00.687816Z","caller":"traceutil/trace.go:171","msg":"trace[1225245960] linearizableReadLoop","detail":"{readStateIndex:26690; appliedIndex:26688; }","duration":"113.649542ms","start":"2026-10-03T22:59:00.574145Z","end":"2026-10-03T22:59:00.687794Z","steps":["trace[1225245960] 'read index received' (duration: 49.481µs)","trace[1225245960] 'applied index is now lower than readState.Index' (duration: 113.598711ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:59:00.687977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.837157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-03T22:59:00.688007Z","caller":"traceutil/trace.go:171","msg":"trace[1831493858] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25830; }","duration":"113.910719ms","start":"2026-10-03T22:59:00.574081Z","end":"2026-10-03T22:59:00.687992Z","steps":["trace[1831493858] 'agreement among raft nodes before linearized reading' (duration: 113.767205ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:59:00.688048Z","caller":"traceutil/trace.go:171","msg":"trace[1575288020] transaction","detail":"{read_only:false; response_revision:25830; number_of_response:1; }","duration":"312.024548ms","start":"2026-10-03T22:59:00.37599Z","end":"2026-10-03T22:59:00.688015Z","steps":["trace[1575288020] 'process raft request' (duration: 311.674208ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:59:00.688235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:59:00.375951Z","time spent":"312.179042ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:59:00.688705Z","caller":"traceutil/trace.go:171","msg":"trace[1011148322] transaction","detail":"{read_only:false; response_revision:25829; number_of_response:1; }","duration":"311.71928ms","start":"2026-10-03T22:59:00.376026Z","end":"2026-10-03T22:59:00.687746Z","steps":["trace[1011148322] 'process raft request' (duration: 150.215739ms)","trace[1011148322] 'compare' (duration: 160.696438ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:59:00.68881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:59:00.375994Z","time spent":"312.766569ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:59:01.415738Z","caller":"traceutil/trace.go:171","msg":"trace[1583910813] transaction","detail":"{read_only:false; response_revision:25835; number_of_response:1; }","duration":"139.502225ms","start":"2026-10-03T22:59:01.276193Z","end":"2026-10-03T22:59:01.415696Z","steps":["trace[1583910813] 'process raft request' (duration: 135.768882ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:59:01.721713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.867123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T22:59:01.721974Z","caller":"traceutil/trace.go:171","msg":"trace[1369124335] transaction","detail":"{read_only:false; response_revision:25837; number_of_response:1; }","duration":"238.211764ms","start":"2026-10-03T22:59:01.483711Z","end":"2026-10-03T22:59:01.721923Z","steps":["trace[1369124335] 'process raft request' (duration: 40.004622ms)","trace[1369124335] 'compare' (duration: 197.41832ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:59:01.722044Z","caller":"traceutil/trace.go:171","msg":"trace[925113852] linearizableReadLoop","detail":"{readStateIndex:26699; appliedIndex:26696; }","duration":"213.520774ms","start":"2026-10-03T22:59:01.508503Z","end":"2026-10-03T22:59:01.722024Z","steps":["trace[925113852] 'read index received' (duration: 15.173348ms)","trace[925113852] 'applied index is now lower than readState.Index' (duration: 198.346286ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:59:01.72215Z","caller":"traceutil/trace.go:171","msg":"trace[1142598744] transaction","detail":"{read_only:false; response_revision:25839; number_of_response:1; }","duration":"224.219659ms","start":"2026-10-03T22:59:01.497882Z","end":"2026-10-03T22:59:01.722102Z","steps":["trace[1142598744] 'process raft request' (duration: 224.073175ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:59:01.722211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.71296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T22:59:01.722146Z","caller":"traceutil/trace.go:171","msg":"trace[955496752] transaction","detail":"{read_only:false; response_revision:25838; number_of_response:1; }","duration":"236.843207ms","start":"2026-10-03T22:59:01.485255Z","end":"2026-10-03T22:59:01.722098Z","steps":["trace[955496752] 'process raft request' (duration: 236.5798ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:59:01.722249Z","caller":"traceutil/trace.go:171","msg":"trace[1306761339] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25839; }","duration":"213.838563ms","start":"2026-10-03T22:59:01.508395Z","end":"2026-10-03T22:59:01.722233Z","steps":["trace[1306761339] 'agreement among raft nodes before linearized reading' (duration: 213.693949ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:59:02.066145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.955789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T22:59:02.066375Z","caller":"traceutil/trace.go:171","msg":"trace[946313591] transaction","detail":"{read_only:false; response_revision:25841; number_of_response:1; }","duration":"144.751378ms","start":"2026-10-03T22:59:01.921601Z","end":"2026-10-03T22:59:02.066352Z","steps":["trace[946313591] 'process raft request' (duration: 144.678216ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:59:02.066488Z","caller":"traceutil/trace.go:171","msg":"trace[868888976] transaction","detail":"{read_only:false; response_revision:25840; number_of_response:1; }","duration":"429.275209ms","start":"2026-10-03T22:59:01.637144Z","end":"2026-10-03T22:59:02.066419Z","steps":["trace[868888976] 'process raft request' (duration: 199.912929ms)","trace[868888976] 'compare' (duration: 228.003613ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:59:02.066689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T22:59:01.637111Z","time spent":"429.463374ms","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T22:59:02.158871Z","caller":"traceutil/trace.go:171","msg":"trace[397114142] transaction","detail":"{read_only:false; response_revision:25842; number_of_response:1; }","duration":"144.276806ms","start":"2026-10-03T22:59:02.014549Z","end":"2026-10-03T22:59:02.158826Z","steps":["trace[397114142] 'process raft request' (duration: 144.017029ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:59:02.367263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.31903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T22:59:02.367462Z","caller":"traceutil/trace.go:171","msg":"trace[1725986469] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25842; }","duration":"184.582327ms","start":"2026-10-03T22:59:02.182833Z","end":"2026-10-03T22:59:02.367415Z","steps":["trace[1725986469] 'count revisions from in-memory index tree' (duration: 184.186936ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:59:02.368245Z","caller":"traceutil/trace.go:171","msg":"trace[813470532] transaction","detail":"{read_only:false; response_revision:25843; number_of_response:1; }","duration":"160.599895ms","start":"2026-10-03T22:59:02.20763Z","end":"2026-10-03T22:59:02.36823Z","steps":["trace[813470532] 'process raft request' (duration: 123.136443ms)","trace[813470532] 'compare' (duration: 36.214398ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T22:59:02.655793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.589451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T22:59:02.65587Z","caller":"traceutil/trace.go:171","msg":"trace[1514908909] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:25844; }","duration":"129.734435ms","start":"2026-10-03T22:59:02.526117Z","end":"2026-10-03T22:59:02.655852Z","steps":["trace[1514908909] 'count revisions from in-memory index tree' (duration: 129.483318ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:59:02.889246Z","caller":"traceutil/trace.go:171","msg":"trace[799847050] linearizableReadLoop","detail":"{readStateIndex:26706; appliedIndex:26704; }","duration":"139.554796ms","start":"2026-10-03T22:59:02.749657Z","end":"2026-10-03T22:59:02.889212Z","steps":["trace[799847050] 'read index received' (duration: 2.705015ms)","trace[799847050] 'applied index is now lower than readState.Index' (duration: 136.847861ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:59:02.889388Z","caller":"traceutil/trace.go:171","msg":"trace[695645027] transaction","detail":"{read_only:false; response_revision:25846; number_of_response:1; }","duration":"195.027254ms","start":"2026-10-03T22:59:02.694333Z","end":"2026-10-03T22:59:02.889361Z","steps":["trace[695645027] 'process raft request' (duration: 194.591262ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:59:02.889612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.895435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-03T22:59:02.88967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.009181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-03T22:59:02.889706Z","caller":"traceutil/trace.go:171","msg":"trace[1087817785] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:25846; }","duration":"140.157933ms","start":"2026-10-03T22:59:02.749527Z","end":"2026-10-03T22:59:02.889685Z","steps":["trace[1087817785] 'agreement among raft nodes before linearized reading' (duration: 139.849364ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:59:02.889737Z","caller":"traceutil/trace.go:171","msg":"trace[111993757] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25846; }","duration":"110.133315ms","start":"2026-10-03T22:59:02.779579Z","end":"2026-10-03T22:59:02.889713Z","steps":["trace[111993757] 'agreement among raft nodes before linearized reading' (duration: 109.836836ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:59:03.527989Z","caller":"traceutil/trace.go:171","msg":"trace[1270745058] transaction","detail":"{read_only:false; response_revision:25851; number_of_response:1; }","duration":"103.541764ms","start":"2026-10-03T22:59:03.424416Z","end":"2026-10-03T22:59:03.527958Z","steps":["trace[1270745058] 'process raft request' (duration: 103.152693ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:59:04.126684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.93709ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-03T22:59:05.044097Z","caller":"traceutil/trace.go:171","msg":"trace[850769005] linearizableReadLoop","detail":"{readStateIndex:26722; appliedIndex:26721; }","duration":"128.302036ms","start":"2026-10-03T22:59:04.915761Z","end":"2026-10-03T22:59:05.044063Z","steps":["trace[850769005] 'read index received' (duration: 128.08423ms)","trace[850769005] 'applied index is now lower than readState.Index' (duration: 216.526µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T22:59:05.044219Z","caller":"traceutil/trace.go:171","msg":"trace[559601520] transaction","detail":"{read_only:false; response_revision:25861; number_of_response:1; }","duration":"149.636344ms","start":"2026-10-03T22:59:04.89454Z","end":"2026-10-03T22:59:05.044176Z","steps":["trace[559601520] 'process raft request' (duration: 149.319715ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:59:05.044289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.493231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T22:59:05.044343Z","caller":"traceutil/trace.go:171","msg":"trace[1166211843] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25861; }","duration":"128.635665ms","start":"2026-10-03T22:59:04.915695Z","end":"2026-10-03T22:59:05.04433Z","steps":["trace[1166211843] 'agreement among raft nodes before linearized reading' (duration: 128.508812ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:59:05.057997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.886765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-03T22:59:05.058033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.802543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T22:59:05.058276Z","caller":"traceutil/trace.go:171","msg":"trace[1560118834] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25862; }","duration":"107.195664ms","start":"2026-10-03T22:59:04.951067Z","end":"2026-10-03T22:59:05.058263Z","steps":["trace[1560118834] 'agreement among raft nodes before linearized reading' (duration: 106.3126ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:59:05.058268Z","caller":"traceutil/trace.go:171","msg":"trace[1524688699] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25862; }","duration":"103.178923ms","start":"2026-10-03T22:59:04.955068Z","end":"2026-10-03T22:59:05.058247Z","steps":["trace[1524688699] 'agreement among raft nodes before linearized reading' (duration: 102.414392ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T22:59:30.021515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.967704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-03T22:59:30.021652Z","caller":"traceutil/trace.go:171","msg":"trace[1345492236] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26052; }","duration":"104.1642ms","start":"2026-10-03T22:59:29.917468Z","end":"2026-10-03T22:59:30.021632Z","steps":["trace[1345492236] 'range keys from in-memory index tree' (duration: 103.675017ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:59:30.294365Z","caller":"traceutil/trace.go:171","msg":"trace[504435496] transaction","detail":"{read_only:false; response_revision:26054; number_of_response:1; }","duration":"266.540135ms","start":"2026-10-03T22:59:30.027787Z","end":"2026-10-03T22:59:30.294327Z","steps":["trace[504435496] 'process raft request' (duration: 266.301199ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:59:30.294635Z","caller":"traceutil/trace.go:171","msg":"trace[102322164] transaction","detail":"{read_only:false; response_revision:26055; number_of_response:1; }","duration":"266.768941ms","start":"2026-10-03T22:59:30.027812Z","end":"2026-10-03T22:59:30.294581Z","steps":["trace[102322164] 'process raft request' (duration: 266.455743ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:59:30.335017Z","caller":"traceutil/trace.go:171","msg":"trace[38309081] transaction","detail":"{read_only:false; response_revision:26056; number_of_response:1; }","duration":"102.71167ms","start":"2026-10-03T22:59:30.232268Z","end":"2026-10-03T22:59:30.334979Z","steps":["trace[38309081] 'process raft request' (duration: 102.488384ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T22:59:34.324528Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000694a.wal"} {"level":"info","ts":"2026-10-03T23:03:25.697939Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25553} {"level":"info","ts":"2026-10-03T23:03:25.87496Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25553,"took":"171.118245ms","hash":2968726459,"current-db-size-bytes":57262080,"current-db-size":"57 MB","current-db-size-in-use-bytes":36208640,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-03T23:03:25.875051Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2968726459,"revision":25553,"compact-revision":22162} {"level":"info","ts":"2026-10-03T23:04:40.289242Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cfc9fec265e3384a","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-03T23:04:40.296252Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-03T23:04:40.2964Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-03T23:08:25.709378Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28494} {"level":"info","ts":"2026-10-03T23:08:25.86467Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28494,"took":"148.788862ms","hash":779591903,"current-db-size-bytes":57262080,"current-db-size":"57 MB","current-db-size-in-use-bytes":31399936,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-03T23:08:25.864781Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":779591903,"revision":28494,"compact-revision":25553} {"level":"info","ts":"2026-10-03T23:13:25.716945Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30771} {"level":"info","ts":"2026-10-03T23:13:25.866216Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30771,"took":"142.915908ms","hash":2034170612,"current-db-size-bytes":57262080,"current-db-size":"57 MB","current-db-size-in-use-bytes":30396416,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-03T23:13:25.866318Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2034170612,"revision":30771,"compact-revision":28494} {"level":"info","ts":"2026-10-03T23:18:25.72609Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33022} {"level":"info","ts":"2026-10-03T23:18:25.898452Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33022,"took":"163.865261ms","hash":4064393842,"current-db-size-bytes":58302464,"current-db-size":"58 MB","current-db-size-in-use-bytes":30130176,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-03T23:18:25.898553Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4064393842,"revision":33022,"compact-revision":30771} {"level":"info","ts":"2026-10-03T23:23:25.736954Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35249} {"level":"info","ts":"2026-10-03T23:23:25.865348Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35249,"took":"123.8747ms","hash":2272154100,"current-db-size-bytes":58302464,"current-db-size":"58 MB","current-db-size-in-use-bytes":31825920,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-03T23:23:25.865413Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2272154100,"revision":35249,"compact-revision":33022} {"level":"info","ts":"2026-10-03T23:26:05.457867Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cfc9fec265e3384a","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-03T23:26:05.46445Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-03T23:26:05.464901Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-03T23:28:25.7451Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37541} {"level":"info","ts":"2026-10-03T23:28:25.882421Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37541,"took":"130.367897ms","hash":1949534301,"current-db-size-bytes":58302464,"current-db-size":"58 MB","current-db-size-in-use-bytes":32505856,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-03T23:28:25.88251Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1949534301,"revision":37541,"compact-revision":35249} {"level":"info","ts":"2026-10-03T23:33:25.754036Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39813} {"level":"info","ts":"2026-10-03T23:33:25.906237Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39813,"took":"145.200363ms","hash":2884342501,"current-db-size-bytes":58302464,"current-db-size":"58 MB","current-db-size-in-use-bytes":29667328,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-03T23:33:25.906335Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2884342501,"revision":39813,"compact-revision":37541}