{"level":"warn","ts":"2026-09-09T06:16:23.120255Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-09T06:16:23.120361Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.56: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.56:2380","--initial-cluster=instance=https://162.253.55.56:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.56:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.56: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-09-09T06:16:23.120434Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-09T06:16:23.120446Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.56:2380"]} {"level":"info","ts":"2026-09-09T06:16:23.120466Z","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-09-09T06:16:23.1212Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.56:2379"]} {"level":"info","ts":"2026-09-09T06:16:23.1213Z","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.56:2380"],"listen-peer-urls":["https://162.253.55.56:2380"],"advertise-client-urls":["https://162.253.55.56:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.56:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.56: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-09-09T06:16:23.124634Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.060726ms"} {"level":"info","ts":"2026-09-09T06:16:23.13778Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c9fb5245756f3898","cluster-id":"2527a2eb37d698df"} {"level":"info","ts":"2026-09-09T06:16:23.137857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9fb5245756f3898 switched to configuration voters=()"} {"level":"info","ts":"2026-09-09T06:16:23.137874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9fb5245756f3898 became follower at term 0"} {"level":"info","ts":"2026-09-09T06:16:23.137884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c9fb5245756f3898 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-09T06:16:23.137895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9fb5245756f3898 became follower at term 1"} {"level":"info","ts":"2026-09-09T06:16:23.137986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9fb5245756f3898 switched to configuration voters=(14554317079054334104)"} {"level":"warn","ts":"2026-09-09T06:16:23.143853Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-09T06:16:23.145726Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-09T06:16:23.147954Z","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-09-09T06:16:23.150817Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c9fb5245756f3898","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-09T06:16:23.151615Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c9fb5245756f3898","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-09T06:16:23.151627Z","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-09-09T06:16:23.152463Z","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-09-09T06:16:23.15254Z","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-09-09T06:16:23.15357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9fb5245756f3898 switched to configuration voters=(14554317079054334104)"} {"level":"info","ts":"2026-09-09T06:16:23.154052Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2527a2eb37d698df","local-member-id":"c9fb5245756f3898","added-peer-id":"c9fb5245756f3898","added-peer-peer-urls":["https://162.253.55.56:2380"]} {"level":"info","ts":"2026-09-09T06:16:23.15836Z","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-09-09T06:16:23.158485Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.56:2380"} {"level":"info","ts":"2026-09-09T06:16:23.158616Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.56:2380"} {"level":"info","ts":"2026-09-09T06:16:23.15874Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c9fb5245756f3898","initial-advertise-peer-urls":["https://162.253.55.56:2380"],"listen-peer-urls":["https://162.253.55.56:2380"],"advertise-client-urls":["https://162.253.55.56:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.56:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-09T06:16:23.158782Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-09T06:16:23.638902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9fb5245756f3898 is starting a new election at term 1"} {"level":"info","ts":"2026-09-09T06:16:23.638977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9fb5245756f3898 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-09T06:16:23.638998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9fb5245756f3898 received MsgPreVoteResp from c9fb5245756f3898 at term 1"} {"level":"info","ts":"2026-09-09T06:16:23.639012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9fb5245756f3898 became candidate at term 2"} {"level":"info","ts":"2026-09-09T06:16:23.63902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9fb5245756f3898 received MsgVoteResp from c9fb5245756f3898 at term 2"} {"level":"info","ts":"2026-09-09T06:16:23.63903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c9fb5245756f3898 became leader at term 2"} {"level":"info","ts":"2026-09-09T06:16:23.63904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c9fb5245756f3898 elected leader c9fb5245756f3898 at term 2"} {"level":"info","ts":"2026-09-09T06:16:23.640384Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:16:23.641132Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c9fb5245756f3898","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.56:2379]}","request-path":"/0/members/c9fb5245756f3898/attributes","cluster-id":"2527a2eb37d698df","publish-timeout":"7s"} {"level":"info","ts":"2026-09-09T06:16:23.641155Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T06:16:23.6412Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T06:16:23.641451Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-09T06:16:23.641511Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-09T06:16:23.641779Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2527a2eb37d698df","local-member-id":"c9fb5245756f3898","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:16:23.641977Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:16:23.642038Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:16:23.643617Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-09T06:16:23.655231Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.56:2379"} {"level":"info","ts":"2026-09-09T06:17:18.190816Z","caller":"traceutil/trace.go:171","msg":"trace[1675364852] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"226.393918ms","start":"2026-09-09T06:17:17.964384Z","end":"2026-09-09T06:17:18.190778Z","steps":["trace[1675364852] 'process raft request' (duration: 226.175492ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:17:18.697364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.038947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:17:18.69765Z","caller":"traceutil/trace.go:171","msg":"trace[784578000] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:645; }","duration":"538.210587ms","start":"2026-09-09T06:17:18.159414Z","end":"2026-09-09T06:17:18.697624Z","steps":["trace[784578000] 'read index received' (duration: 31.135206ms)","trace[784578000] 'applied index is now lower than readState.Index' (duration: 507.07398ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:17:18.697712Z","caller":"traceutil/trace.go:171","msg":"trace[1975215027] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"716.729179ms","start":"2026-09-09T06:17:17.980953Z","end":"2026-09-09T06:17:18.697682Z","steps":["trace[1975215027] 'process raft request' (duration: 364.489452ms)","trace[1975215027] 'compare' (duration: 350.828381ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:17:18.69784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.438711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:17:18.69783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:17:17.980902Z","time spent":"716.871342ms","remote":"127.0.0.1:34076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:17:18.697936Z","caller":"traceutil/trace.go:171","msg":"trace[1169935398] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"538.553715ms","start":"2026-09-09T06:17:18.15934Z","end":"2026-09-09T06:17:18.697894Z","steps":["trace[1169935398] 'agreement among raft nodes before linearized reading' (duration: 538.417282ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:17:18.69799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:17:18.159303Z","time spent":"538.675647ms","remote":"127.0.0.1:33928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-09T06:17:18.698007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.277164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-09-09T06:17:18.698008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.597534ms","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-09-09T06:17:18.69807Z","caller":"traceutil/trace.go:171","msg":"trace[1354092464] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"511.392087ms","start":"2026-09-09T06:17:18.186658Z","end":"2026-09-09T06:17:18.69805Z","steps":["trace[1354092464] 'agreement among raft nodes before linearized reading' (duration: 511.149631ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:18.698082Z","caller":"traceutil/trace.go:171","msg":"trace[894117161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"113.710067ms","start":"2026-09-09T06:17:18.584348Z","end":"2026-09-09T06:17:18.698058Z","steps":["trace[894117161] 'agreement among raft nodes before linearized reading' (duration: 113.466891ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:18.69801Z","caller":"traceutil/trace.go:171","msg":"trace[1739766476] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"659.532823ms","start":"2026-09-09T06:17:18.038402Z","end":"2026-09-09T06:17:18.697935Z","steps":["trace[1739766476] 'process raft request' (duration: 659.138603ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:17:18.698114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:17:18.186633Z","time spent":"511.469739ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-09T06:17:18.698381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:17:18.038368Z","time spent":"659.781558ms","remote":"127.0.0.1:34368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-09T06:17:18.931457Z","caller":"traceutil/trace.go:171","msg":"trace[1909833388] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"227.641529ms","start":"2026-09-09T06:17:18.70376Z","end":"2026-09-09T06:17:18.931401Z","steps":["trace[1909833388] 'process raft request' (duration: 173.101608ms)","trace[1909833388] 'compare' (duration: 54.299565ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:17:18.93405Z","caller":"traceutil/trace.go:171","msg":"trace[630092759] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:648; }","duration":"220.332826ms","start":"2026-09-09T06:17:18.713684Z","end":"2026-09-09T06:17:18.934017Z","steps":["trace[630092759] 'read index received' (duration: 163.207321ms)","trace[630092759] 'applied index is now lower than readState.Index' (duration: 57.123775ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:17:18.93419Z","caller":"traceutil/trace.go:171","msg":"trace[556547231] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"229.69463ms","start":"2026-09-09T06:17:18.704468Z","end":"2026-09-09T06:17:18.934162Z","steps":["trace[556547231] 'process raft request' (duration: 229.288019ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:17:18.934208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.502801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:17:18.934292Z","caller":"traceutil/trace.go:171","msg":"trace[668459349] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:623; }","duration":"220.641383ms","start":"2026-09-09T06:17:18.713635Z","end":"2026-09-09T06:17:18.934276Z","steps":["trace[668459349] 'agreement among raft nodes before linearized reading' (duration: 220.49855ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:20.165975Z","caller":"traceutil/trace.go:171","msg":"trace[76419600] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"115.535832ms","start":"2026-09-09T06:17:20.05036Z","end":"2026-09-09T06:17:20.165896Z","steps":["trace[76419600] 'process raft request' (duration: 113.377629ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:35.512583Z","caller":"traceutil/trace.go:171","msg":"trace[1131538885] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"221.561797ms","start":"2026-09-09T06:17:35.290995Z","end":"2026-09-09T06:17:35.512557Z","steps":["trace[1131538885] 'process raft request' (duration: 221.404523ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:18:09.753517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.157799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T06:18:09.753605Z","caller":"traceutil/trace.go:171","msg":"trace[2063156979] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1207; }","duration":"158.296693ms","start":"2026-09-09T06:18:09.59529Z","end":"2026-09-09T06:18:09.753587Z","steps":["trace[2063156979] 'count revisions from in-memory index tree' (duration: 158.050377ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:18:54.82458Z","caller":"traceutil/trace.go:171","msg":"trace[835071397] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"115.772088ms","start":"2026-09-09T06:18:54.708767Z","end":"2026-09-09T06:18:54.824539Z","steps":["trace[835071397] 'process raft request' (duration: 115.585494ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:19:02.183754Z","caller":"traceutil/trace.go:171","msg":"trace[1052689828] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"131.782961ms","start":"2026-09-09T06:19:02.051941Z","end":"2026-09-09T06:19:02.183724Z","steps":["trace[1052689828] 'process raft request' (duration: 70.018239ms)","trace[1052689828] 'compare' (duration: 61.603518ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:19:03.947177Z","caller":"traceutil/trace.go:171","msg":"trace[751132394] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"108.055015ms","start":"2026-09-09T06:19:03.839099Z","end":"2026-09-09T06:19:03.947154Z","steps":["trace[751132394] 'process raft request' (duration: 107.84743ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:19:08.064779Z","caller":"traceutil/trace.go:171","msg":"trace[544700569] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"103.990183ms","start":"2026-09-09T06:19:07.960754Z","end":"2026-09-09T06:19:08.064744Z","steps":["trace[544700569] 'process raft request' (duration: 103.807108ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:19:08.083548Z","caller":"traceutil/trace.go:171","msg":"trace[1381882962] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"112.067345ms","start":"2026-09-09T06:19:07.971451Z","end":"2026-09-09T06:19:08.083519Z","steps":["trace[1381882962] 'process raft request' (duration: 111.819289ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:19:40.71291Z","caller":"traceutil/trace.go:171","msg":"trace[1573977988] linearizableReadLoop","detail":"{readStateIndex:2255; appliedIndex:2254; }","duration":"237.704042ms","start":"2026-09-09T06:19:40.475176Z","end":"2026-09-09T06:19:40.71288Z","steps":["trace[1573977988] 'read index received' (duration: 217.931526ms)","trace[1573977988] 'applied index is now lower than readState.Index' (duration: 19.771036ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:19:40.713029Z","caller":"traceutil/trace.go:171","msg":"trace[2116710619] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"281.458962ms","start":"2026-09-09T06:19:40.431502Z","end":"2026-09-09T06:19:40.712961Z","steps":["trace[2116710619] 'process raft request' (duration: 261.593314ms)","trace[2116710619] 'compare' (duration: 19.609192ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:19:40.713359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.171683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-09-09T06:19:40.71341Z","caller":"traceutil/trace.go:171","msg":"trace[446169413] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2165; }","duration":"238.273966ms","start":"2026-09-09T06:19:40.475122Z","end":"2026-09-09T06:19:40.713396Z","steps":["trace[446169413] 'agreement among raft nodes before linearized reading' (duration: 237.901147ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:19:40.8843Z","caller":"traceutil/trace.go:171","msg":"trace[816170151] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"165.918238ms","start":"2026-09-09T06:19:40.71835Z","end":"2026-09-09T06:19:40.884268Z","steps":["trace[816170151] 'process raft request' (duration: 165.839576ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:19:40.884349Z","caller":"traceutil/trace.go:171","msg":"trace[237115057] linearizableReadLoop","detail":"{readStateIndex:2256; appliedIndex:2255; }","duration":"171.290723ms","start":"2026-09-09T06:19:40.713002Z","end":"2026-09-09T06:19:40.884292Z","steps":["trace[237115057] 'read index received' (duration: 120.947639ms)","trace[237115057] 'applied index is now lower than readState.Index' (duration: 50.340844ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:19:40.884462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.036422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T06:19:40.88452Z","caller":"traceutil/trace.go:171","msg":"trace[1142394878] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:2167; }","duration":"248.156144ms","start":"2026-09-09T06:19:40.63635Z","end":"2026-09-09T06:19:40.884507Z","steps":["trace[1142394878] 'agreement among raft nodes before linearized reading' (duration: 248.037052ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:19:40.884305Z","caller":"traceutil/trace.go:171","msg":"trace[2078675422] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"360.723233ms","start":"2026-09-09T06:19:40.523547Z","end":"2026-09-09T06:19:40.88427Z","steps":["trace[2078675422] 'process raft request' (duration: 310.397059ms)","trace[2078675422] 'compare' (duration: 50.137949ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:19:40.88471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:19:40.523533Z","time spent":"361.088352ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:20:35.354084Z","caller":"traceutil/trace.go:171","msg":"trace[994633356] transaction","detail":"{read_only:false; response_revision:2632; number_of_response:1; }","duration":"243.472556ms","start":"2026-09-09T06:20:35.110586Z","end":"2026-09-09T06:20:35.354059Z","steps":["trace[994633356] 'process raft request' (duration: 243.250951ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:21:14.361808Z","caller":"traceutil/trace.go:171","msg":"trace[1039183157] linearizableReadLoop","detail":"{readStateIndex:2983; appliedIndex:2982; }","duration":"120.172318ms","start":"2026-09-09T06:21:14.241602Z","end":"2026-09-09T06:21:14.361774Z","steps":["trace[1039183157] 'read index received' (duration: 119.873261ms)","trace[1039183157] 'applied index is now lower than readState.Index' (duration: 297.857µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:21:14.361873Z","caller":"traceutil/trace.go:171","msg":"trace[281533891] transaction","detail":"{read_only:false; response_revision:2866; number_of_response:1; }","duration":"142.055806ms","start":"2026-09-09T06:21:14.219777Z","end":"2026-09-09T06:21:14.361833Z","steps":["trace[281533891] 'process raft request' (duration: 141.821021ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:21:14.362242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.614459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-09T06:21:14.362372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.478336ms","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-09-09T06:21:14.362346Z","caller":"traceutil/trace.go:171","msg":"trace[1775100782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2866; }","duration":"120.758823ms","start":"2026-09-09T06:21:14.241533Z","end":"2026-09-09T06:21:14.362292Z","steps":["trace[1775100782] 'agreement among raft nodes before linearized reading' (duration: 120.444936ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:21:14.362421Z","caller":"traceutil/trace.go:171","msg":"trace[164553620] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2866; }","duration":"114.770773ms","start":"2026-09-09T06:21:14.247639Z","end":"2026-09-09T06:21:14.36241Z","steps":["trace[164553620] 'agreement among raft nodes before linearized reading' (duration: 114.527607ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:24:31.095156Z","caller":"traceutil/trace.go:171","msg":"trace[1079061662] transaction","detail":"{read_only:false; response_revision:4098; number_of_response:1; }","duration":"254.302486ms","start":"2026-09-09T06:24:30.840828Z","end":"2026-09-09T06:24:31.09513Z","steps":["trace[1079061662] 'process raft request' (duration: 254.157392ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:26:00.112183Z","caller":"traceutil/trace.go:171","msg":"trace[625492950] transaction","detail":"{read_only:false; response_revision:5110; number_of_response:1; }","duration":"133.529238ms","start":"2026-09-09T06:25:59.97861Z","end":"2026-09-09T06:26:00.112139Z","steps":["trace[625492950] 'process raft request' (duration: 53.81972ms)","trace[625492950] 'compare' (duration: 79.504083ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:26:05.760861Z","caller":"traceutil/trace.go:171","msg":"trace[1169740656] transaction","detail":"{read_only:false; response_revision:5309; number_of_response:1; }","duration":"213.364061ms","start":"2026-09-09T06:26:05.547465Z","end":"2026-09-09T06:26:05.760829Z","steps":["trace[1169740656] 'process raft request' (duration: 207.720889ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:26:05.761073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.155943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-09T06:26:05.761143Z","caller":"traceutil/trace.go:171","msg":"trace[1467111483] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5309; }","duration":"126.308457ms","start":"2026-09-09T06:26:05.63482Z","end":"2026-09-09T06:26:05.761128Z","steps":["trace[1467111483] 'agreement among raft nodes before linearized reading' (duration: 126.077121ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:26:05.76086Z","caller":"traceutil/trace.go:171","msg":"trace[1232368184] linearizableReadLoop","detail":"{readStateIndex:5520; appliedIndex:5519; }","duration":"125.901157ms","start":"2026-09-09T06:26:05.634901Z","end":"2026-09-09T06:26:05.760802Z","steps":["trace[1232368184] 'read index received' (duration: 120.198134ms)","trace[1232368184] 'applied index is now lower than readState.Index' (duration: 5.701623ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:26:06.054097Z","caller":"traceutil/trace.go:171","msg":"trace[51190785] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5312; }","duration":"122.908043ms","start":"2026-09-09T06:26:05.931132Z","end":"2026-09-09T06:26:06.05404Z","steps":["trace[51190785] 'process raft request' (duration: 122.743399ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:26:14.715941Z","caller":"traceutil/trace.go:171","msg":"trace[1745647530] transaction","detail":"{read_only:false; response_revision:5477; number_of_response:1; }","duration":"121.124187ms","start":"2026-09-09T06:26:14.594786Z","end":"2026-09-09T06:26:14.71591Z","steps":["trace[1745647530] 'process raft request' (duration: 110.911641ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:26:14.716165Z","caller":"traceutil/trace.go:171","msg":"trace[429079448] transaction","detail":"{read_only:false; response_revision:5478; number_of_response:1; }","duration":"120.382128ms","start":"2026-09-09T06:26:14.595772Z","end":"2026-09-09T06:26:14.716154Z","steps":["trace[429079448] 'process raft request' (duration: 120.205114ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:26:14.716157Z","caller":"traceutil/trace.go:171","msg":"trace[515596688] linearizableReadLoop","detail":"{readStateIndex:5697; appliedIndex:5694; }","duration":"111.021554ms","start":"2026-09-09T06:26:14.605111Z","end":"2026-09-09T06:26:14.716133Z","steps":["trace[515596688] 'read index received' (duration: 100.655694ms)","trace[515596688] 'applied index is now lower than readState.Index' (duration: 10.36483ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:26:14.716338Z","caller":"traceutil/trace.go:171","msg":"trace[771046165] transaction","detail":"{read_only:false; response_revision:5479; number_of_response:1; }","duration":"120.361488ms","start":"2026-09-09T06:26:14.595957Z","end":"2026-09-09T06:26:14.716319Z","steps":["trace[771046165] 'process raft request' (duration: 120.137673ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:26:14.716396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.25328ms","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-09-09T06:26:14.716426Z","caller":"traceutil/trace.go:171","msg":"trace[1272567984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5479; }","duration":"111.359622ms","start":"2026-09-09T06:26:14.605058Z","end":"2026-09-09T06:26:14.716417Z","steps":["trace[1272567984] 'agreement among raft nodes before linearized reading' (duration: 111.161627ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:26:21.483842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.233101ms","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-09-09T06:26:21.483989Z","caller":"traceutil/trace.go:171","msg":"trace[272767227] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5594; }","duration":"210.458516ms","start":"2026-09-09T06:26:21.273487Z","end":"2026-09-09T06:26:21.483946Z","steps":["trace[272767227] 'range keys from in-memory index tree' (duration: 210.006825ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:26:21.48403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.37431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-5xhwm\" ","response":"range_response_count:1 size:7817"} {"level":"info","ts":"2026-09-09T06:26:21.484144Z","caller":"traceutil/trace.go:171","msg":"trace[376951830] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-5xhwm; range_end:; response_count:1; response_revision:5594; }","duration":"540.573134ms","start":"2026-09-09T06:26:20.943547Z","end":"2026-09-09T06:26:21.48412Z","steps":["trace[376951830] 'range keys from in-memory index tree' (duration: 540.083302ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:26:21.484193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:26:20.943521Z","time spent":"540.661928ms","remote":"127.0.0.1:34076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":7840,"request content":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-5xhwm\" "} {"level":"warn","ts":"2026-09-09T06:26:21.484187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.891635ms","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-09-09T06:26:21.484244Z","caller":"traceutil/trace.go:171","msg":"trace[930154492] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5594; }","duration":"284.975806ms","start":"2026-09-09T06:26:21.199255Z","end":"2026-09-09T06:26:21.484231Z","steps":["trace[930154492] 'range keys from in-memory index tree' (duration: 284.703799ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:26:21.642313Z","caller":"traceutil/trace.go:171","msg":"trace[1920959085] transaction","detail":"{read_only:false; response_revision:5596; number_of_response:1; }","duration":"151.428677ms","start":"2026-09-09T06:26:21.490845Z","end":"2026-09-09T06:26:21.642274Z","steps":["trace[1920959085] 'process raft request' (duration: 125.590248ms)","trace[1920959085] 'compare' (duration: 25.443398ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:26:21.642316Z","caller":"traceutil/trace.go:171","msg":"trace[910212398] transaction","detail":"{read_only:false; response_revision:5597; number_of_response:1; }","duration":"145.835187ms","start":"2026-09-09T06:26:21.496437Z","end":"2026-09-09T06:26:21.642272Z","steps":["trace[910212398] 'process raft request' (duration: 145.722224ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:26:23.81525Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2918} {"level":"info","ts":"2026-09-09T06:26:23.897699Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2918,"took":"80.376385ms","hash":2531996818,"current-db-size-bytes":54624256,"current-db-size":"55 MB","current-db-size-in-use-bytes":31342592,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-09T06:26:23.897823Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2531996818,"revision":2918,"compact-revision":-1} {"level":"info","ts":"2026-09-09T06:26:29.44572Z","caller":"traceutil/trace.go:171","msg":"trace[42015581] linearizableReadLoop","detail":"{readStateIndex:6019; appliedIndex:6018; }","duration":"161.170601ms","start":"2026-09-09T06:26:29.284513Z","end":"2026-09-09T06:26:29.445683Z","steps":["trace[42015581] 'read index received' (duration: 160.865954ms)","trace[42015581] 'applied index is now lower than readState.Index' (duration: 303.177µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:26:29.446183Z","caller":"traceutil/trace.go:171","msg":"trace[1604015507] transaction","detail":"{read_only:false; response_revision:5794; number_of_response:1; }","duration":"187.010689ms","start":"2026-09-09T06:26:29.259153Z","end":"2026-09-09T06:26:29.446164Z","steps":["trace[1604015507] 'process raft request' (duration: 186.331382ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:26:29.44658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.048753ms","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-09-09T06:26:29.446661Z","caller":"traceutil/trace.go:171","msg":"trace[1372583866] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5794; }","duration":"162.196566ms","start":"2026-09-09T06:26:29.284444Z","end":"2026-09-09T06:26:29.446641Z","steps":["trace[1372583866] 'agreement among raft nodes before linearized reading' (duration: 161.977491ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:26:29.732652Z","caller":"traceutil/trace.go:171","msg":"trace[1314723026] linearizableReadLoop","detail":"{readStateIndex:6022; appliedIndex:6020; }","duration":"243.3021ms","start":"2026-09-09T06:26:29.489321Z","end":"2026-09-09T06:26:29.732623Z","steps":["trace[1314723026] 'read index received' (duration: 196.111787ms)","trace[1314723026] 'applied index is now lower than readState.Index' (duration: 47.188883ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:26:29.732849Z","caller":"traceutil/trace.go:171","msg":"trace[794869785] transaction","detail":"{read_only:false; response_revision:5797; number_of_response:1; }","duration":"273.246091ms","start":"2026-09-09T06:26:29.459585Z","end":"2026-09-09T06:26:29.732831Z","steps":["trace[794869785] 'process raft request' (duration: 272.941644ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:26:29.733173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.835254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18115"} {"level":"info","ts":"2026-09-09T06:26:29.733238Z","caller":"traceutil/trace.go:171","msg":"trace[2076062111] transaction","detail":"{read_only:false; response_revision:5796; number_of_response:1; }","duration":"280.219966ms","start":"2026-09-09T06:26:29.452986Z","end":"2026-09-09T06:26:29.733206Z","steps":["trace[2076062111] 'process raft request' (duration: 232.455269ms)","trace[2076062111] 'compare' (duration: 46.884565ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:26:29.733257Z","caller":"traceutil/trace.go:171","msg":"trace[1556472952] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5797; }","duration":"241.973267ms","start":"2026-09-09T06:26:29.491264Z","end":"2026-09-09T06:26:29.733238Z","steps":["trace[1556472952] 'agreement among raft nodes before linearized reading' (duration: 241.66394ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:26:29.733429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.841372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-09T06:26:29.733505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.767841ms","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-09-09T06:26:29.733532Z","caller":"traceutil/trace.go:171","msg":"trace[1312788722] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:5797; }","duration":"143.004587ms","start":"2026-09-09T06:26:29.590499Z","end":"2026-09-09T06:26:29.733503Z","steps":["trace[1312788722] 'agreement among raft nodes before linearized reading' (duration: 142.75417ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:26:29.733569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.573023ms","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-09-09T06:26:29.733676Z","caller":"traceutil/trace.go:171","msg":"trace[322250685] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5797; }","duration":"183.776968ms","start":"2026-09-09T06:26:29.549875Z","end":"2026-09-09T06:26:29.733652Z","steps":["trace[322250685] 'agreement among raft nodes before linearized reading' (duration: 183.442049ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:26:29.733605Z","caller":"traceutil/trace.go:171","msg":"trace[1017074377] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5797; }","duration":"278.902673ms","start":"2026-09-09T06:26:29.454676Z","end":"2026-09-09T06:26:29.733579Z","steps":["trace[1017074377] 'agreement among raft nodes before linearized reading' (duration: 278.567594ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:26:40.293502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.289982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:26:40.293621Z","caller":"traceutil/trace.go:171","msg":"trace[1249831172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5913; }","duration":"127.478757ms","start":"2026-09-09T06:26:40.166117Z","end":"2026-09-09T06:26:40.293595Z","steps":["trace[1249831172] 'range keys from in-memory index tree' (duration: 127.160898ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:26:40.293508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.784168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:26:40.293737Z","caller":"traceutil/trace.go:171","msg":"trace[1082471367] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5913; }","duration":"117.053785ms","start":"2026-09-09T06:26:40.176662Z","end":"2026-09-09T06:26:40.293716Z","steps":["trace[1082471367] 'range keys from in-memory index tree' (duration: 116.655226ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:26:40.584215Z","caller":"traceutil/trace.go:171","msg":"trace[1928968053] transaction","detail":"{read_only:false; response_revision:5918; number_of_response:1; }","duration":"108.193223ms","start":"2026-09-09T06:26:40.475975Z","end":"2026-09-09T06:26:40.584169Z","steps":["trace[1928968053] 'process raft request' (duration: 71.018631ms)","trace[1928968053] 'compare' (duration: 37.002468ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:26:40.595523Z","caller":"traceutil/trace.go:171","msg":"trace[1454158545] linearizableReadLoop","detail":"{readStateIndex:6146; appliedIndex:6144; }","duration":"116.49228ms","start":"2026-09-09T06:26:40.479001Z","end":"2026-09-09T06:26:40.595493Z","steps":["trace[1454158545] 'read index received' (duration: 67.982754ms)","trace[1454158545] 'applied index is now lower than readState.Index' (duration: 48.508036ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:26:40.595738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.701957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:26:40.595788Z","caller":"traceutil/trace.go:171","msg":"trace[1541571803] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:5921; }","duration":"116.81684ms","start":"2026-09-09T06:26:40.478955Z","end":"2026-09-09T06:26:40.595772Z","steps":["trace[1541571803] 'agreement among raft nodes before linearized reading' (duration: 116.657315ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:26:40.595782Z","caller":"traceutil/trace.go:171","msg":"trace[666476454] transaction","detail":"{read_only:false; response_revision:5920; number_of_response:1; }","duration":"116.646675ms","start":"2026-09-09T06:26:40.479107Z","end":"2026-09-09T06:26:40.595754Z","steps":["trace[666476454] 'process raft request' (duration: 116.229514ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:26:40.595813Z","caller":"traceutil/trace.go:171","msg":"trace[957629190] transaction","detail":"{read_only:false; response_revision:5919; number_of_response:1; }","duration":"117.524367ms","start":"2026-09-09T06:26:40.478258Z","end":"2026-09-09T06:26:40.595782Z","steps":["trace[957629190] 'process raft request' (duration: 116.884001ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:26:40.59591Z","caller":"traceutil/trace.go:171","msg":"trace[436136425] transaction","detail":"{read_only:false; response_revision:5921; number_of_response:1; }","duration":"115.119627ms","start":"2026-09-09T06:26:40.480764Z","end":"2026-09-09T06:26:40.595883Z","steps":["trace[436136425] 'process raft request' (duration: 114.668696ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:31:23.822823Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5648} {"level":"info","ts":"2026-09-09T06:31:23.930064Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5648,"took":"105.339866ms","hash":3251168801,"current-db-size-bytes":54624256,"current-db-size":"55 MB","current-db-size-in-use-bytes":26329088,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-09T06:31:23.930159Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3251168801,"revision":5648,"compact-revision":2918} {"level":"info","ts":"2026-09-09T06:32:34.856595Z","caller":"traceutil/trace.go:171","msg":"trace[1630051574] linearizableReadLoop","detail":"{readStateIndex:8896; appliedIndex:8895; }","duration":"454.312952ms","start":"2026-09-09T06:32:34.402252Z","end":"2026-09-09T06:32:34.856564Z","steps":["trace[1630051574] 'read index received' (duration: 454.151058ms)","trace[1630051574] 'applied index is now lower than readState.Index' (duration: 160.384µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:32:34.856657Z","caller":"traceutil/trace.go:171","msg":"trace[668179685] transaction","detail":"{read_only:false; response_revision:8585; number_of_response:1; }","duration":"477.30196ms","start":"2026-09-09T06:32:34.379319Z","end":"2026-09-09T06:32:34.856621Z","steps":["trace[668179685] 'process raft request' (duration: 477.079555ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:34.856857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.602691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-09T06:32:34.856874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.379301Z","time spent":"477.444574ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:32:34.856929Z","caller":"traceutil/trace.go:171","msg":"trace[219634252] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8585; }","duration":"454.713533ms","start":"2026-09-09T06:32:34.402196Z","end":"2026-09-09T06:32:34.85691Z","steps":["trace[219634252] 'agreement among raft nodes before linearized reading' (duration: 454.523738ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:34.856972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.402174Z","time spent":"454.786925ms","remote":"127.0.0.1:34150","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":"info","ts":"2026-09-09T06:32:35.079849Z","caller":"traceutil/trace.go:171","msg":"trace[1307407400] transaction","detail":"{read_only:false; response_revision:8586; number_of_response:1; }","duration":"231.141346ms","start":"2026-09-09T06:32:34.848665Z","end":"2026-09-09T06:32:35.079806Z","steps":["trace[1307407400] 'process raft request' (duration: 210.520508ms)","trace[1307407400] 'compare' (duration: 20.431564ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:32:35.080593Z","caller":"traceutil/trace.go:171","msg":"trace[1654447590] linearizableReadLoop","detail":"{readStateIndex:8897; appliedIndex:8896; }","duration":"223.872344ms","start":"2026-09-09T06:32:34.856687Z","end":"2026-09-09T06:32:35.080559Z","steps":["trace[1654447590] 'read index received' (duration: 202.517068ms)","trace[1654447590] 'applied index is now lower than readState.Index' (duration: 21.350626ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:32:35.080755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.59904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.08082Z","caller":"traceutil/trace.go:171","msg":"trace[1134645179] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8586; }","duration":"374.726283ms","start":"2026-09-09T06:32:34.706078Z","end":"2026-09-09T06:32:35.080804Z","steps":["trace[1134645179] 'agreement among raft nodes before linearized reading' (duration: 374.60716ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.080836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.487875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:32:35.080805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.054025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:32:35.080876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.70605Z","time spent":"374.809837ms","remote":"127.0.0.1:38350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true "} {"level":"info","ts":"2026-09-09T06:32:35.080887Z","caller":"traceutil/trace.go:171","msg":"trace[292334897] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8586; }","duration":"225.588338ms","start":"2026-09-09T06:32:34.855284Z","end":"2026-09-09T06:32:35.080872Z","steps":["trace[292334897] 'agreement among raft nodes before linearized reading' (duration: 225.507235ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:32:35.080924Z","caller":"traceutil/trace.go:171","msg":"trace[1001045532] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8586; }","duration":"438.2247ms","start":"2026-09-09T06:32:34.64267Z","end":"2026-09-09T06:32:35.080895Z","steps":["trace[1001045532] 'agreement among raft nodes before linearized reading' (duration: 438.029164ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.080991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.642658Z","time spent":"438.313142ms","remote":"127.0.0.1:38318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:500 "} {"level":"warn","ts":"2026-09-09T06:32:35.081174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.901365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.081236Z","caller":"traceutil/trace.go:171","msg":"trace[318893235] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8586; }","duration":"225.989757ms","start":"2026-09-09T06:32:34.855231Z","end":"2026-09-09T06:32:35.081221Z","steps":["trace[318893235] 'agreement among raft nodes before linearized reading' (duration: 225.893885ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.081341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.278408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.08143Z","caller":"traceutil/trace.go:171","msg":"trace[726800266] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8586; }","duration":"375.405321ms","start":"2026-09-09T06:32:34.70601Z","end":"2026-09-09T06:32:35.081416Z","steps":["trace[726800266] 'agreement among raft nodes before linearized reading' (duration: 375.278248ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.081487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.705989Z","time spent":"375.484302ms","remote":"127.0.0.1:38350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:10000 "} {"level":"warn","ts":"2026-09-09T06:32:35.081432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.614561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-09T06:32:35.08221Z","caller":"traceutil/trace.go:171","msg":"trace[354654358] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8586; }","duration":"459.472732ms","start":"2026-09-09T06:32:34.622705Z","end":"2026-09-09T06:32:35.082178Z","steps":["trace[354654358] 'agreement among raft nodes before linearized reading' (duration: 458.514478ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.081191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.132421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:32:35.082299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.622678Z","time spent":"459.582585ms","remote":"127.0.0.1:34076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-09T06:32:35.082411Z","caller":"traceutil/trace.go:171","msg":"trace[449868206] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8586; }","duration":"440.311092ms","start":"2026-09-09T06:32:34.642016Z","end":"2026-09-09T06:32:35.082327Z","steps":["trace[449868206] 'agreement among raft nodes before linearized reading' (duration: 439.131243ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.08249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.641995Z","time spent":"440.471526ms","remote":"127.0.0.1:38318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:10000 "} {"level":"warn","ts":"2026-09-09T06:32:35.081493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.299945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.082612Z","caller":"traceutil/trace.go:171","msg":"trace[221507037] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8586; }","duration":"227.418733ms","start":"2026-09-09T06:32:34.855146Z","end":"2026-09-09T06:32:35.082564Z","steps":["trace[221507037] 'agreement among raft nodes before linearized reading' (duration: 226.300695ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.081462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.92062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.082761Z","caller":"traceutil/trace.go:171","msg":"trace[1433755059] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8586; }","duration":"470.241234ms","start":"2026-09-09T06:32:34.612491Z","end":"2026-09-09T06:32:35.082732Z","steps":["trace[1433755059] 'agreement among raft nodes before linearized reading' (duration: 468.859529ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.081509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.259956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:32:35.082844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.612482Z","time spent":"470.344686ms","remote":"127.0.0.1:38306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:10000 "} {"level":"info","ts":"2026-09-09T06:32:35.082842Z","caller":"traceutil/trace.go:171","msg":"trace[556804635] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8586; }","duration":"497.615181ms","start":"2026-09-09T06:32:34.585202Z","end":"2026-09-09T06:32:35.082817Z","steps":["trace[556804635] 'agreement among raft nodes before linearized reading' (duration: 496.266416ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.082903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.585177Z","time spent":"497.705203ms","remote":"127.0.0.1:38296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:10000 "} {"level":"warn","ts":"2026-09-09T06:32:35.080755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.505423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.082975Z","caller":"traceutil/trace.go:171","msg":"trace[641921381] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8586; }","duration":"375.773771ms","start":"2026-09-09T06:32:34.707174Z","end":"2026-09-09T06:32:35.082948Z","steps":["trace[641921381] 'agreement among raft nodes before linearized reading' (duration: 373.480853ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.081558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.329503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:32:35.083036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.707152Z","time spent":"375.863833ms","remote":"127.0.0.1:38350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:500 "} {"level":"info","ts":"2026-09-09T06:32:35.083075Z","caller":"traceutil/trace.go:171","msg":"trace[1512838650] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8586; }","duration":"246.858032ms","start":"2026-09-09T06:32:34.836183Z","end":"2026-09-09T06:32:35.083041Z","steps":["trace[1512838650] 'agreement among raft nodes before linearized reading' (duration: 245.340704ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.081563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.885514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.083148Z","caller":"traceutil/trace.go:171","msg":"trace[611502284] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8586; }","duration":"469.528695ms","start":"2026-09-09T06:32:34.613606Z","end":"2026-09-09T06:32:35.083134Z","steps":["trace[611502284] 'agreement among raft nodes before linearized reading' (duration: 467.816952ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.083195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.613599Z","time spent":"469.579957ms","remote":"127.0.0.1:38306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:500 "} {"level":"warn","ts":"2026-09-09T06:32:35.081625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.713767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.08333Z","caller":"traceutil/trace.go:171","msg":"trace[203719979] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8586; }","duration":"322.451501ms","start":"2026-09-09T06:32:34.760859Z","end":"2026-09-09T06:32:35.08331Z","steps":["trace[203719979] 'agreement among raft nodes before linearized reading' (duration: 320.724647ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.083418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.760838Z","time spent":"322.530752ms","remote":"127.0.0.1:38368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":113,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:10000 "} {"level":"warn","ts":"2026-09-09T06:32:35.081586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.034583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.083482Z","caller":"traceutil/trace.go:171","msg":"trace[1375336645] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8586; }","duration":"471.005893ms","start":"2026-09-09T06:32:34.612463Z","end":"2026-09-09T06:32:35.083469Z","steps":["trace[1375336645] 'agreement among raft nodes before linearized reading' (duration: 468.949961ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.083529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.612449Z","time spent":"471.063234ms","remote":"127.0.0.1:38306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true "} {"level":"warn","ts":"2026-09-09T06:32:35.081673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.355913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8xnr4\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-09T06:32:35.083661Z","caller":"traceutil/trace.go:171","msg":"trace[1573925231] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8xnr4; range_end:; response_count:1; response_revision:8586; }","duration":"612.371734ms","start":"2026-09-09T06:32:34.47128Z","end":"2026-09-09T06:32:35.083652Z","steps":["trace[1573925231] 'agreement among raft nodes before linearized reading' (duration: 610.26374ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.083694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.471262Z","time spent":"612.421606ms","remote":"127.0.0.1:34076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-8xnr4\" "} {"level":"warn","ts":"2026-09-09T06:32:35.08167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.360888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.083845Z","caller":"traceutil/trace.go:171","msg":"trace[1569558648] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8586; }","duration":"322.588885ms","start":"2026-09-09T06:32:34.761241Z","end":"2026-09-09T06:32:35.08383Z","steps":["trace[1569558648] 'agreement among raft nodes before linearized reading' (duration: 320.318188ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.083883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.761233Z","time spent":"322.636026ms","remote":"127.0.0.1:38368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":113,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:500 "} {"level":"warn","ts":"2026-09-09T06:32:35.081686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.840139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.083953Z","caller":"traceutil/trace.go:171","msg":"trace[1065838006] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8586; }","duration":"323.126538ms","start":"2026-09-09T06:32:34.760811Z","end":"2026-09-09T06:32:35.083938Z","steps":["trace[1065838006] 'agreement among raft nodes before linearized reading' (duration: 320.83996ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.084012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.760798Z","time spent":"323.194999ms","remote":"127.0.0.1:38368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true "} {"level":"warn","ts":"2026-09-09T06:32:35.081647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.469057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.084086Z","caller":"traceutil/trace.go:171","msg":"trace[1649660025] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8586; }","duration":"497.942169ms","start":"2026-09-09T06:32:34.586127Z","end":"2026-09-09T06:32:35.084069Z","steps":["trace[1649660025] 'agreement among raft nodes before linearized reading' (duration: 495.392145ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.084127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.586117Z","time spent":"497.99685ms","remote":"127.0.0.1:38296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:500 "} {"level":"warn","ts":"2026-09-09T06:32:35.081714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.54802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.08422Z","caller":"traceutil/trace.go:171","msg":"trace[1143758398] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8586; }","duration":"293.128284ms","start":"2026-09-09T06:32:34.791081Z","end":"2026-09-09T06:32:35.084209Z","steps":["trace[1143758398] 'agreement among raft nodes before linearized reading' (duration: 290.5855ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.081732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.237102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.08433Z","caller":"traceutil/trace.go:171","msg":"trace[2019757225] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8586; }","duration":"347.852288ms","start":"2026-09-09T06:32:34.736462Z","end":"2026-09-09T06:32:35.084315Z","steps":["trace[2019757225] 'agreement among raft nodes before linearized reading' (duration: 345.244512ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.084401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.736449Z","time spent":"347.91635ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:500 "} {"level":"warn","ts":"2026-09-09T06:32:35.08169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.039606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.084702Z","caller":"traceutil/trace.go:171","msg":"trace[1106462207] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8586; }","duration":"418.147025ms","start":"2026-09-09T06:32:34.666532Z","end":"2026-09-09T06:32:35.084679Z","steps":["trace[1106462207] 'agreement among raft nodes before linearized reading' (duration: 414.960504ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.081687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.588437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:32:35.084773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.666507Z","time spent":"418.244577ms","remote":"127.0.0.1:38334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:500 "} {"level":"info","ts":"2026-09-09T06:32:35.08482Z","caller":"traceutil/trace.go:171","msg":"trace[712596429] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8586; }","duration":"271.789018ms","start":"2026-09-09T06:32:34.812993Z","end":"2026-09-09T06:32:35.084782Z","steps":["trace[712596429] 'agreement among raft nodes before linearized reading' (duration: 268.599527ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.081731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.467182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:32:35.081755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.711443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.08498Z","caller":"traceutil/trace.go:171","msg":"trace[1381866815] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8586; }","duration":"249.697673ms","start":"2026-09-09T06:32:34.835211Z","end":"2026-09-09T06:32:35.084908Z","steps":["trace[1381866815] 'agreement among raft nodes before linearized reading' (duration: 246.42506ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:32:35.085033Z","caller":"traceutil/trace.go:171","msg":"trace[421953064] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8586; }","duration":"294.016577ms","start":"2026-09-09T06:32:34.790997Z","end":"2026-09-09T06:32:35.085014Z","steps":["trace[421953064] 'agreement among raft nodes before linearized reading' (duration: 290.715643ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.081748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.894873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.085207Z","caller":"traceutil/trace.go:171","msg":"trace[119676232] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8586; }","duration":"293.505304ms","start":"2026-09-09T06:32:34.791683Z","end":"2026-09-09T06:32:35.085188Z","steps":["trace[119676232] 'agreement among raft nodes before linearized reading' (duration: 289.978605ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.081762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.73269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.08531Z","caller":"traceutil/trace.go:171","msg":"trace[959751684] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8586; }","duration":"272.314811ms","start":"2026-09-09T06:32:34.812985Z","end":"2026-09-09T06:32:35.085299Z","steps":["trace[959751684] 'agreement among raft nodes before linearized reading' (duration: 268.744011ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.081779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.158862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.085481Z","caller":"traceutil/trace.go:171","msg":"trace[1393619215] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8586; }","duration":"348.917455ms","start":"2026-09-09T06:32:34.736552Z","end":"2026-09-09T06:32:35.08547Z","steps":["trace[1393619215] 'agreement among raft nodes before linearized reading' (duration: 345.198871ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.085527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.736513Z","time spent":"348.990378ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true "} {"level":"warn","ts":"2026-09-09T06:32:35.081778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.602229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.08588Z","caller":"traceutil/trace.go:171","msg":"trace[922409526] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8586; }","duration":"272.713331ms","start":"2026-09-09T06:32:34.813153Z","end":"2026-09-09T06:32:35.085866Z","steps":["trace[922409526] 'agreement among raft nodes before linearized reading' (duration: 268.595297ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.081791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.174684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.086061Z","caller":"traceutil/trace.go:171","msg":"trace[1341428529] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8586; }","duration":"398.43501ms","start":"2026-09-09T06:32:34.687597Z","end":"2026-09-09T06:32:35.086032Z","steps":["trace[1341428529] 'agreement among raft nodes before linearized reading' (duration: 394.166003ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.086128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.687591Z","time spent":"398.514992ms","remote":"127.0.0.1:38340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true "} {"level":"warn","ts":"2026-09-09T06:32:35.081791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.193442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:32:35.081809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.045462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.086337Z","caller":"traceutil/trace.go:171","msg":"trace[1965110443] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8586; }","duration":"420.596226ms","start":"2026-09-09T06:32:34.665724Z","end":"2026-09-09T06:32:35.08632Z","steps":["trace[1965110443] 'agreement among raft nodes before linearized reading' (duration: 416.035692ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.086424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.66571Z","time spent":"420.669348ms","remote":"127.0.0.1:38334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:10000 "} {"level":"warn","ts":"2026-09-09T06:32:35.081833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.425029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.086634Z","caller":"traceutil/trace.go:171","msg":"trace[2130574476] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8586; }","duration":"399.271171ms","start":"2026-09-09T06:32:34.687344Z","end":"2026-09-09T06:32:35.086615Z","steps":["trace[2130574476] 'agreement among raft nodes before linearized reading' (duration: 394.467651ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.086693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.687317Z","time spent":"399.358043ms","remote":"127.0.0.1:38340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:500 "} {"level":"warn","ts":"2026-09-09T06:32:35.08183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.997541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.086876Z","caller":"traceutil/trace.go:171","msg":"trace[836610508] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8586; }","duration":"421.059838ms","start":"2026-09-09T06:32:34.6658Z","end":"2026-09-09T06:32:35.086859Z","steps":["trace[836610508] 'agreement among raft nodes before linearized reading' (duration: 415.995261ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.086935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.665792Z","time spent":"421.12657ms","remote":"127.0.0.1:38334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"warn","ts":"2026-09-09T06:32:35.081834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.249585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.087062Z","caller":"traceutil/trace.go:171","msg":"trace[232957347] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8586; }","duration":"399.480297ms","start":"2026-09-09T06:32:34.687565Z","end":"2026-09-09T06:32:35.087045Z","steps":["trace[232957347] 'agreement among raft nodes before linearized reading' (duration: 394.244816ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.087134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.687559Z","time spent":"399.532888ms","remote":"127.0.0.1:38340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:10000 "} {"level":"warn","ts":"2026-09-09T06:32:35.081831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.090932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.087245Z","caller":"traceutil/trace.go:171","msg":"trace[1162570891] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8586; }","duration":"444.545288ms","start":"2026-09-09T06:32:34.642684Z","end":"2026-09-09T06:32:35.08723Z","steps":["trace[1162570891] 'agreement among raft nodes before linearized reading' (duration: 439.115062ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.087291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.642658Z","time spent":"444.61819ms","remote":"127.0.0.1:38318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true "} {"level":"warn","ts":"2026-09-09T06:32:35.082038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.34145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.087441Z","caller":"traceutil/trace.go:171","msg":"trace[1318831172] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8586; }","duration":"501.816417ms","start":"2026-09-09T06:32:34.585612Z","end":"2026-09-09T06:32:35.087428Z","steps":["trace[1318831172] 'agreement among raft nodes before linearized reading' (duration: 495.706383ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.087477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.585578Z","time spent":"501.887039ms","remote":"127.0.0.1:38296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true "} {"level":"info","ts":"2026-09-09T06:32:35.086259Z","caller":"traceutil/trace.go:171","msg":"trace[819310593] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8586; }","duration":"349.649943ms","start":"2026-09-09T06:32:34.736548Z","end":"2026-09-09T06:32:35.086198Z","steps":["trace[819310593] 'agreement among raft nodes before linearized reading' (duration: 345.216612ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.087552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:32:34.736517Z","time spent":"351.013788ms","remote":"127.0.0.1:38352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:10000 "} {"level":"warn","ts":"2026-09-09T06:32:35.082083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.810431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.087805Z","caller":"traceutil/trace.go:171","msg":"trace[1701611606] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8586; }","duration":"252.578856ms","start":"2026-09-09T06:32:34.835203Z","end":"2026-09-09T06:32:35.087782Z","steps":["trace[1701611606] 'agreement among raft nodes before linearized reading' (duration: 246.74715ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:32:35.100136Z","caller":"traceutil/trace.go:171","msg":"trace[2069221331] transaction","detail":"{read_only:false; response_revision:8587; number_of_response:1; }","duration":"233.117867ms","start":"2026-09-09T06:32:34.867001Z","end":"2026-09-09T06:32:35.100118Z","steps":["trace[2069221331] 'process raft request' (duration: 232.949993ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.100581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.863618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.102584Z","caller":"traceutil/trace.go:171","msg":"trace[1594870534] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8587; }","duration":"130.911709ms","start":"2026-09-09T06:32:34.971648Z","end":"2026-09-09T06:32:35.10256Z","steps":["trace[1594870534] 'agreement among raft nodes before linearized reading' (duration: 128.876568ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.100622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.51612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.102704Z","caller":"traceutil/trace.go:171","msg":"trace[40524863] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8587; }","duration":"177.636713ms","start":"2026-09-09T06:32:34.925055Z","end":"2026-09-09T06:32:35.102692Z","steps":["trace[40524863] 'agreement among raft nodes before linearized reading' (duration: 175.53581ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.100665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.118285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.102901Z","caller":"traceutil/trace.go:171","msg":"trace[544873436] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8587; }","duration":"178.340631ms","start":"2026-09-09T06:32:34.924513Z","end":"2026-09-09T06:32:35.102854Z","steps":["trace[544873436] 'agreement among raft nodes before linearized reading' (duration: 176.129356ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.100702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.228638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.103061Z","caller":"traceutil/trace.go:171","msg":"trace[1791009772] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8587; }","duration":"178.582686ms","start":"2026-09-09T06:32:34.924464Z","end":"2026-09-09T06:32:35.103047Z","steps":["trace[1791009772] 'agreement among raft nodes before linearized reading' (duration: 176.216377ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.10074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.697102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.103158Z","caller":"traceutil/trace.go:171","msg":"trace[996139388] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8587; }","duration":"201.136503ms","start":"2026-09-09T06:32:34.902012Z","end":"2026-09-09T06:32:35.103148Z","steps":["trace[996139388] 'agreement among raft nodes before linearized reading' (duration: 198.706022ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.100802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.696057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.103586Z","caller":"traceutil/trace.go:171","msg":"trace[2146203450] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8587; }","duration":"202.504928ms","start":"2026-09-09T06:32:34.901062Z","end":"2026-09-09T06:32:35.103567Z","steps":["trace[2146203450] 'agreement among raft nodes before linearized reading' (duration: 199.692827ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.100838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.783969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.103858Z","caller":"traceutil/trace.go:171","msg":"trace[1117169079] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8587; }","duration":"202.805674ms","start":"2026-09-09T06:32:34.901025Z","end":"2026-09-09T06:32:35.103831Z","steps":["trace[1117169079] 'agreement among raft nodes before linearized reading' (duration: 199.792159ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.100885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.249584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.103964Z","caller":"traceutil/trace.go:171","msg":"trace[1344383591] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8587; }","duration":"229.348011ms","start":"2026-09-09T06:32:34.874593Z","end":"2026-09-09T06:32:35.103941Z","steps":["trace[1344383591] 'agreement among raft nodes before linearized reading' (duration: 226.261534ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.100935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.398543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.104111Z","caller":"traceutil/trace.go:171","msg":"trace[1180248423] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8587; }","duration":"230.589204ms","start":"2026-09-09T06:32:34.873505Z","end":"2026-09-09T06:32:35.104094Z","steps":["trace[1180248423] 'agreement among raft nodes before linearized reading' (duration: 227.400164ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:35.100976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.469215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:32:35.102115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.851129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:32:35.102202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.961117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:32:35.102255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.045999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:32:35.102309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.951645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:32:35.102356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.53259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:35.10441Z","caller":"traceutil/trace.go:171","msg":"trace[1516149016] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8587; }","duration":"132.583911ms","start":"2026-09-09T06:32:34.971788Z","end":"2026-09-09T06:32:35.104371Z","steps":["trace[1516149016] 'agreement among raft nodes before linearized reading' (duration: 130.54466ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:32:35.104508Z","caller":"traceutil/trace.go:171","msg":"trace[1498495228] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:8587; }","duration":"109.283085ms","start":"2026-09-09T06:32:34.995205Z","end":"2026-09-09T06:32:35.104488Z","steps":["trace[1498495228] 'agreement among raft nodes before linearized reading' (duration: 106.963916ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:32:35.104608Z","caller":"traceutil/trace.go:171","msg":"trace[1538847953] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:8587; }","duration":"109.412559ms","start":"2026-09-09T06:32:34.995174Z","end":"2026-09-09T06:32:35.104586Z","steps":["trace[1538847953] 'agreement among raft nodes before linearized reading' (duration: 107.050029ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:32:35.104686Z","caller":"traceutil/trace.go:171","msg":"trace[2130942387] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8587; }","duration":"231.199089ms","start":"2026-09-09T06:32:34.873471Z","end":"2026-09-09T06:32:35.10467Z","steps":["trace[2130942387] 'agreement among raft nodes before linearized reading' (duration: 227.482215ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:32:35.104873Z","caller":"traceutil/trace.go:171","msg":"trace[447425945] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8587; }","duration":"132.53439ms","start":"2026-09-09T06:32:34.972293Z","end":"2026-09-09T06:32:35.104827Z","steps":["trace[447425945] 'agreement among raft nodes before linearized reading' (duration: 129.983196ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:32:35.105048Z","caller":"traceutil/trace.go:171","msg":"trace[867223510] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:8587; }","duration":"108.854174ms","start":"2026-09-09T06:32:34.996171Z","end":"2026-09-09T06:32:35.105025Z","steps":["trace[867223510] 'agreement among raft nodes before linearized reading' (duration: 105.819068ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:32:46.395103Z","caller":"traceutil/trace.go:171","msg":"trace[1562845058] linearizableReadLoop","detail":"{readStateIndex:8960; appliedIndex:8959; }","duration":"107.161183ms","start":"2026-09-09T06:32:46.28791Z","end":"2026-09-09T06:32:46.395071Z","steps":["trace[1562845058] 'read index received' (duration: 106.804024ms)","trace[1562845058] 'applied index is now lower than readState.Index' (duration: 355.449µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:32:46.395269Z","caller":"traceutil/trace.go:171","msg":"trace[1684662440] transaction","detail":"{read_only:false; response_revision:8646; number_of_response:1; }","duration":"114.291352ms","start":"2026-09-09T06:32:46.2809Z","end":"2026-09-09T06:32:46.395191Z","steps":["trace[1684662440] 'process raft request' (duration: 113.962223ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:46.395362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.433119ms","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-09-09T06:32:46.395445Z","caller":"traceutil/trace.go:171","msg":"trace[1616590518] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8646; }","duration":"107.579213ms","start":"2026-09-09T06:32:46.287853Z","end":"2026-09-09T06:32:46.395432Z","steps":["trace[1616590518] 'agreement among raft nodes before linearized reading' (duration: 107.347187ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:32:46.536539Z","caller":"traceutil/trace.go:171","msg":"trace[1515344112] transaction","detail":"{read_only:false; response_revision:8647; number_of_response:1; }","duration":"135.893304ms","start":"2026-09-09T06:32:46.40061Z","end":"2026-09-09T06:32:46.536504Z","steps":["trace[1515344112] 'process raft request' (duration: 104.093885ms)","trace[1515344112] 'compare' (duration: 31.405629ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:32:47.312463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.537278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:32:47.312585Z","caller":"traceutil/trace.go:171","msg":"trace[1262480418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8648; }","duration":"155.711802ms","start":"2026-09-09T06:32:47.156835Z","end":"2026-09-09T06:32:47.312547Z","steps":["trace[1262480418] 'range keys from in-memory index tree' (duration: 154.720687ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:47.312919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.00713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T06:32:47.312985Z","caller":"traceutil/trace.go:171","msg":"trace[1798677656] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:8648; }","duration":"195.097822ms","start":"2026-09-09T06:32:47.11785Z","end":"2026-09-09T06:32:47.312948Z","steps":["trace[1798677656] 'count revisions from in-memory index tree' (duration: 194.895557ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:47.313008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.991835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8xnr4\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-09-09T06:32:47.313035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.698676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.56\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-09T06:32:47.313116Z","caller":"traceutil/trace.go:171","msg":"trace[2068326329] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8xnr4; range_end:; response_count:1; response_revision:8648; }","duration":"124.105848ms","start":"2026-09-09T06:32:47.188982Z","end":"2026-09-09T06:32:47.313088Z","steps":["trace[2068326329] 'range keys from in-memory index tree' (duration: 123.839831ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:32:47.313131Z","caller":"traceutil/trace.go:171","msg":"trace[47237829] range","detail":"{range_begin:/registry/masterleases/162.253.55.56; range_end:; response_count:1; response_revision:8648; }","duration":"222.87487ms","start":"2026-09-09T06:32:47.090236Z","end":"2026-09-09T06:32:47.313111Z","steps":["trace[47237829] 'range keys from in-memory index tree' (duration: 222.381828ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:47.313182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.941246ms","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-09-09T06:32:47.313269Z","caller":"traceutil/trace.go:171","msg":"trace[258111680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8648; }","duration":"142.083829ms","start":"2026-09-09T06:32:47.171162Z","end":"2026-09-09T06:32:47.313245Z","steps":["trace[258111680] 'range keys from in-memory index tree' (duration: 141.68414ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:32:47.313728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.381132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-09T06:32:47.314125Z","caller":"traceutil/trace.go:171","msg":"trace[11289492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8648; }","duration":"136.548611ms","start":"2026-09-09T06:32:47.1773Z","end":"2026-09-09T06:32:47.313848Z","steps":["trace[11289492] 'range keys from in-memory index tree' (duration: 135.137875ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:32:49.786231Z","caller":"traceutil/trace.go:171","msg":"trace[1196373587] transaction","detail":"{read_only:false; response_revision:8663; number_of_response:1; }","duration":"101.47451ms","start":"2026-09-09T06:32:49.684728Z","end":"2026-09-09T06:32:49.786203Z","steps":["trace[1196373587] 'process raft request' (duration: 101.267215ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:32:49.803447Z","caller":"traceutil/trace.go:171","msg":"trace[1851867028] transaction","detail":"{read_only:false; response_revision:8664; number_of_response:1; }","duration":"112.859868ms","start":"2026-09-09T06:32:49.690558Z","end":"2026-09-09T06:32:49.803418Z","steps":["trace[1851867028] 'process raft request' (duration: 112.668663ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:33:17.054127Z","caller":"traceutil/trace.go:171","msg":"trace[493198924] transaction","detail":"{read_only:false; response_revision:8862; number_of_response:1; }","duration":"331.822823ms","start":"2026-09-09T06:33:16.722274Z","end":"2026-09-09T06:33:17.054097Z","steps":["trace[493198924] 'process raft request' (duration: 331.613708ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:33:17.054333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:33:16.722252Z","time spent":"331.968117ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:33:17.315578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.255815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:33:17.315687Z","caller":"traceutil/trace.go:171","msg":"trace[1672233357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8862; }","duration":"145.372438ms","start":"2026-09-09T06:33:17.170287Z","end":"2026-09-09T06:33:17.31566Z","steps":["trace[1672233357] 'range keys from in-memory index tree' (duration: 145.179114ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:33:17.315681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.595332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.56\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-09T06:33:17.31582Z","caller":"traceutil/trace.go:171","msg":"trace[1191389130] range","detail":"{range_begin:/registry/masterleases/162.253.55.56; range_end:; response_count:1; response_revision:8862; }","duration":"223.782917ms","start":"2026-09-09T06:33:17.091996Z","end":"2026-09-09T06:33:17.315779Z","steps":["trace[1191389130] 'range keys from in-memory index tree' (duration: 223.398807ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:33:17.316267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.411666ms","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-09-09T06:33:17.316557Z","caller":"traceutil/trace.go:171","msg":"trace[1995238086] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8862; }","duration":"156.890839ms","start":"2026-09-09T06:33:17.159639Z","end":"2026-09-09T06:33:17.31653Z","steps":["trace[1995238086] 'range keys from in-memory index tree' (duration: 155.912144ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:33:24.079446Z","caller":"traceutil/trace.go:171","msg":"trace[702573281] transaction","detail":"{read_only:false; response_revision:8899; number_of_response:1; }","duration":"121.477356ms","start":"2026-09-09T06:33:23.957934Z","end":"2026-09-09T06:33:24.079412Z","steps":["trace[702573281] 'process raft request' (duration: 121.27427ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:33:24.166348Z","caller":"traceutil/trace.go:171","msg":"trace[1804552467] transaction","detail":"{read_only:false; response_revision:8900; number_of_response:1; }","duration":"207.28204ms","start":"2026-09-09T06:33:23.959023Z","end":"2026-09-09T06:33:24.166305Z","steps":["trace[1804552467] 'process raft request' (duration: 192.079505ms)","trace[1804552467] 'compare' (duration: 14.981109ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:33:31.035547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.063496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-09-09T06:33:31.035651Z","caller":"traceutil/trace.go:171","msg":"trace[353964127] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:8975; }","duration":"106.2059ms","start":"2026-09-09T06:33:30.929421Z","end":"2026-09-09T06:33:31.035627Z","steps":["trace[353964127] 'range keys from in-memory index tree' (duration: 105.856541ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:33:31.490261Z","caller":"traceutil/trace.go:171","msg":"trace[157899222] transaction","detail":"{read_only:false; response_revision:8978; number_of_response:1; }","duration":"134.647009ms","start":"2026-09-09T06:33:31.355583Z","end":"2026-09-09T06:33:31.49023Z","steps":["trace[157899222] 'process raft request' (duration: 134.459723ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:33:31.583706Z","caller":"traceutil/trace.go:171","msg":"trace[2030475986] linearizableReadLoop","detail":"{readStateIndex:9307; appliedIndex:9305; }","duration":"188.854457ms","start":"2026-09-09T06:33:31.394821Z","end":"2026-09-09T06:33:31.583676Z","steps":["trace[2030475986] 'read index received' (duration: 95.219063ms)","trace[2030475986] 'applied index is now lower than readState.Index' (duration: 93.634174ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:33:31.584043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.188084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-09T06:33:31.584086Z","caller":"traceutil/trace.go:171","msg":"trace[1475914397] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8979; }","duration":"189.302047ms","start":"2026-09-09T06:33:31.394771Z","end":"2026-09-09T06:33:31.584073Z","steps":["trace[1475914397] 'agreement among raft nodes before linearized reading' (duration: 189.069352ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:33:31.584768Z","caller":"traceutil/trace.go:171","msg":"trace[258728212] transaction","detail":"{read_only:false; response_revision:8979; number_of_response:1; }","duration":"221.027098ms","start":"2026-09-09T06:33:31.363702Z","end":"2026-09-09T06:33:31.58473Z","steps":["trace[258728212] 'process raft request' (duration: 171.99489ms)","trace[258728212] 'compare' (duration: 47.826507ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:33:31.994643Z","caller":"traceutil/trace.go:171","msg":"trace[2073129236] transaction","detail":"{read_only:false; response_revision:8983; number_of_response:1; }","duration":"125.365624ms","start":"2026-09-09T06:33:31.869241Z","end":"2026-09-09T06:33:31.994606Z","steps":["trace[2073129236] 'process raft request' (duration: 125.087346ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:33:32.698804Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002469.wal"} {"level":"info","ts":"2026-09-09T06:34:46.352673Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c9fb5245756f3898","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-09T06:34:46.359485Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-09T06:34:46.359615Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-09-09T06:34:54.049879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.205739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-09T06:34:54.050026Z","caller":"traceutil/trace.go:171","msg":"trace[536063242] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:9728; }","duration":"186.423734ms","start":"2026-09-09T06:34:53.863577Z","end":"2026-09-09T06:34:54.05Z","steps":["trace[536063242] 'count revisions from in-memory index tree' (duration: 186.078425ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:34:54.050199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.288463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:34:54.050334Z","caller":"traceutil/trace.go:171","msg":"trace[404606858] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:9728; }","duration":"175.512728ms","start":"2026-09-09T06:34:53.874791Z","end":"2026-09-09T06:34:54.050304Z","steps":["trace[404606858] 'range keys from in-memory index tree' (duration: 175.160479ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:34:54.231052Z","caller":"traceutil/trace.go:171","msg":"trace[812402317] transaction","detail":"{read_only:false; response_revision:9729; number_of_response:1; }","duration":"139.568943ms","start":"2026-09-09T06:34:54.091439Z","end":"2026-09-09T06:34:54.231008Z","steps":["trace[812402317] 'process raft request' (duration: 139.307137ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:36:23.838049Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7979} {"level":"info","ts":"2026-09-09T06:36:23.927609Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7979,"took":"86.970666ms","hash":2502441435,"current-db-size-bytes":54624256,"current-db-size":"55 MB","current-db-size-in-use-bytes":25661440,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-09T06:36:23.927697Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2502441435,"revision":7979,"compact-revision":5648} {"level":"info","ts":"2026-09-09T06:37:39.548941Z","caller":"traceutil/trace.go:171","msg":"trace[50227530] linearizableReadLoop","detail":"{readStateIndex:11502; appliedIndex:11501; }","duration":"389.80123ms","start":"2026-09-09T06:37:39.159105Z","end":"2026-09-09T06:37:39.548906Z","steps":["trace[50227530] 'read index received' (duration: 389.568664ms)","trace[50227530] 'applied index is now lower than readState.Index' (duration: 231.176µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:39.548993Z","caller":"traceutil/trace.go:171","msg":"trace[1133385306] transaction","detail":"{read_only:false; response_revision:11107; number_of_response:1; }","duration":"408.144099ms","start":"2026-09-09T06:37:39.140791Z","end":"2026-09-09T06:37:39.548936Z","steps":["trace[1133385306] 'process raft request' (duration: 407.897723ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:39.549231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:39.140757Z","time spent":"408.348785ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:37:39.549259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.059637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:37:39.549427Z","caller":"traceutil/trace.go:171","msg":"trace[1468580015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11107; }","duration":"390.360635ms","start":"2026-09-09T06:37:39.159037Z","end":"2026-09-09T06:37:39.549398Z","steps":["trace[1468580015] 'agreement among raft nodes before linearized reading' (duration: 389.985696ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:39.549631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:39.159007Z","time spent":"390.60471ms","remote":"127.0.0.1:33928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-09T06:37:39.615503Z","caller":"traceutil/trace.go:171","msg":"trace[62655937] transaction","detail":"{read_only:false; response_revision:11108; number_of_response:1; }","duration":"337.974746ms","start":"2026-09-09T06:37:39.277487Z","end":"2026-09-09T06:37:39.615461Z","steps":["trace[62655937] 'process raft request' (duration: 295.923282ms)","trace[62655937] 'compare' (duration: 41.790307ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:39.615743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:39.27746Z","time spent":"338.171441ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:37:39.615744Z","caller":"traceutil/trace.go:171","msg":"trace[1886981037] transaction","detail":"{read_only:false; response_revision:11109; number_of_response:1; }","duration":"321.109765ms","start":"2026-09-09T06:37:39.294588Z","end":"2026-09-09T06:37:39.615698Z","steps":["trace[1886981037] 'process raft request' (duration: 320.785917ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:39.615829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.40657ms","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-09-09T06:37:39.615828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.563754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-09T06:37:39.615915Z","caller":"traceutil/trace.go:171","msg":"trace[206635474] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11109; }","duration":"396.547684ms","start":"2026-09-09T06:37:39.219342Z","end":"2026-09-09T06:37:39.615889Z","steps":["trace[206635474] 'agreement among raft nodes before linearized reading' (duration: 396.363769ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:39.615943Z","caller":"traceutil/trace.go:171","msg":"trace[114508388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11109; }","duration":"279.747888ms","start":"2026-09-09T06:37:39.336165Z","end":"2026-09-09T06:37:39.615913Z","steps":["trace[114508388] 'agreement among raft nodes before linearized reading' (duration: 279.334648ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:39.615982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:39.219301Z","time spent":"396.669397ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-09T06:37:39.615971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.572416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-09-09T06:37:39.615926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:39.294571Z","time spent":"321.251129ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:37:39.615737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.293456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1857"} {"level":"info","ts":"2026-09-09T06:37:39.616162Z","caller":"traceutil/trace.go:171","msg":"trace[1595095937] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:11109; }","duration":"340.754668ms","start":"2026-09-09T06:37:39.275387Z","end":"2026-09-09T06:37:39.616141Z","steps":["trace[1595095937] 'agreement among raft nodes before linearized reading' (duration: 340.173903ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:39.615965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.179619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:10359"} {"level":"warn","ts":"2026-09-09T06:37:39.616215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:39.275361Z","time spent":"340.837979ms","remote":"127.0.0.1:34050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1880,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" "} {"level":"info","ts":"2026-09-09T06:37:39.616228Z","caller":"traceutil/trace.go:171","msg":"trace[1510584419] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:11109; }","duration":"343.493257ms","start":"2026-09-09T06:37:39.272713Z","end":"2026-09-09T06:37:39.616206Z","steps":["trace[1510584419] 'agreement among raft nodes before linearized reading' (duration: 343.034185ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:39.616261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:39.272678Z","time spent":"343.574549ms","remote":"127.0.0.1:34076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10382,"request content":"key:\"/registry/pods/openstack/rabbitmq-cinder-server-0\" "} {"level":"info","ts":"2026-09-09T06:37:39.616471Z","caller":"traceutil/trace.go:171","msg":"trace[1113638061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11109; }","duration":"275.108189ms","start":"2026-09-09T06:37:39.340951Z","end":"2026-09-09T06:37:39.61606Z","steps":["trace[1113638061] 'agreement among raft nodes before linearized reading' (duration: 274.773081ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:39.784266Z","caller":"traceutil/trace.go:171","msg":"trace[1135486074] linearizableReadLoop","detail":"{readStateIndex:11507; appliedIndex:11504; }","duration":"160.687606ms","start":"2026-09-09T06:37:39.623556Z","end":"2026-09-09T06:37:39.784243Z","steps":["trace[1135486074] 'read index received' (duration: 47.31822ms)","trace[1135486074] 'applied index is now lower than readState.Index' (duration: 113.356116ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:39.784274Z","caller":"traceutil/trace.go:171","msg":"trace[1700342865] transaction","detail":"{read_only:false; response_revision:11111; number_of_response:1; }","duration":"161.663341ms","start":"2026-09-09T06:37:39.622572Z","end":"2026-09-09T06:37:39.784235Z","steps":["trace[1700342865] 'process raft request' (duration: 127.913069ms)","trace[1700342865] 'compare' (duration: 33.478705ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:39.784403Z","caller":"traceutil/trace.go:171","msg":"trace[273598816] transaction","detail":"{read_only:false; response_revision:11113; number_of_response:1; }","duration":"153.997765ms","start":"2026-09-09T06:37:39.630393Z","end":"2026-09-09T06:37:39.784391Z","steps":["trace[273598816] 'process raft request' (duration: 153.777459ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:39.7846Z","caller":"traceutil/trace.go:171","msg":"trace[1544310803] transaction","detail":"{read_only:false; response_revision:11112; number_of_response:1; }","duration":"161.353183ms","start":"2026-09-09T06:37:39.623237Z","end":"2026-09-09T06:37:39.78459Z","steps":["trace[1544310803] 'process raft request' (duration: 160.893091ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:39.784624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.031706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T06:37:39.784714Z","caller":"traceutil/trace.go:171","msg":"trace[2073163318] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:11113; }","duration":"161.185809ms","start":"2026-09-09T06:37:39.623508Z","end":"2026-09-09T06:37:39.784693Z","steps":["trace[2073163318] 'agreement among raft nodes before linearized reading' (duration: 160.969294ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:39.98021Z","caller":"traceutil/trace.go:171","msg":"trace[542064479] transaction","detail":"{read_only:false; response_revision:11115; number_of_response:1; }","duration":"187.792638ms","start":"2026-09-09T06:37:39.792388Z","end":"2026-09-09T06:37:39.980181Z","steps":["trace[542064479] 'process raft request' (duration: 187.530442ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:39.980569Z","caller":"traceutil/trace.go:171","msg":"trace[1176088788] transaction","detail":"{read_only:false; response_revision:11116; number_of_response:1; }","duration":"188.125197ms","start":"2026-09-09T06:37:39.792432Z","end":"2026-09-09T06:37:39.980557Z","steps":["trace[1176088788] 'process raft request' (duration: 187.672755ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:39.980588Z","caller":"traceutil/trace.go:171","msg":"trace[1121525696] linearizableReadLoop","detail":"{readStateIndex:11512; appliedIndex:11508; }","duration":"178.345317ms","start":"2026-09-09T06:37:39.802215Z","end":"2026-09-09T06:37:39.98056Z","steps":["trace[1121525696] 'read index received' (duration: 29.842713ms)","trace[1121525696] 'applied index is now lower than readState.Index' (duration: 148.501424ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:39.980719Z","caller":"traceutil/trace.go:171","msg":"trace[1211396064] transaction","detail":"{read_only:false; response_revision:11117; number_of_response:1; }","duration":"188.011653ms","start":"2026-09-09T06:37:39.792673Z","end":"2026-09-09T06:37:39.980685Z","steps":["trace[1211396064] 'process raft request' (duration: 187.721776ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:39.980878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.646614ms","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-09-09T06:37:39.980922Z","caller":"traceutil/trace.go:171","msg":"trace[1032630126] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11117; }","duration":"178.717596ms","start":"2026-09-09T06:37:39.802191Z","end":"2026-09-09T06:37:39.980909Z","steps":["trace[1032630126] 'agreement among raft nodes before linearized reading' (duration: 178.518831ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:41.344229Z","caller":"traceutil/trace.go:171","msg":"trace[2093394044] transaction","detail":"{read_only:false; response_revision:11124; number_of_response:1; }","duration":"169.47373ms","start":"2026-09-09T06:37:41.174715Z","end":"2026-09-09T06:37:41.344189Z","steps":["trace[2093394044] 'process raft request' (duration: 169.257925ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:41.457148Z","caller":"traceutil/trace.go:171","msg":"trace[1848382873] transaction","detail":"{read_only:false; response_revision:11126; number_of_response:1; }","duration":"103.32308ms","start":"2026-09-09T06:37:41.353778Z","end":"2026-09-09T06:37:41.457101Z","steps":["trace[1848382873] 'process raft request' (duration: 103.061403ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:41.457573Z","caller":"traceutil/trace.go:171","msg":"trace[1625214523] transaction","detail":"{read_only:false; response_revision:11127; number_of_response:1; }","duration":"101.849773ms","start":"2026-09-09T06:37:41.35561Z","end":"2026-09-09T06:37:41.45746Z","steps":["trace[1625214523] 'process raft request' (duration: 101.426001ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:41.502156Z","caller":"traceutil/trace.go:171","msg":"trace[207354850] transaction","detail":"{read_only:false; response_revision:11130; number_of_response:1; }","duration":"101.416642ms","start":"2026-09-09T06:37:41.400711Z","end":"2026-09-09T06:37:41.502128Z","steps":["trace[207354850] 'process raft request' (duration: 101.217796ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:41.683863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.237537ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:37:41.684149Z","caller":"traceutil/trace.go:171","msg":"trace[665273035] transaction","detail":"{read_only:false; response_revision:11132; number_of_response:1; }","duration":"128.912104ms","start":"2026-09-09T06:37:41.55521Z","end":"2026-09-09T06:37:41.684122Z","steps":["trace[665273035] 'process raft request' (duration: 128.801461ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:41.684058Z","caller":"traceutil/trace.go:171","msg":"trace[474013512] transaction","detail":"{read_only:false; response_revision:11131; number_of_response:1; }","duration":"210.744194ms","start":"2026-09-09T06:37:41.473286Z","end":"2026-09-09T06:37:41.68403Z","steps":["trace[474013512] 'process raft request' (duration: 73.21135ms)","trace[474013512] 'compare' (duration: 137.073493ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:41.684395Z","caller":"traceutil/trace.go:171","msg":"trace[602665106] linearizableReadLoop","detail":"{readStateIndex:11526; appliedIndex:11525; }","duration":"135.283147ms","start":"2026-09-09T06:37:41.549056Z","end":"2026-09-09T06:37:41.684339Z","steps":["trace[602665106] 'read index received' (duration: 44.731µs)","trace[602665106] 'applied index is now lower than readState.Index' (duration: 135.233846ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:41.68473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.666476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-cinder\" ","response":"range_response_count:1 size:3234"} {"level":"info","ts":"2026-09-09T06:37:41.684796Z","caller":"traceutil/trace.go:171","msg":"trace[101684499] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-cinder; range_end:; response_count:1; response_revision:11132; }","duration":"135.783939ms","start":"2026-09-09T06:37:41.548992Z","end":"2026-09-09T06:37:41.684776Z","steps":["trace[101684499] 'agreement among raft nodes before linearized reading' (duration: 135.519833ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:41.758594Z","caller":"traceutil/trace.go:171","msg":"trace[1834087613] transaction","detail":"{read_only:false; response_revision:11134; number_of_response:1; }","duration":"137.095563ms","start":"2026-09-09T06:37:41.621468Z","end":"2026-09-09T06:37:41.758563Z","steps":["trace[1834087613] 'process raft request' (duration: 136.874418ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:46.071188Z","caller":"traceutil/trace.go:171","msg":"trace[1358659655] linearizableReadLoop","detail":"{readStateIndex:11553; appliedIndex:11552; }","duration":"121.789922ms","start":"2026-09-09T06:37:45.949353Z","end":"2026-09-09T06:37:46.071143Z","steps":["trace[1358659655] 'read index received' (duration: 44.440056ms)","trace[1358659655] 'applied index is now lower than readState.Index' (duration: 77.347096ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:46.07128Z","caller":"traceutil/trace.go:171","msg":"trace[1637474307] transaction","detail":"{read_only:false; response_revision:11157; number_of_response:1; }","duration":"203.159161ms","start":"2026-09-09T06:37:45.868081Z","end":"2026-09-09T06:37:46.07124Z","steps":["trace[1637474307] 'process raft request' (duration: 125.790834ms)","trace[1637474307] 'compare' (duration: 76.907345ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:46.071564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.157542ms","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-09-09T06:37:46.071633Z","caller":"traceutil/trace.go:171","msg":"trace[1522842148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11157; }","duration":"122.317346ms","start":"2026-09-09T06:37:45.949295Z","end":"2026-09-09T06:37:46.071612Z","steps":["trace[1522842148] 'agreement among raft nodes before linearized reading' (duration: 122.052229ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:46.071856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.397712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8xnr4\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-09T06:37:46.071948Z","caller":"traceutil/trace.go:171","msg":"trace[3620638] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8xnr4; range_end:; response_count:1; response_revision:11157; }","duration":"103.549735ms","start":"2026-09-09T06:37:45.968378Z","end":"2026-09-09T06:37:46.071928Z","steps":["trace[3620638] 'agreement among raft nodes before linearized reading' (duration: 103.289969ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:46.072396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.405264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-09T06:37:46.072463Z","caller":"traceutil/trace.go:171","msg":"trace[2000016543] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11157; }","duration":"107.495617ms","start":"2026-09-09T06:37:45.96495Z","end":"2026-09-09T06:37:46.072446Z","steps":["trace[2000016543] 'agreement among raft nodes before linearized reading' (duration: 106.668036ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:46.351213Z","caller":"traceutil/trace.go:171","msg":"trace[776861927] transaction","detail":"{read_only:false; response_revision:11159; number_of_response:1; }","duration":"273.55165ms","start":"2026-09-09T06:37:46.077627Z","end":"2026-09-09T06:37:46.351179Z","steps":["trace[776861927] 'process raft request' (duration: 273.293513ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:46.351453Z","caller":"traceutil/trace.go:171","msg":"trace[433432818] transaction","detail":"{read_only:false; response_revision:11160; number_of_response:1; }","duration":"271.269012ms","start":"2026-09-09T06:37:46.08017Z","end":"2026-09-09T06:37:46.351439Z","steps":["trace[433432818] 'process raft request' (duration: 270.963065ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:46.351472Z","caller":"traceutil/trace.go:171","msg":"trace[496068728] linearizableReadLoop","detail":"{readStateIndex:11556; appliedIndex:11554; }","duration":"199.182521ms","start":"2026-09-09T06:37:46.15223Z","end":"2026-09-09T06:37:46.351412Z","steps":["trace[496068728] 'read index received' (duration: 198.675637ms)","trace[496068728] 'applied index is now lower than readState.Index' (duration: 504.493µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:46.351792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.648137ms","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-09-09T06:37:46.35184Z","caller":"traceutil/trace.go:171","msg":"trace[404376369] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11160; }","duration":"218.77602ms","start":"2026-09-09T06:37:46.133051Z","end":"2026-09-09T06:37:46.351827Z","steps":["trace[404376369] 'agreement among raft nodes before linearized reading' (duration: 218.582345ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:49.911108Z","caller":"traceutil/trace.go:171","msg":"trace[281362715] linearizableReadLoop","detail":"{readStateIndex:11576; appliedIndex:11575; }","duration":"228.041428ms","start":"2026-09-09T06:37:49.683039Z","end":"2026-09-09T06:37:49.911081Z","steps":["trace[281362715] 'read index received' (duration: 227.848922ms)","trace[281362715] 'applied index is now lower than readState.Index' (duration: 191.376µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:49.911168Z","caller":"traceutil/trace.go:171","msg":"trace[2047406363] transaction","detail":"{read_only:false; response_revision:11178; number_of_response:1; }","duration":"240.934066ms","start":"2026-09-09T06:37:49.670196Z","end":"2026-09-09T06:37:49.91113Z","steps":["trace[2047406363] 'process raft request' (duration: 240.69937ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:49.911284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.226032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:37:49.911328Z","caller":"traceutil/trace.go:171","msg":"trace[150560390] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11178; }","duration":"228.341535ms","start":"2026-09-09T06:37:49.682975Z","end":"2026-09-09T06:37:49.911316Z","steps":["trace[150560390] 'agreement among raft nodes before linearized reading' (duration: 228.242642ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:50.069609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.233077ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:37:50.069832Z","caller":"traceutil/trace.go:171","msg":"trace[152158724] linearizableReadLoop","detail":"{readStateIndex:11578; appliedIndex:11576; }","duration":"158.623293ms","start":"2026-09-09T06:37:49.911185Z","end":"2026-09-09T06:37:50.069808Z","steps":["trace[152158724] 'read index received' (duration: 56.922184ms)","trace[152158724] 'applied index is now lower than readState.Index' (duration: 101.699589ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:50.069847Z","caller":"traceutil/trace.go:171","msg":"trace[326887024] transaction","detail":"{read_only:false; response_revision:11179; number_of_response:1; }","duration":"338.525951ms","start":"2026-09-09T06:37:49.731279Z","end":"2026-09-09T06:37:50.069805Z","steps":["trace[326887024] 'process raft request' (duration: 236.952085ms)","trace[326887024] 'compare' (duration: 101.051792ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:50.070017Z","caller":"traceutil/trace.go:171","msg":"trace[758329218] transaction","detail":"{read_only:false; response_revision:11180; number_of_response:1; }","duration":"228.649842ms","start":"2026-09-09T06:37:49.841325Z","end":"2026-09-09T06:37:50.069975Z","steps":["trace[758329218] 'process raft request' (duration: 228.408876ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:50.070068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:49.731247Z","time spent":"338.716556ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:37:50.070153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.274228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:37:50.070205Z","caller":"traceutil/trace.go:171","msg":"trace[1150555413] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:11180; }","duration":"139.374091ms","start":"2026-09-09T06:37:49.930814Z","end":"2026-09-09T06:37:50.070188Z","steps":["trace[1150555413] 'agreement among raft nodes before linearized reading' (duration: 139.30084ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:50.070752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.634539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:37:50.070835Z","caller":"traceutil/trace.go:171","msg":"trace[1936589707] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:11180; }","duration":"205.44851ms","start":"2026-09-09T06:37:49.865364Z","end":"2026-09-09T06:37:50.070812Z","steps":["trace[1936589707] 'agreement among raft nodes before linearized reading' (duration: 204.593448ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:50.34028Z","caller":"traceutil/trace.go:171","msg":"trace[867696276] transaction","detail":"{read_only:false; response_revision:11182; number_of_response:1; }","duration":"103.109865ms","start":"2026-09-09T06:37:50.237124Z","end":"2026-09-09T06:37:50.340234Z","steps":["trace[867696276] 'process raft request' (duration: 102.665344ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:52.279075Z","caller":"traceutil/trace.go:171","msg":"trace[667493238] transaction","detail":"{read_only:false; response_revision:11191; number_of_response:1; }","duration":"202.386212ms","start":"2026-09-09T06:37:52.076659Z","end":"2026-09-09T06:37:52.279045Z","steps":["trace[667493238] 'process raft request' (duration: 147.976352ms)","trace[667493238] 'compare' (duration: 54.281467ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:52.362373Z","caller":"traceutil/trace.go:171","msg":"trace[145964434] transaction","detail":"{read_only:false; response_revision:11192; number_of_response:1; }","duration":"188.209408ms","start":"2026-09-09T06:37:52.174134Z","end":"2026-09-09T06:37:52.362344Z","steps":["trace[145964434] 'process raft request' (duration: 188.001343ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:52.499931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.592297ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-09T06:37:52.500054Z","caller":"traceutil/trace.go:171","msg":"trace[1402511570] linearizableReadLoop","detail":"{readStateIndex:11591; appliedIndex:11589; }","duration":"154.596051ms","start":"2026-09-09T06:37:52.345437Z","end":"2026-09-09T06:37:52.500033Z","steps":["trace[1402511570] 'read index received' (duration: 16.672456ms)","trace[1402511570] 'applied index is now lower than readState.Index' (duration: 137.921525ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:52.500325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.28727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8xnr4\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-09T06:37:52.500361Z","caller":"traceutil/trace.go:171","msg":"trace[217549049] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8xnr4; range_end:; response_count:1; response_revision:11192; }","duration":"141.381062ms","start":"2026-09-09T06:37:52.358968Z","end":"2026-09-09T06:37:52.500349Z","steps":["trace[217549049] 'agreement among raft nodes before linearized reading' (duration: 141.182597ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:52.500431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.916348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-09T06:37:52.500574Z","caller":"traceutil/trace.go:171","msg":"trace[237752559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11192; }","duration":"155.191636ms","start":"2026-09-09T06:37:52.345358Z","end":"2026-09-09T06:37:52.50055Z","steps":["trace[237752559] 'agreement among raft nodes before linearized reading' (duration: 154.774685ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:52.500859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.312451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:37:52.500964Z","caller":"traceutil/trace.go:171","msg":"trace[502357120] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:11192; }","duration":"123.459674ms","start":"2026-09-09T06:37:52.377478Z","end":"2026-09-09T06:37:52.500938Z","steps":["trace[502357120] 'agreement among raft nodes before linearized reading' (duration: 123.215448ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:52.722607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.59412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:37:52.722825Z","caller":"traceutil/trace.go:171","msg":"trace[1794441107] transaction","detail":"{read_only:false; response_revision:11194; number_of_response:1; }","duration":"217.391034ms","start":"2026-09-09T06:37:52.505411Z","end":"2026-09-09T06:37:52.722802Z","steps":["trace[1794441107] 'process raft request' (duration: 107.474356ms)","trace[1794441107] 'compare' (duration: 109.399225ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:52.723079Z","caller":"traceutil/trace.go:171","msg":"trace[1000187513] linearizableReadLoop","detail":"{readStateIndex:11594; appliedIndex:11591; }","duration":"214.969454ms","start":"2026-09-09T06:37:52.508072Z","end":"2026-09-09T06:37:52.723042Z","steps":["trace[1000187513] 'read index received' (duration: 59.656964ms)","trace[1000187513] 'applied index is now lower than readState.Index' (duration: 155.31009ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:52.723105Z","caller":"traceutil/trace.go:171","msg":"trace[1401183821] transaction","detail":"{read_only:false; response_revision:11195; number_of_response:1; }","duration":"217.5954ms","start":"2026-09-09T06:37:52.505471Z","end":"2026-09-09T06:37:52.723066Z","steps":["trace[1401183821] 'process raft request' (duration: 217.271621ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:52.723602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.246366ms","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-09-09T06:37:52.723703Z","caller":"traceutil/trace.go:171","msg":"trace[959775818] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11195; }","duration":"180.41922ms","start":"2026-09-09T06:37:52.54326Z","end":"2026-09-09T06:37:52.72368Z","steps":["trace[959775818] 'agreement among raft nodes before linearized reading' (duration: 180.045011ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:52.724208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.113402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:42 size:385920"} {"level":"info","ts":"2026-09-09T06:37:52.724365Z","caller":"traceutil/trace.go:171","msg":"trace[1074540398] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:42; response_revision:11195; }","duration":"216.260556ms","start":"2026-09-09T06:37:52.508032Z","end":"2026-09-09T06:37:52.724293Z","steps":["trace[1074540398] 'agreement among raft nodes before linearized reading' (duration: 215.122698ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:55.086159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.723525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:37:55.08647Z","caller":"traceutil/trace.go:171","msg":"trace[2107682712] linearizableReadLoop","detail":"{readStateIndex:11603; appliedIndex:11601; }","duration":"274.614127ms","start":"2026-09-09T06:37:54.811827Z","end":"2026-09-09T06:37:55.086441Z","steps":["trace[2107682712] 'read index received' (duration: 38.938285ms)","trace[2107682712] 'applied index is now lower than readState.Index' (duration: 235.673322ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:55.086521Z","caller":"traceutil/trace.go:171","msg":"trace[865639850] transaction","detail":"{read_only:false; response_revision:11205; number_of_response:1; }","duration":"274.360711ms","start":"2026-09-09T06:37:54.812133Z","end":"2026-09-09T06:37:55.086494Z","steps":["trace[865639850] 'process raft request' (duration: 274.166316ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:55.08661Z","caller":"traceutil/trace.go:171","msg":"trace[68002070] transaction","detail":"{read_only:false; response_revision:11204; number_of_response:1; }","duration":"274.787861ms","start":"2026-09-09T06:37:54.811721Z","end":"2026-09-09T06:37:55.086509Z","steps":["trace[68002070] 'process raft request' (duration: 162.572514ms)","trace[68002070] 'compare' (duration: 111.484629ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:55.08661Z","caller":"traceutil/trace.go:171","msg":"trace[1393814977] transaction","detail":"{read_only:false; response_revision:11206; number_of_response:1; }","duration":"274.325151ms","start":"2026-09-09T06:37:54.812188Z","end":"2026-09-09T06:37:55.086513Z","steps":["trace[1393814977] 'process raft request' (duration: 274.187727ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:55.087151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.295865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:42 size:385920"} {"level":"info","ts":"2026-09-09T06:37:55.087264Z","caller":"traceutil/trace.go:171","msg":"trace[1752510870] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:42; response_revision:11206; }","duration":"275.481979ms","start":"2026-09-09T06:37:54.811763Z","end":"2026-09-09T06:37:55.087245Z","steps":["trace[1752510870] 'agreement among raft nodes before linearized reading' (duration: 274.751681ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:55.36227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.559856ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:37:55.362501Z","caller":"traceutil/trace.go:171","msg":"trace[1914584347] transaction","detail":"{read_only:false; response_revision:11208; number_of_response:1; }","duration":"153.683197ms","start":"2026-09-09T06:37:55.208794Z","end":"2026-09-09T06:37:55.362477Z","steps":["trace[1914584347] 'process raft request' (duration: 153.608055ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:55.362515Z","caller":"traceutil/trace.go:171","msg":"trace[441503748] transaction","detail":"{read_only:false; response_revision:11207; number_of_response:1; }","duration":"198.199824ms","start":"2026-09-09T06:37:55.16427Z","end":"2026-09-09T06:37:55.362469Z","steps":["trace[441503748] 'process raft request' (duration: 31.302299ms)","trace[441503748] 'compare' (duration: 166.397362ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:56.559433Z","caller":"traceutil/trace.go:171","msg":"trace[911552433] transaction","detail":"{read_only:false; response_revision:11210; number_of_response:1; }","duration":"218.408161ms","start":"2026-09-09T06:37:56.340992Z","end":"2026-09-09T06:37:56.5594Z","steps":["trace[911552433] 'process raft request' (duration: 218.200496ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:56.637797Z","caller":"traceutil/trace.go:171","msg":"trace[1310796924] transaction","detail":"{read_only:false; response_revision:11211; number_of_response:1; }","duration":"216.910182ms","start":"2026-09-09T06:37:56.420842Z","end":"2026-09-09T06:37:56.637753Z","steps":["trace[1310796924] 'process raft request' (duration: 203.218622ms)","trace[1310796924] 'compare' (duration: 13.249158ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:57.285061Z","caller":"traceutil/trace.go:171","msg":"trace[625229434] transaction","detail":"{read_only:false; response_revision:11216; number_of_response:1; }","duration":"187.43969ms","start":"2026-09-09T06:37:57.097588Z","end":"2026-09-09T06:37:57.285028Z","steps":["trace[625229434] 'process raft request' (duration: 119.976116ms)","trace[625229434] 'compare' (duration: 67.160786ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:57.285263Z","caller":"traceutil/trace.go:171","msg":"trace[1984036682] transaction","detail":"{read_only:false; response_revision:11217; number_of_response:1; }","duration":"184.561696ms","start":"2026-09-09T06:37:57.100669Z","end":"2026-09-09T06:37:57.28523Z","steps":["trace[1984036682] 'process raft request' (duration: 184.244428ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:57.285432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.425796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.56\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-09T06:37:57.285479Z","caller":"traceutil/trace.go:171","msg":"trace[1810109031] range","detail":"{range_begin:/registry/masterleases/162.253.55.56; range_end:; response_count:1; response_revision:11217; }","duration":"179.577289ms","start":"2026-09-09T06:37:57.105889Z","end":"2026-09-09T06:37:57.285466Z","steps":["trace[1810109031] 'agreement among raft nodes before linearized reading' (duration: 179.404034ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:57.285435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.020533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:10848"} {"level":"info","ts":"2026-09-09T06:37:57.28558Z","caller":"traceutil/trace.go:171","msg":"trace[141467759] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:11217; }","duration":"150.223548ms","start":"2026-09-09T06:37:57.13533Z","end":"2026-09-09T06:37:57.285553Z","steps":["trace[141467759] 'agreement among raft nodes before linearized reading' (duration: 149.852618ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:57.285818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.076565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1857"} {"level":"info","ts":"2026-09-09T06:37:57.286015Z","caller":"traceutil/trace.go:171","msg":"trace[342478439] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:11217; }","duration":"132.436604ms","start":"2026-09-09T06:37:57.153549Z","end":"2026-09-09T06:37:57.285986Z","steps":["trace[342478439] 'agreement among raft nodes before linearized reading' (duration: 131.777107ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:57.442335Z","caller":"traceutil/trace.go:171","msg":"trace[1068152181] transaction","detail":"{read_only:false; response_revision:11218; number_of_response:1; }","duration":"142.136733ms","start":"2026-09-09T06:37:57.300157Z","end":"2026-09-09T06:37:57.442294Z","steps":["trace[1068152181] 'process raft request' (duration: 88.989545ms)","trace[1068152181] 'compare' (duration: 52.837719ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:57.442918Z","caller":"traceutil/trace.go:171","msg":"trace[1079674796] transaction","detail":"{read_only:false; response_revision:11219; number_of_response:1; }","duration":"120.334064ms","start":"2026-09-09T06:37:57.322558Z","end":"2026-09-09T06:37:57.442892Z","steps":["trace[1079674796] 'process raft request' (duration: 119.644348ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:57.593823Z","caller":"traceutil/trace.go:171","msg":"trace[1690041505] transaction","detail":"{read_only:false; response_revision:11221; number_of_response:1; }","duration":"144.840011ms","start":"2026-09-09T06:37:57.448944Z","end":"2026-09-09T06:37:57.593784Z","steps":["trace[1690041505] 'process raft request' (duration: 143.925507ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:57.593938Z","caller":"traceutil/trace.go:171","msg":"trace[396829118] transaction","detail":"{read_only:false; response_revision:11222; number_of_response:1; }","duration":"143.601609ms","start":"2026-09-09T06:37:57.45029Z","end":"2026-09-09T06:37:57.593892Z","steps":["trace[396829118] 'process raft request' (duration: 143.362973ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:57.594092Z","caller":"traceutil/trace.go:171","msg":"trace[282493764] transaction","detail":"{read_only:false; response_revision:11223; number_of_response:1; }","duration":"142.774858ms","start":"2026-09-09T06:37:57.451269Z","end":"2026-09-09T06:37:57.594044Z","steps":["trace[282493764] 'process raft request' (duration: 142.45125ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:57.594421Z","caller":"traceutil/trace.go:171","msg":"trace[1122679344] transaction","detail":"{read_only:false; response_revision:11225; number_of_response:1; }","duration":"133.193934ms","start":"2026-09-09T06:37:57.461211Z","end":"2026-09-09T06:37:57.594405Z","steps":["trace[1122679344] 'process raft request' (duration: 132.945577ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:57.59444Z","caller":"traceutil/trace.go:171","msg":"trace[360707846] transaction","detail":"{read_only:false; response_revision:11224; number_of_response:1; }","duration":"141.700201ms","start":"2026-09-09T06:37:57.452718Z","end":"2026-09-09T06:37:57.594418Z","steps":["trace[360707846] 'process raft request' (duration: 141.100255ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:57.759784Z","caller":"traceutil/trace.go:171","msg":"trace[2073673508] linearizableReadLoop","detail":"{readStateIndex:11627; appliedIndex:11626; }","duration":"116.766124ms","start":"2026-09-09T06:37:57.642982Z","end":"2026-09-09T06:37:57.759748Z","steps":["trace[2073673508] 'read index received' (duration: 63.890662ms)","trace[2073673508] 'applied index is now lower than readState.Index' (duration: 52.874562ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:57.760017Z","caller":"traceutil/trace.go:171","msg":"trace[1394976043] transaction","detail":"{read_only:false; response_revision:11227; number_of_response:1; }","duration":"120.52525ms","start":"2026-09-09T06:37:57.639308Z","end":"2026-09-09T06:37:57.759833Z","steps":["trace[1394976043] 'process raft request' (duration: 67.685999ms)","trace[1394976043] 'compare' (duration: 52.578973ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:57.760107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.095682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-cinder-server\" ","response":"range_response_count:1 size:9647"} {"level":"info","ts":"2026-09-09T06:37:57.760342Z","caller":"traceutil/trace.go:171","msg":"trace[1926156771] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-cinder-server; range_end:; response_count:1; response_revision:11227; }","duration":"117.368669ms","start":"2026-09-09T06:37:57.642954Z","end":"2026-09-09T06:37:57.760322Z","steps":["trace[1926156771] 'agreement among raft nodes before linearized reading' (duration: 116.965399ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:38:35.953168Z","caller":"traceutil/trace.go:171","msg":"trace[1665126130] transaction","detail":"{read_only:false; response_revision:11626; number_of_response:1; }","duration":"108.853782ms","start":"2026-09-09T06:38:35.844266Z","end":"2026-09-09T06:38:35.95312Z","steps":["trace[1665126130] 'process raft request' (duration: 108.699058ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:41:23.848419Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10426} {"level":"info","ts":"2026-09-09T06:41:23.942505Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10426,"took":"91.071128ms","hash":1698751124,"current-db-size-bytes":54624256,"current-db-size":"55 MB","current-db-size-in-use-bytes":28065792,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-09T06:41:23.942614Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1698751124,"revision":10426,"compact-revision":7979} {"level":"info","ts":"2026-09-09T06:45:11.517601Z","caller":"traceutil/trace.go:171","msg":"trace[1050604280] transaction","detail":"{read_only:false; response_revision:14252; number_of_response:1; }","duration":"115.348028ms","start":"2026-09-09T06:45:11.40218Z","end":"2026-09-09T06:45:11.517528Z","steps":["trace[1050604280] 'process raft request' (duration: 115.02082ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:45:26.357102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.026728ms","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-09-09T06:45:26.357208Z","caller":"traceutil/trace.go:171","msg":"trace[1413102632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14326; }","duration":"314.198853ms","start":"2026-09-09T06:45:26.042985Z","end":"2026-09-09T06:45:26.357184Z","steps":["trace[1413102632] 'range keys from in-memory index tree' (duration: 313.70279ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:45:26.357254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:45:26.042957Z","time spent":"314.286744ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-09T06:45:26.357291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.118324ms","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-09-09T06:45:26.357368Z","caller":"traceutil/trace.go:171","msg":"trace[991070032] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14326; }","duration":"303.219666ms","start":"2026-09-09T06:45:26.054129Z","end":"2026-09-09T06:45:26.357348Z","steps":["trace[991070032] 'range keys from in-memory index tree' (duration: 302.971579ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:45:26.357398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:45:26.054114Z","time spent":"303.275898ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-09T06:45:26.358197Z","caller":"traceutil/trace.go:171","msg":"trace[2010181544] transaction","detail":"{read_only:false; response_revision:14327; number_of_response:1; }","duration":"131.054084ms","start":"2026-09-09T06:45:26.227107Z","end":"2026-09-09T06:45:26.358161Z","steps":["trace[2010181544] 'process raft request' (duration: 130.86297ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:45:40.2741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.174969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-09-09T06:45:40.275502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.514405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-09T06:45:40.275608Z","caller":"traceutil/trace.go:171","msg":"trace[2001632334] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14526; }","duration":"499.624588ms","start":"2026-09-09T06:45:39.775931Z","end":"2026-09-09T06:45:40.275556Z","steps":["trace[2001632334] 'agreement among raft nodes before linearized reading' (duration: 499.330221ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:45:40.27567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:45:39.775911Z","time spent":"499.74299ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-09T06:45:40.27585Z","caller":"traceutil/trace.go:171","msg":"trace[1268447770] linearizableReadLoop","detail":"{readStateIndex:15037; appliedIndex:15036; }","duration":"499.144715ms","start":"2026-09-09T06:45:39.775973Z","end":"2026-09-09T06:45:40.275118Z","steps":["trace[1268447770] 'read index received' (duration: 352.692653ms)","trace[1268447770] 'applied index is now lower than readState.Index' (duration: 146.449912ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:45:40.27628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.198041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:45:40.276343Z","caller":"traceutil/trace.go:171","msg":"trace[748178689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14526; }","duration":"116.312853ms","start":"2026-09-09T06:45:40.160015Z","end":"2026-09-09T06:45:40.276328Z","steps":["trace[748178689] 'agreement among raft nodes before linearized reading' (duration: 116.213121ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:45:40.276349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.286319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8xnr4\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-09T06:45:40.276417Z","caller":"traceutil/trace.go:171","msg":"trace[539014906] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8xnr4; range_end:; response_count:1; response_revision:14526; }","duration":"424.424532ms","start":"2026-09-09T06:45:39.851971Z","end":"2026-09-09T06:45:40.276396Z","steps":["trace[539014906] 'agreement among raft nodes before linearized reading' (duration: 424.085633ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:45:40.276461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:45:39.851949Z","time spent":"424.501273ms","remote":"127.0.0.1:34076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-8xnr4\" "} {"level":"warn","ts":"2026-09-09T06:45:40.276851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.597622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-bth6d.18d3941f277775e6\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-09-09T06:45:40.276888Z","caller":"traceutil/trace.go:171","msg":"trace[1982667113] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-bth6d.18d3941f277775e6; range_end:; response_count:1; response_revision:14526; }","duration":"218.676374ms","start":"2026-09-09T06:45:40.058197Z","end":"2026-09-09T06:45:40.276874Z","steps":["trace[1982667113] 'agreement among raft nodes before linearized reading' (duration: 218.562851ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:45:40.27718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.135166ms","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-09-09T06:45:40.277274Z","caller":"traceutil/trace.go:171","msg":"trace[616410116] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14526; }","duration":"363.295791ms","start":"2026-09-09T06:45:39.913953Z","end":"2026-09-09T06:45:40.277249Z","steps":["trace[616410116] 'agreement among raft nodes before linearized reading' (duration: 363.051214ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:45:40.277318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:45:39.913881Z","time spent":"363.428804ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-09T06:45:40.27751Z","caller":"traceutil/trace.go:171","msg":"trace[1533077344] transaction","detail":"{read_only:false; response_revision:14526; number_of_response:1; }","duration":"505.483525ms","start":"2026-09-09T06:45:39.769711Z","end":"2026-09-09T06:45:40.275194Z","steps":["trace[1533077344] 'process raft request' (duration: 359.093135ms)","trace[1533077344] 'compare' (duration: 144.564155ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:45:40.277714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:45:39.76969Z","time spent":"507.954778ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:46:23.85817Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12751} {"level":"info","ts":"2026-09-09T06:46:23.949812Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12751,"took":"88.644585ms","hash":2589701067,"current-db-size-bytes":54624256,"current-db-size":"55 MB","current-db-size-in-use-bytes":28487680,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-09T06:46:23.949895Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2589701067,"revision":12751,"compact-revision":10426} {"level":"info","ts":"2026-09-09T06:46:50.811772Z","caller":"traceutil/trace.go:171","msg":"trace[1901942859] linearizableReadLoop","detail":"{readStateIndex:15999; appliedIndex:15998; }","duration":"137.163004ms","start":"2026-09-09T06:46:50.674572Z","end":"2026-09-09T06:46:50.811735Z","steps":["trace[1901942859] 'read index received' (duration: 136.929337ms)","trace[1901942859] 'applied index is now lower than readState.Index' (duration: 232.237µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:46:50.811853Z","caller":"traceutil/trace.go:171","msg":"trace[379368538] transaction","detail":"{read_only:false; response_revision:15460; number_of_response:1; }","duration":"158.142015ms","start":"2026-09-09T06:46:50.653666Z","end":"2026-09-09T06:46:50.811808Z","steps":["trace[379368538] 'process raft request' (duration: 157.838787ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:46:50.813002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.395834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-09T06:46:50.813066Z","caller":"traceutil/trace.go:171","msg":"trace[621595419] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:15460; }","duration":"138.490807ms","start":"2026-09-09T06:46:50.67456Z","end":"2026-09-09T06:46:50.813051Z","steps":["trace[621595419] 'agreement among raft nodes before linearized reading' (duration: 137.343188ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:46:50.892468Z","caller":"traceutil/trace.go:171","msg":"trace[1690377589] transaction","detail":"{read_only:false; response_revision:15461; number_of_response:1; }","duration":"186.684068ms","start":"2026-09-09T06:46:50.705729Z","end":"2026-09-09T06:46:50.892413Z","steps":["trace[1690377589] 'process raft request' (duration: 169.713549ms)","trace[1690377589] 'compare' (duration: 16.713173ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:46:50.89251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.44815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-09-09T06:46:50.892573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.116819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-09T06:46:50.892642Z","caller":"traceutil/trace.go:171","msg":"trace[1167263933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15461; }","duration":"182.613694ms","start":"2026-09-09T06:46:50.710006Z","end":"2026-09-09T06:46:50.89262Z","steps":["trace[1167263933] 'agreement among raft nodes before linearized reading' (duration: 182.343038ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:46:50.8927Z","caller":"traceutil/trace.go:171","msg":"trace[12415741] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15461; }","duration":"110.311924ms","start":"2026-09-09T06:46:50.782365Z","end":"2026-09-09T06:46:50.892677Z","steps":["trace[12415741] 'agreement among raft nodes before linearized reading' (duration: 109.975876ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:46:50.892762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.777555ms","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-09-09T06:46:50.892863Z","caller":"traceutil/trace.go:171","msg":"trace[1617972068] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15461; }","duration":"183.919358ms","start":"2026-09-09T06:46:50.708917Z","end":"2026-09-09T06:46:50.892836Z","steps":["trace[1617972068] 'agreement among raft nodes before linearized reading' (duration: 183.555879ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:46:50.89291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.892809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-09T06:46:50.893032Z","caller":"traceutil/trace.go:171","msg":"trace[1469022727] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15461; }","duration":"190.077745ms","start":"2026-09-09T06:46:50.702927Z","end":"2026-09-09T06:46:50.893004Z","steps":["trace[1469022727] 'agreement among raft nodes before linearized reading' (duration: 189.619213ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:47:22.785288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.087144ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-09T06:47:22.785414Z","caller":"traceutil/trace.go:171","msg":"trace[1399743388] linearizableReadLoop","detail":"{readStateIndex:16175; appliedIndex:16174; }","duration":"293.598126ms","start":"2026-09-09T06:47:22.491793Z","end":"2026-09-09T06:47:22.785391Z","steps":["trace[1399743388] 'read index received' (duration: 60.451µs)","trace[1399743388] 'applied index is now lower than readState.Index' (duration: 293.535495ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:47:22.785559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.224745ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:47:22.785737Z","caller":"traceutil/trace.go:171","msg":"trace[2092680988] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:15629; }","duration":"197.41103ms","start":"2026-09-09T06:47:22.588302Z","end":"2026-09-09T06:47:22.785713Z","steps":["trace[2092680988] 'range keys from in-memory index tree' (duration: 197.205315ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:47:22.785832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.504244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-09-09T06:47:22.785874Z","caller":"traceutil/trace.go:171","msg":"trace[1525457663] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15629; }","duration":"158.600966ms","start":"2026-09-09T06:47:22.627262Z","end":"2026-09-09T06:47:22.785863Z","steps":["trace[1525457663] 'agreement among raft nodes before linearized reading' (duration: 158.410362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:47:22.785852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.032636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T06:47:22.786Z","caller":"traceutil/trace.go:171","msg":"trace[1983812770] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:15629; }","duration":"294.246562ms","start":"2026-09-09T06:47:22.491729Z","end":"2026-09-09T06:47:22.785975Z","steps":["trace[1983812770] 'agreement among raft nodes before linearized reading' (duration: 293.890754ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:47:22.786261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.485483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-09T06:47:22.786328Z","caller":"traceutil/trace.go:171","msg":"trace[142613710] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15629; }","duration":"109.550815ms","start":"2026-09-09T06:47:22.676763Z","end":"2026-09-09T06:47:22.786314Z","steps":["trace[142613710] 'agreement among raft nodes before linearized reading' (duration: 109.411221ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:47:22.786473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.566631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-09-09T06:47:22.786493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.227108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:47:22.786523Z","caller":"traceutil/trace.go:171","msg":"trace[586082837] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15629; }","duration":"112.670234ms","start":"2026-09-09T06:47:22.673841Z","end":"2026-09-09T06:47:22.786511Z","steps":["trace[586082837] 'agreement among raft nodes before linearized reading' (duration: 112.543471ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:47:22.786559Z","caller":"traceutil/trace.go:171","msg":"trace[840017394] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15629; }","duration":"115.335031ms","start":"2026-09-09T06:47:22.671204Z","end":"2026-09-09T06:47:22.786539Z","steps":["trace[840017394] 'agreement among raft nodes before linearized reading' (duration: 115.234258ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:47:23.969639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.897312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:47:23.969754Z","caller":"traceutil/trace.go:171","msg":"trace[1870865307] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:15638; }","duration":"335.111437ms","start":"2026-09-09T06:47:23.634615Z","end":"2026-09-09T06:47:23.969727Z","steps":["trace[1870865307] 'range keys from in-memory index tree' (duration: 334.78999ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:47:23.969843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:47:23.634566Z","time spent":"335.26425ms","remote":"127.0.0.1:34330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-09-09T06:47:23.969994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.1744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8xnr4\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-09T06:47:23.970057Z","caller":"traceutil/trace.go:171","msg":"trace[480014630] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8xnr4; range_end:; response_count:1; response_revision:15638; }","duration":"311.267632ms","start":"2026-09-09T06:47:23.658772Z","end":"2026-09-09T06:47:23.97004Z","steps":["trace[480014630] 'range keys from in-memory index tree' (duration: 310.919354ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:47:23.970091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:47:23.658756Z","time spent":"311.326645ms","remote":"127.0.0.1:34076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-8xnr4\" "} {"level":"info","ts":"2026-09-09T06:51:23.870295Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15005} {"level":"info","ts":"2026-09-09T06:51:23.979859Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15005,"took":"105.699788ms","hash":1728482232,"current-db-size-bytes":54624256,"current-db-size":"55 MB","current-db-size-in-use-bytes":30441472,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-09T06:51:23.979942Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1728482232,"revision":15005,"compact-revision":12751} {"level":"info","ts":"2026-09-09T06:53:03.090709Z","caller":"traceutil/trace.go:171","msg":"trace[982173144] linearizableReadLoop","detail":"{readStateIndex:18725; appliedIndex:18724; }","duration":"355.699479ms","start":"2026-09-09T06:53:02.734986Z","end":"2026-09-09T06:53:03.090686Z","steps":["trace[982173144] 'read index received' (duration: 355.459663ms)","trace[982173144] 'applied index is now lower than readState.Index' (duration: 238.926µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:53:03.091006Z","caller":"traceutil/trace.go:171","msg":"trace[1551282300] transaction","detail":"{read_only:false; response_revision:18095; number_of_response:1; }","duration":"356.309974ms","start":"2026-09-09T06:53:02.734686Z","end":"2026-09-09T06:53:03.090996Z","steps":["trace[1551282300] 'process raft request' (duration: 355.837013ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:53:03.091108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:53:02.734658Z","time spent":"356.368115ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:53:03.091367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.378437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19624"} {"level":"info","ts":"2026-09-09T06:53:03.091388Z","caller":"traceutil/trace.go:171","msg":"trace[1697740540] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:18095; }","duration":"356.424387ms","start":"2026-09-09T06:53:02.734957Z","end":"2026-09-09T06:53:03.091382Z","steps":["trace[1697740540] 'agreement among raft nodes before linearized reading' (duration: 356.296954ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:53:03.091405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:53:02.734948Z","time spent":"356.453248ms","remote":"127.0.0.1:34072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19647,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-09-09T06:53:03.09159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.905532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-rdqqg\" ","response":"range_response_count:1 size:20751"} {"level":"info","ts":"2026-09-09T06:53:03.091607Z","caller":"traceutil/trace.go:171","msg":"trace[1066201354] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-rdqqg; range_end:; response_count:1; response_revision:18095; }","duration":"288.962905ms","start":"2026-09-09T06:53:02.802638Z","end":"2026-09-09T06:53:03.091601Z","steps":["trace[1066201354] 'agreement among raft nodes before linearized reading' (duration: 288.879303ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:53:03.092099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.213694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-09T06:53:03.092117Z","caller":"traceutil/trace.go:171","msg":"trace[475874595] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18095; }","duration":"137.278856ms","start":"2026-09-09T06:53:02.954833Z","end":"2026-09-09T06:53:03.092112Z","steps":["trace[475874595] 'agreement among raft nodes before linearized reading' (duration: 137.216034ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:53:03.092267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.138894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T06:53:03.092298Z","caller":"traceutil/trace.go:171","msg":"trace[1965432090] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:18095; }","duration":"225.225356ms","start":"2026-09-09T06:53:02.867063Z","end":"2026-09-09T06:53:03.092289Z","steps":["trace[1965432090] 'agreement among raft nodes before linearized reading' (duration: 225.161975ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:55:14.15161Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c9fb5245756f3898","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-09T06:55:14.162889Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-09T06:55:14.163111Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-09T06:56:23.877957Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17396} {"level":"info","ts":"2026-09-09T06:56:23.996562Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17396,"took":"114.855348ms","hash":1927916250,"current-db-size-bytes":54624256,"current-db-size":"55 MB","current-db-size-in-use-bytes":33804288,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-09T06:56:23.996659Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1927916250,"revision":17396,"compact-revision":15005} {"level":"info","ts":"2026-09-09T06:58:53.518899Z","caller":"traceutil/trace.go:171","msg":"trace[946871581] linearizableReadLoop","detail":"{readStateIndex:21512; appliedIndex:21511; }","duration":"219.381276ms","start":"2026-09-09T06:58:53.299483Z","end":"2026-09-09T06:58:53.518864Z","steps":["trace[946871581] 'read index received' (duration: 219.023628ms)","trace[946871581] 'applied index is now lower than readState.Index' (duration: 356.338µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:58:53.519269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.741126ms","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-09-09T06:58:53.519275Z","caller":"traceutil/trace.go:171","msg":"trace[714225022] transaction","detail":"{read_only:false; response_revision:20794; number_of_response:1; }","duration":"236.431356ms","start":"2026-09-09T06:58:53.282795Z","end":"2026-09-09T06:58:53.519226Z","steps":["trace[714225022] 'process raft request' (duration: 235.81606ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:58:53.519335Z","caller":"traceutil/trace.go:171","msg":"trace[36303017] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20794; }","duration":"219.842718ms","start":"2026-09-09T06:58:53.299475Z","end":"2026-09-09T06:58:53.519318Z","steps":["trace[36303017] 'agreement among raft nodes before linearized reading' (duration: 219.532451ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:58:53.910509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.681844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-09T06:58:53.910613Z","caller":"traceutil/trace.go:171","msg":"trace[389464717] linearizableReadLoop","detail":"{readStateIndex:21513; appliedIndex:21512; }","duration":"379.826345ms","start":"2026-09-09T06:58:53.530766Z","end":"2026-09-09T06:58:53.910593Z","steps":["trace[389464717] 'read index received' (duration: 179.048858ms)","trace[389464717] 'applied index is now lower than readState.Index' (duration: 200.775637ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:58:53.910739Z","caller":"traceutil/trace.go:171","msg":"trace[1575704247] transaction","detail":"{read_only:false; response_revision:20795; number_of_response:1; }","duration":"386.885012ms","start":"2026-09-09T06:58:53.523837Z","end":"2026-09-09T06:58:53.910722Z","steps":["trace[1575704247] 'process raft request' (duration: 185.907411ms)","trace[1575704247] 'compare' (duration: 200.384537ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:58:53.910814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:58:53.523809Z","time spent":"386.962574ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:58:53.910986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.15042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-09T06:58:53.91103Z","caller":"traceutil/trace.go:171","msg":"trace[1655941198] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:20795; }","duration":"152.270472ms","start":"2026-09-09T06:58:53.758747Z","end":"2026-09-09T06:58:53.911018Z","steps":["trace[1655941198] 'agreement among raft nodes before linearized reading' (duration: 152.146709ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:58:53.911067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.245645ms","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-09-09T06:58:53.911141Z","caller":"traceutil/trace.go:171","msg":"trace[355491631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20795; }","duration":"380.396909ms","start":"2026-09-09T06:58:53.530725Z","end":"2026-09-09T06:58:53.911122Z","steps":["trace[355491631] 'agreement among raft nodes before linearized reading' (duration: 380.146152ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:58:53.911169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.666649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-09T06:58:53.911161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.363418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-09-09T06:58:53.911267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.440651ms","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-09-09T06:58:53.911284Z","caller":"traceutil/trace.go:171","msg":"trace[75578301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20795; }","duration":"380.558864ms","start":"2026-09-09T06:58:53.530701Z","end":"2026-09-09T06:58:53.91126Z","steps":["trace[75578301] 'agreement among raft nodes before linearized reading' (duration: 380.199374ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:58:53.911305Z","caller":"traceutil/trace.go:171","msg":"trace[1621954830] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20795; }","duration":"380.474602ms","start":"2026-09-09T06:58:53.530817Z","end":"2026-09-09T06:58:53.911292Z","steps":["trace[1621954830] 'agreement among raft nodes before linearized reading' (duration: 380.389029ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:58:53.911339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:58:53.530673Z","time spent":"380.652866ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-09T06:58:53.911365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:58:53.530769Z","time spent":"380.586684ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-09T06:58:53.911227Z","caller":"traceutil/trace.go:171","msg":"trace[1713613101] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:20795; }","duration":"348.748921ms","start":"2026-09-09T06:58:53.56246Z","end":"2026-09-09T06:58:53.911209Z","steps":["trace[1713613101] 'agreement among raft nodes before linearized reading' (duration: 348.662558ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:58:53.911425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:58:53.562448Z","time spent":"348.963956ms","remote":"127.0.0.1:38404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true "} {"level":"warn","ts":"2026-09-09T06:58:53.911178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:58:53.530697Z","time spent":"380.472572ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-09T06:58:54.250054Z","caller":"traceutil/trace.go:171","msg":"trace[407563415] transaction","detail":"{read_only:false; response_revision:20796; number_of_response:1; }","duration":"333.751212ms","start":"2026-09-09T06:58:53.91628Z","end":"2026-09-09T06:58:54.250031Z","steps":["trace[407563415] 'process raft request' (duration: 333.580107ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:58:54.250201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:58:53.916251Z","time spent":"333.865755ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:58:54.563824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.962534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-09T06:58:54.563993Z","caller":"traceutil/trace.go:171","msg":"trace[1669820940] transaction","detail":"{read_only:false; response_revision:20797; number_of_response:1; }","duration":"646.451803ms","start":"2026-09-09T06:58:53.917521Z","end":"2026-09-09T06:58:54.563973Z","steps":["trace[1669820940] 'process raft request' (duration: 435.252674ms)","trace[1669820940] 'compare' (duration: 210.612464ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:58:54.564061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:58:53.917487Z","time spent":"646.542406ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:58:54.564538Z","caller":"traceutil/trace.go:171","msg":"trace[617116603] linearizableReadLoop","detail":"{readStateIndex:21516; appliedIndex:21513; }","duration":"624.249352ms","start":"2026-09-09T06:58:53.940261Z","end":"2026-09-09T06:58:54.56451Z","steps":["trace[617116603] 'read index received' (duration: 309.671494ms)","trace[617116603] 'applied index is now lower than readState.Index' (duration: 314.576408ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:58:54.564562Z","caller":"traceutil/trace.go:171","msg":"trace[2118866409] transaction","detail":"{read_only:false; response_revision:20799; number_of_response:1; }","duration":"413.670679ms","start":"2026-09-09T06:58:54.150862Z","end":"2026-09-09T06:58:54.564533Z","steps":["trace[2118866409] 'process raft request' (duration: 413.520245ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:58:54.564686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:58:54.150828Z","time spent":"413.786082ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:58:54.56459Z","caller":"traceutil/trace.go:171","msg":"trace[1778108501] transaction","detail":"{read_only:false; response_revision:20800; number_of_response:1; }","duration":"384.564473ms","start":"2026-09-09T06:58:54.179982Z","end":"2026-09-09T06:58:54.564547Z","steps":["trace[1778108501] 'process raft request' (duration: 384.478261ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:58:54.564841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.570251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-09-09T06:58:54.564895Z","caller":"traceutil/trace.go:171","msg":"trace[1089298027] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20800; }","duration":"624.663543ms","start":"2026-09-09T06:58:53.940217Z","end":"2026-09-09T06:58:54.564881Z","steps":["trace[1089298027] 'agreement among raft nodes before linearized reading' (duration: 624.465639ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:58:54.564931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:58:53.940203Z","time spent":"624.719184ms","remote":"127.0.0.1:34150","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":"warn","ts":"2026-09-09T06:58:54.565001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.566449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T06:58:54.56504Z","caller":"traceutil/trace.go:171","msg":"trace[1716662015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20800; }","duration":"404.64084ms","start":"2026-09-09T06:58:54.160389Z","end":"2026-09-09T06:58:54.56503Z","steps":["trace[1716662015] 'agreement among raft nodes before linearized reading' (duration: 404.582389ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:58:54.56507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:58:54.160374Z","time spent":"404.689882ms","remote":"127.0.0.1:33928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-09T06:58:54.564854Z","caller":"traceutil/trace.go:171","msg":"trace[313289288] transaction","detail":"{read_only:false; response_revision:20798; number_of_response:1; }","duration":"644.767561ms","start":"2026-09-09T06:58:53.920075Z","end":"2026-09-09T06:58:54.564843Z","steps":["trace[313289288] 'process raft request' (duration: 643.838347ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:58:54.565193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:58:53.920063Z","time spent":"645.072899ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:58:54.565428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:58:54.179953Z","time spent":"384.908853ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:58:54.961874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.763639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-09T06:58:54.962046Z","caller":"traceutil/trace.go:171","msg":"trace[1888366476] transaction","detail":"{read_only:false; response_revision:20801; number_of_response:1; }","duration":"389.880888ms","start":"2026-09-09T06:58:54.572138Z","end":"2026-09-09T06:58:54.962019Z","steps":["trace[1888366476] 'process raft request' (duration: 239.855642ms)","trace[1888366476] 'compare' (duration: 149.220565ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:58:54.962126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:58:54.572099Z","time spent":"389.98589ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:58:54.973476Z","caller":"traceutil/trace.go:171","msg":"trace[1410609840] linearizableReadLoop","detail":"{readStateIndex:21520; appliedIndex:21519; }","duration":"138.709089ms","start":"2026-09-09T06:58:54.834731Z","end":"2026-09-09T06:58:54.97344Z","steps":["trace[1410609840] 'read index received' (duration: 138.521315ms)","trace[1410609840] 'applied index is now lower than readState.Index' (duration: 185.704µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:58:54.973517Z","caller":"traceutil/trace.go:171","msg":"trace[518047103] transaction","detail":"{read_only:false; response_revision:20802; number_of_response:1; }","duration":"354.540555ms","start":"2026-09-09T06:58:54.61894Z","end":"2026-09-09T06:58:54.97348Z","steps":["trace[518047103] 'process raft request' (duration: 354.31859ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:58:54.973754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.858703ms","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":"warn","ts":"2026-09-09T06:58:54.973777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.044479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"warn","ts":"2026-09-09T06:58:54.97379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:58:54.618914Z","time spent":"354.758612ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:58:54.973808Z","caller":"traceutil/trace.go:171","msg":"trace[611685075] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:20802; }","duration":"115.956556ms","start":"2026-09-09T06:58:54.857834Z","end":"2026-09-09T06:58:54.973791Z","steps":["trace[611685075] 'agreement among raft nodes before linearized reading' (duration: 115.840103ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:58:54.973829Z","caller":"traceutil/trace.go:171","msg":"trace[1397863307] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:20802; }","duration":"139.134451ms","start":"2026-09-09T06:58:54.834679Z","end":"2026-09-09T06:58:54.973813Z","steps":["trace[1397863307] 'agreement among raft nodes before linearized reading' (duration: 138.897696ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:01:23.886112Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19769} {"level":"info","ts":"2026-09-09T07:01:24.016057Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19769,"took":"125.108527ms","hash":2257431378,"current-db-size-bytes":54624256,"current-db-size":"55 MB","current-db-size-in-use-bytes":29859840,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-09T07:01:24.016219Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2257431378,"revision":19769,"compact-revision":17396} {"level":"info","ts":"2026-09-09T07:02:55.127005Z","caller":"traceutil/trace.go:171","msg":"trace[168671649] transaction","detail":"{read_only:false; response_revision:23359; number_of_response:1; }","duration":"182.590348ms","start":"2026-09-09T07:02:54.94438Z","end":"2026-09-09T07:02:55.126971Z","steps":["trace[168671649] 'process raft request' (duration: 182.364502ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:06:23.893107Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22050} {"level":"info","ts":"2026-09-09T07:06:24.012344Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22050,"took":"115.711349ms","hash":2944212631,"current-db-size-bytes":54624256,"current-db-size":"55 MB","current-db-size-in-use-bytes":44130304,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-09-09T07:06:24.012625Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2944212631,"revision":22050,"compact-revision":19769} {"level":"info","ts":"2026-09-09T07:10:03.553663Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006c7c.wal"} {"level":"info","ts":"2026-09-09T07:11:23.904532Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25255} {"level":"info","ts":"2026-09-09T07:11:24.064098Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25255,"took":"155.189995ms","hash":2728287735,"current-db-size-bytes":54624256,"current-db-size":"55 MB","current-db-size-in-use-bytes":31318016,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-09T07:11:24.064186Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2728287735,"revision":25255,"compact-revision":22050}