{"level":"warn","ts":"2026-10-09T05:22:28.739512Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-09T05:22:28.739666Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.20:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.20:2380","--initial-cluster=instance=https://199.204.45.20:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.20:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.20:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-09T05:22:28.739776Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-09T05:22:28.739807Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.20:2380"]} {"level":"info","ts":"2026-10-09T05:22:28.739842Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-09T05:22:28.741127Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.20:2379"]} {"level":"info","ts":"2026-10-09T05:22:28.741364Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.20:2380"],"listen-peer-urls":["https://199.204.45.20:2380"],"advertise-client-urls":["https://199.204.45.20:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.20:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.20:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-09T05:22:28.745052Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.145347ms"} {"level":"info","ts":"2026-10-09T05:22:28.753574Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e2d037ebe7b91690","cluster-id":"7224e0058ac64c16"} {"level":"info","ts":"2026-10-09T05:22:28.753718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2d037ebe7b91690 switched to configuration voters=()"} {"level":"info","ts":"2026-10-09T05:22:28.75378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2d037ebe7b91690 became follower at term 0"} {"level":"info","ts":"2026-10-09T05:22:28.753795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e2d037ebe7b91690 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-09T05:22:28.753807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2d037ebe7b91690 became follower at term 1"} {"level":"info","ts":"2026-10-09T05:22:28.753864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2d037ebe7b91690 switched to configuration voters=(16343624534072039056)"} {"level":"warn","ts":"2026-10-09T05:22:28.765783Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-09T05:22:28.767913Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-09T05:22:28.769464Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-09T05:22:28.772586Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e2d037ebe7b91690","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-09T05:22:28.772995Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e2d037ebe7b91690","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-09T05:22:28.773193Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-09T05:22:28.773383Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-09T05:22:28.773399Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-09T05:22:28.774437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2d037ebe7b91690 switched to configuration voters=(16343624534072039056)"} {"level":"info","ts":"2026-10-09T05:22:28.775805Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7224e0058ac64c16","local-member-id":"e2d037ebe7b91690","added-peer-id":"e2d037ebe7b91690","added-peer-peer-urls":["https://199.204.45.20:2380"]} {"level":"info","ts":"2026-10-09T05:22:28.780343Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-09T05:22:28.780687Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.20:2380"} {"level":"info","ts":"2026-10-09T05:22:28.78076Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e2d037ebe7b91690","initial-advertise-peer-urls":["https://199.204.45.20:2380"],"listen-peer-urls":["https://199.204.45.20:2380"],"advertise-client-urls":["https://199.204.45.20:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.20:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-09T05:22:28.780809Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.20:2380"} {"level":"info","ts":"2026-10-09T05:22:28.780849Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-09T05:22:28.954185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2d037ebe7b91690 is starting a new election at term 1"} {"level":"info","ts":"2026-10-09T05:22:28.954297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2d037ebe7b91690 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-09T05:22:28.954352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2d037ebe7b91690 received MsgPreVoteResp from e2d037ebe7b91690 at term 1"} {"level":"info","ts":"2026-10-09T05:22:28.954382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2d037ebe7b91690 became candidate at term 2"} {"level":"info","ts":"2026-10-09T05:22:28.954395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2d037ebe7b91690 received MsgVoteResp from e2d037ebe7b91690 at term 2"} {"level":"info","ts":"2026-10-09T05:22:28.954416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2d037ebe7b91690 became leader at term 2"} {"level":"info","ts":"2026-10-09T05:22:28.95444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e2d037ebe7b91690 elected leader e2d037ebe7b91690 at term 2"} {"level":"info","ts":"2026-10-09T05:22:28.955959Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e2d037ebe7b91690","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.20:2379]}","request-path":"/0/members/e2d037ebe7b91690/attributes","cluster-id":"7224e0058ac64c16","publish-timeout":"7s"} {"level":"info","ts":"2026-10-09T05:22:28.956405Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T05:22:28.957012Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T05:22:28.957197Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T05:22:28.957696Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7224e0058ac64c16","local-member-id":"e2d037ebe7b91690","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T05:22:28.957683Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-09T05:22:28.957778Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-09T05:22:28.957819Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T05:22:28.957863Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T05:22:28.960139Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.20:2379"} {"level":"info","ts":"2026-10-09T05:22:28.96326Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-09T05:22:47.926294Z","caller":"traceutil/trace.go:171","msg":"trace[634532071] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"166.71928ms","start":"2026-10-09T05:22:47.759558Z","end":"2026-10-09T05:22:47.926277Z","steps":["trace[634532071] 'process raft request' (duration: 166.583327ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:23:19.600847Z","caller":"traceutil/trace.go:171","msg":"trace[1389159039] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"142.656523ms","start":"2026-10-09T05:23:19.45816Z","end":"2026-10-09T05:23:19.600816Z","steps":["trace[1389159039] 'process raft request' (duration: 120.026121ms)","trace[1389159039] 'compare' (duration: 22.222995ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:23:19.856658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.911334ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-09T05:23:19.856861Z","caller":"traceutil/trace.go:171","msg":"trace[379783143] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"151.698077ms","start":"2026-10-09T05:23:19.705129Z","end":"2026-10-09T05:23:19.856827Z","steps":["trace[379783143] 'process raft request' (duration: 15.930326ms)","trace[379783143] 'compare' (duration: 134.749051ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:23:20.370715Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1625976620481320736,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-09T05:23:20.383302Z","caller":"traceutil/trace.go:171","msg":"trace[1620456406] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"513.231873ms","start":"2026-10-09T05:23:19.870048Z","end":"2026-10-09T05:23:20.38328Z","steps":["trace[1620456406] 'read index received' (duration: 509.394009ms)","trace[1620456406] 'applied index is now lower than readState.Index' (duration: 3.836804ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:23:20.383405Z","caller":"traceutil/trace.go:171","msg":"trace[958604258] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"522.037622ms","start":"2026-10-09T05:23:19.861356Z","end":"2026-10-09T05:23:20.383394Z","steps":["trace[958604258] 'process raft request' (duration: 518.155178ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:23:20.383529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.831229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-09T05:23:20.383592Z","caller":"traceutil/trace.go:171","msg":"trace[1858613485] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:607; }","duration":"294.937161ms","start":"2026-10-09T05:23:20.088639Z","end":"2026-10-09T05:23:20.383576Z","steps":["trace[1858613485] 'agreement among raft nodes before linearized reading' (duration: 294.759897ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:23:20.383702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:23:19.861335Z","time spent":"522.091863ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T05:23:20.383784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.737593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:23:20.38385Z","caller":"traceutil/trace.go:171","msg":"trace[784600230] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:607; }","duration":"513.857546ms","start":"2026-10-09T05:23:19.869968Z","end":"2026-10-09T05:23:20.383825Z","steps":["trace[784600230] 'agreement among raft nodes before linearized reading' (duration: 513.722633ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:23:20.383912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:23:19.869932Z","time spent":"513.967768ms","remote":"127.0.0.1:53226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"info","ts":"2026-10-09T05:23:21.63876Z","caller":"traceutil/trace.go:171","msg":"trace[450784894] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"102.58732ms","start":"2026-10-09T05:23:21.536142Z","end":"2026-10-09T05:23:21.63873Z","steps":["trace[450784894] 'process raft request' (duration: 102.437437ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:23:53.619298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.476527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:1 size:3944"} {"level":"info","ts":"2026-10-09T05:23:53.619439Z","caller":"traceutil/trace.go:171","msg":"trace[921159600] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:1; response_revision:1017; }","duration":"133.659371ms","start":"2026-10-09T05:23:53.48575Z","end":"2026-10-09T05:23:53.619409Z","steps":["trace[921159600] 'agreement among raft nodes before linearized reading' (duration: 133.313174ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:24:54.732145Z","caller":"traceutil/trace.go:171","msg":"trace[1932726065] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"200.997993ms","start":"2026-10-09T05:24:54.531118Z","end":"2026-10-09T05:24:54.732116Z","steps":["trace[1932726065] 'process raft request' (duration: 200.609497ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:24:55.084121Z","caller":"traceutil/trace.go:171","msg":"trace[1108663258] linearizableReadLoop","detail":"{readStateIndex:1924; appliedIndex:1923; }","duration":"125.372327ms","start":"2026-10-09T05:24:54.958723Z","end":"2026-10-09T05:24:55.084096Z","steps":["trace[1108663258] 'read index received' (duration: 125.111082ms)","trace[1108663258] 'applied index is now lower than readState.Index' (duration: 260.375µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:24:55.084249Z","caller":"traceutil/trace.go:171","msg":"trace[54707909] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"127.788181ms","start":"2026-10-09T05:24:54.956447Z","end":"2026-10-09T05:24:55.084235Z","steps":["trace[54707909] 'process raft request' (duration: 127.448984ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:24:55.084563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.807625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-09T05:24:55.084659Z","caller":"traceutil/trace.go:171","msg":"trace[2035587384] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1849; }","duration":"125.942006ms","start":"2026-10-09T05:24:54.958695Z","end":"2026-10-09T05:24:55.084637Z","steps":["trace[2035587384] 'agreement among raft nodes before linearized reading' (duration: 125.724903ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:25:45.503715Z","caller":"traceutil/trace.go:171","msg":"trace[1634020625] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"116.330904ms","start":"2026-10-09T05:25:45.387342Z","end":"2026-10-09T05:25:45.503673Z","steps":["trace[1634020625] 'process raft request' (duration: 89.608295ms)","trace[1634020625] 'compare' (duration: 26.450214ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:25:45.503896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.054838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-09T05:25:45.503988Z","caller":"traceutil/trace.go:171","msg":"trace[1408017585] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2172; }","duration":"107.18938ms","start":"2026-10-09T05:25:45.396757Z","end":"2026-10-09T05:25:45.503947Z","steps":["trace[1408017585] 'agreement among raft nodes before linearized reading' (duration: 106.990407ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:25:45.503687Z","caller":"traceutil/trace.go:171","msg":"trace[863840571] linearizableReadLoop","detail":"{readStateIndex:2259; appliedIndex:2257; }","duration":"106.835333ms","start":"2026-10-09T05:25:45.396802Z","end":"2026-10-09T05:25:45.503638Z","steps":["trace[863840571] 'read index received' (duration: 45.962024ms)","trace[863840571] 'applied index is now lower than readState.Index' (duration: 60.868699ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:25:45.935856Z","caller":"traceutil/trace.go:171","msg":"trace[483171416] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"197.056829ms","start":"2026-10-09T05:25:45.738527Z","end":"2026-10-09T05:25:45.935584Z","steps":["trace[483171416] 'process raft request' (duration: 196.218474ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:25:45.93618Z","caller":"traceutil/trace.go:171","msg":"trace[479749519] linearizableReadLoop","detail":"{readStateIndex:2262; appliedIndex:2261; }","duration":"177.887805ms","start":"2026-10-09T05:25:45.757581Z","end":"2026-10-09T05:25:45.935469Z","steps":["trace[479749519] 'read index received' (duration: 177.08484ms)","trace[479749519] 'applied index is now lower than readState.Index' (duration: 801.015µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:25:45.936208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.531227ms","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-10-09T05:25:45.936313Z","caller":"traceutil/trace.go:171","msg":"trace[571452232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2175; }","duration":"178.748242ms","start":"2026-10-09T05:25:45.757541Z","end":"2026-10-09T05:25:45.936289Z","steps":["trace[571452232] 'agreement among raft nodes before linearized reading' (duration: 178.027128ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:25:46.055722Z","caller":"traceutil/trace.go:171","msg":"trace[1887672593] linearizableReadLoop","detail":"{readStateIndex:2263; appliedIndex:2262; }","duration":"119.428972ms","start":"2026-10-09T05:25:45.936267Z","end":"2026-10-09T05:25:46.055696Z","steps":["trace[1887672593] 'read index received' (duration: 87.808912ms)","trace[1887672593] 'applied index is now lower than readState.Index' (duration: 31.61913ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:25:46.055831Z","caller":"traceutil/trace.go:171","msg":"trace[2013508064] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"140.485242ms","start":"2026-10-09T05:25:45.915298Z","end":"2026-10-09T05:25:46.055784Z","steps":["trace[2013508064] 'process raft request' (duration: 108.760759ms)","trace[2013508064] 'compare' (duration: 31.4971ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:25:46.056025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.92508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-09T05:25:46.056127Z","caller":"traceutil/trace.go:171","msg":"trace[632394035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2176; }","duration":"152.068463ms","start":"2026-10-09T05:25:45.904036Z","end":"2026-10-09T05:25:46.056105Z","steps":["trace[632394035] 'agreement among raft nodes before linearized reading' (duration: 151.772208ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:25:46.352187Z","caller":"traceutil/trace.go:171","msg":"trace[2124859125] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"291.750061ms","start":"2026-10-09T05:25:46.06041Z","end":"2026-10-09T05:25:46.35216Z","steps":["trace[2124859125] 'process raft request' (duration: 291.688ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:25:46.352581Z","caller":"traceutil/trace.go:171","msg":"trace[120258964] linearizableReadLoop","detail":"{readStateIndex:2264; appliedIndex:2263; }","duration":"296.753797ms","start":"2026-10-09T05:25:46.055786Z","end":"2026-10-09T05:25:46.35254Z","steps":["trace[120258964] 'read index received' (duration: 273.887873ms)","trace[120258964] 'applied index is now lower than readState.Index' (duration: 22.838583ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:25:46.35266Z","caller":"traceutil/trace.go:171","msg":"trace[1519849999] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"409.41919ms","start":"2026-10-09T05:25:45.943191Z","end":"2026-10-09T05:25:46.35261Z","steps":["trace[1519849999] 'process raft request' (duration: 386.451954ms)","trace[1519849999] 'compare' (duration: 22.285064ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:25:46.35285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:25:45.943166Z","time spent":"409.596243ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T05:25:46.352882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.076276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-10-09T05:25:46.352885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.144807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-10-09T05:25:46.352927Z","caller":"traceutil/trace.go:171","msg":"trace[1504292728] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2178; }","duration":"129.167158ms","start":"2026-10-09T05:25:46.223745Z","end":"2026-10-09T05:25:46.352912Z","steps":["trace[1504292728] 'agreement among raft nodes before linearized reading' (duration: 128.969214ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:25:46.353005Z","caller":"traceutil/trace.go:171","msg":"trace[388297140] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2178; }","duration":"315.279009ms","start":"2026-10-09T05:25:46.037671Z","end":"2026-10-09T05:25:46.35295Z","steps":["trace[388297140] 'agreement among raft nodes before linearized reading' (duration: 314.965293ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:25:46.353069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:25:46.037648Z","time spent":"315.406112ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-09T05:26:27.372584Z","caller":"traceutil/trace.go:171","msg":"trace[465999744] transaction","detail":"{read_only:false; response_revision:2500; number_of_response:1; }","duration":"106.471176ms","start":"2026-10-09T05:26:27.266073Z","end":"2026-10-09T05:26:27.372544Z","steps":["trace[465999744] 'process raft request' (duration: 99.781518ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:27:12.096031Z","caller":"traceutil/trace.go:171","msg":"trace[1774014094] linearizableReadLoop","detail":"{readStateIndex:2944; appliedIndex:2943; }","duration":"145.131945ms","start":"2026-10-09T05:27:11.950872Z","end":"2026-10-09T05:27:12.096004Z","steps":["trace[1774014094] 'read index received' (duration: 53.499052ms)","trace[1774014094] 'applied index is now lower than readState.Index' (duration: 91.592612ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:27:12.096143Z","caller":"traceutil/trace.go:171","msg":"trace[1884427930] transaction","detail":"{read_only:false; response_revision:2833; number_of_response:1; }","duration":"145.791417ms","start":"2026-10-09T05:27:11.950296Z","end":"2026-10-09T05:27:12.096087Z","steps":["trace[1884427930] 'process raft request' (duration: 54.048672ms)","trace[1884427930] 'compare' (duration: 91.438229ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:27:12.096431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.535592ms","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-10-09T05:27:12.096559Z","caller":"traceutil/trace.go:171","msg":"trace[2128767458] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2833; }","duration":"145.698925ms","start":"2026-10-09T05:27:11.950839Z","end":"2026-10-09T05:27:12.096538Z","steps":["trace[2128767458] 'agreement among raft nodes before linearized reading' (duration: 145.335668ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:27:12.356354Z","caller":"traceutil/trace.go:171","msg":"trace[2117654015] transaction","detail":"{read_only:false; response_revision:2834; number_of_response:1; }","duration":"255.170848ms","start":"2026-10-09T05:27:12.101152Z","end":"2026-10-09T05:27:12.356323Z","steps":["trace[2117654015] 'process raft request' (duration: 222.838539ms)","trace[2117654015] 'compare' (duration: 32.064194ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:27:12.356437Z","caller":"traceutil/trace.go:171","msg":"trace[785711337] linearizableReadLoop","detail":"{readStateIndex:2945; appliedIndex:2944; }","duration":"159.450288ms","start":"2026-10-09T05:27:12.196965Z","end":"2026-10-09T05:27:12.356415Z","steps":["trace[785711337] 'read index received' (duration: 127.040397ms)","trace[785711337] 'applied index is now lower than readState.Index' (duration: 32.408411ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:27:12.35665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.668242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-10-09T05:27:12.356684Z","caller":"traceutil/trace.go:171","msg":"trace[343756406] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2835; }","duration":"159.744584ms","start":"2026-10-09T05:27:12.196929Z","end":"2026-10-09T05:27:12.356674Z","steps":["trace[343756406] 'agreement among raft nodes before linearized reading' (duration: 159.56995ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:27:12.946378Z","caller":"traceutil/trace.go:171","msg":"trace[1564529149] transaction","detail":"{read_only:false; response_revision:2838; number_of_response:1; }","duration":"107.421584ms","start":"2026-10-09T05:27:12.838921Z","end":"2026-10-09T05:27:12.946343Z","steps":["trace[1564529149] 'process raft request' (duration: 97.005235ms)","trace[1564529149] 'compare' (duration: 10.254546ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:30:25.869993Z","caller":"traceutil/trace.go:171","msg":"trace[917894051] transaction","detail":"{read_only:false; response_revision:4054; number_of_response:1; }","duration":"132.693903ms","start":"2026-10-09T05:30:25.73727Z","end":"2026-10-09T05:30:25.869964Z","steps":["trace[917894051] 'process raft request' (duration: 132.50029ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:31:53.557798Z","caller":"traceutil/trace.go:171","msg":"trace[1760593722] transaction","detail":"{read_only:false; response_revision:5106; number_of_response:1; }","duration":"138.399219ms","start":"2026-10-09T05:31:53.419381Z","end":"2026-10-09T05:31:53.55778Z","steps":["trace[1760593722] 'process raft request' (duration: 65.968704ms)","trace[1760593722] 'compare' (duration: 72.360674ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:31:53.557884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.742753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.20\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-10-09T05:31:53.55799Z","caller":"traceutil/trace.go:171","msg":"trace[1753351394] range","detail":"{range_begin:/registry/masterleases/199.204.45.20; range_end:; response_count:1; response_revision:5105; }","duration":"189.907667ms","start":"2026-10-09T05:31:53.368058Z","end":"2026-10-09T05:31:53.557966Z","steps":["trace[1753351394] 'range keys from in-memory index tree' (duration: 189.546959ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:31:55.793933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.059546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lpcgl\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-09T05:31:55.794013Z","caller":"traceutil/trace.go:171","msg":"trace[600920897] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lpcgl; range_end:; response_count:1; response_revision:5232; }","duration":"164.181917ms","start":"2026-10-09T05:31:55.629811Z","end":"2026-10-09T05:31:55.793992Z","steps":["trace[600920897] 'range keys from in-memory index tree' (duration: 163.890532ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:31:55.794276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.53313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-09T05:31:55.794309Z","caller":"traceutil/trace.go:171","msg":"trace[264328883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5232; }","duration":"159.601871ms","start":"2026-10-09T05:31:55.634698Z","end":"2026-10-09T05:31:55.7943Z","steps":["trace[264328883] 'range keys from in-memory index tree' (duration: 159.406718ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:31:55.794489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.764129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:31:55.794515Z","caller":"traceutil/trace.go:171","msg":"trace[411553832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5232; }","duration":"125.817859ms","start":"2026-10-09T05:31:55.668689Z","end":"2026-10-09T05:31:55.794507Z","steps":["trace[411553832] 'range keys from in-memory index tree' (duration: 125.719738ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:31:55.926553Z","caller":"traceutil/trace.go:171","msg":"trace[441707765] transaction","detail":"{read_only:false; response_revision:5234; number_of_response:1; }","duration":"126.638135ms","start":"2026-10-09T05:31:55.799889Z","end":"2026-10-09T05:31:55.926527Z","steps":["trace[441707765] 'process raft request' (duration: 126.471372ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:31:59.692733Z","caller":"traceutil/trace.go:171","msg":"trace[402675255] linearizableReadLoop","detail":"{readStateIndex:5526; appliedIndex:5525; }","duration":"101.970187ms","start":"2026-10-09T05:31:59.590732Z","end":"2026-10-09T05:31:59.692703Z","steps":["trace[402675255] 'read index received' (duration: 62.33941ms)","trace[402675255] 'applied index is now lower than readState.Index' (duration: 39.629617ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:31:59.692892Z","caller":"traceutil/trace.go:171","msg":"trace[1209656270] transaction","detail":"{read_only:false; response_revision:5316; number_of_response:1; }","duration":"117.595386ms","start":"2026-10-09T05:31:59.575274Z","end":"2026-10-09T05:31:59.69287Z","steps":["trace[1209656270] 'process raft request' (duration: 77.825196ms)","trace[1209656270] 'compare' (duration: 39.443404ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:31:59.693076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.330292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/loki\" ","response":"range_response_count:1 size:800"} {"level":"info","ts":"2026-10-09T05:31:59.693142Z","caller":"traceutil/trace.go:171","msg":"trace[1755828839] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/loki; range_end:; response_count:1; response_revision:5316; }","duration":"102.449215ms","start":"2026-10-09T05:31:59.590676Z","end":"2026-10-09T05:31:59.693125Z","steps":["trace[1755828839] 'agreement among raft nodes before linearized reading' (duration: 102.245272ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:32:10.765244Z","caller":"traceutil/trace.go:171","msg":"trace[1390736091] linearizableReadLoop","detail":"{readStateIndex:5726; appliedIndex:5725; }","duration":"151.279623ms","start":"2026-10-09T05:32:10.613908Z","end":"2026-10-09T05:32:10.765187Z","steps":["trace[1390736091] 'read index received' (duration: 70.252805ms)","trace[1390736091] 'applied index is now lower than readState.Index' (duration: 81.025807ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:32:10.765308Z","caller":"traceutil/trace.go:171","msg":"trace[493206331] transaction","detail":"{read_only:false; response_revision:5511; number_of_response:1; }","duration":"159.288821ms","start":"2026-10-09T05:32:10.605978Z","end":"2026-10-09T05:32:10.765267Z","steps":["trace[493206331] 'process raft request' (duration: 78.264145ms)","trace[493206331] 'compare' (duration: 80.820723ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:32:10.765495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.564567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-09T05:32:10.765538Z","caller":"traceutil/trace.go:171","msg":"trace[156759355] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5511; }","duration":"151.637838ms","start":"2026-10-09T05:32:10.613887Z","end":"2026-10-09T05:32:10.765525Z","steps":["trace[156759355] 'agreement among raft nodes before linearized reading' (duration: 151.465835ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:32:11.264339Z","caller":"traceutil/trace.go:171","msg":"trace[1135060227] transaction","detail":"{read_only:false; response_revision:5515; number_of_response:1; }","duration":"175.034033ms","start":"2026-10-09T05:32:11.089277Z","end":"2026-10-09T05:32:11.264311Z","steps":["trace[1135060227] 'process raft request' (duration: 173.993045ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:32:18.066283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.500529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T05:32:18.066374Z","caller":"traceutil/trace.go:171","msg":"trace[1626370201] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5679; }","duration":"106.641122ms","start":"2026-10-09T05:32:17.959709Z","end":"2026-10-09T05:32:18.06635Z","steps":["trace[1626370201] 'count revisions from in-memory index tree' (duration: 106.422688ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:32:18.252978Z","caller":"traceutil/trace.go:171","msg":"trace[877716463] linearizableReadLoop","detail":"{readStateIndex:5899; appliedIndex:5897; }","duration":"181.861111ms","start":"2026-10-09T05:32:18.071092Z","end":"2026-10-09T05:32:18.252953Z","steps":["trace[877716463] 'read index received' (duration: 35.089222ms)","trace[877716463] 'applied index is now lower than readState.Index' (duration: 146.770839ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:32:18.253138Z","caller":"traceutil/trace.go:171","msg":"trace[378317224] transaction","detail":"{read_only:false; response_revision:5681; number_of_response:1; }","duration":"182.561044ms","start":"2026-10-09T05:32:18.070564Z","end":"2026-10-09T05:32:18.253125Z","steps":["trace[378317224] 'process raft request' (duration: 168.104215ms)","trace[378317224] 'compare' (duration: 14.144133ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:32:18.253189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.49851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:32:18.253267Z","caller":"traceutil/trace.go:171","msg":"trace[1937494291] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:5681; }","duration":"177.626073ms","start":"2026-10-09T05:32:18.075627Z","end":"2026-10-09T05:32:18.253253Z","steps":["trace[1937494291] 'agreement among raft nodes before linearized reading' (duration: 177.514381ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:32:18.253409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.297919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80120"} {"level":"info","ts":"2026-10-09T05:32:18.253464Z","caller":"traceutil/trace.go:171","msg":"trace[998898740] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5681; }","duration":"182.365601ms","start":"2026-10-09T05:32:18.071086Z","end":"2026-10-09T05:32:18.253452Z","steps":["trace[998898740] 'agreement among raft nodes before linearized reading' (duration: 182.071095ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:32:29.493985Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2929} {"level":"info","ts":"2026-10-09T05:32:29.569564Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2929,"took":"73.297753ms","hash":3629101363,"current-db-size-bytes":55648256,"current-db-size":"56 MB","current-db-size-in-use-bytes":32161792,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T05:32:29.569658Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3629101363,"revision":2929,"compact-revision":-1} {"level":"info","ts":"2026-10-09T05:37:29.504369Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5826} {"level":"info","ts":"2026-10-09T05:37:29.597613Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5826,"took":"91.013724ms","hash":1613143638,"current-db-size-bytes":55648256,"current-db-size":"56 MB","current-db-size-in-use-bytes":26423296,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-09T05:37:29.597669Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1613143638,"revision":5826,"compact-revision":2929} {"level":"warn","ts":"2026-10-09T05:38:31.331014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.166088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T05:38:31.331323Z","caller":"traceutil/trace.go:171","msg":"trace[539065401] transaction","detail":"{read_only:false; response_revision:8540; number_of_response:1; }","duration":"422.671563ms","start":"2026-10-09T05:38:30.90862Z","end":"2026-10-09T05:38:31.331291Z","steps":["trace[539065401] 'process raft request' (duration: 229.108367ms)","trace[539065401] 'compare' (duration: 193.018495ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:38:31.331427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:38:30.908602Z","time spent":"422.771684ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T05:38:31.331345Z","caller":"traceutil/trace.go:171","msg":"trace[833829004] transaction","detail":"{read_only:false; response_revision:8541; number_of_response:1; }","duration":"217.944238ms","start":"2026-10-09T05:38:31.113311Z","end":"2026-10-09T05:38:31.331255Z","steps":["trace[833829004] 'process raft request' (duration: 217.818986ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:38:43.600131Z","caller":"traceutil/trace.go:171","msg":"trace[649839657] linearizableReadLoop","detail":"{readStateIndex:8914; appliedIndex:8913; }","duration":"218.849404ms","start":"2026-10-09T05:38:43.381254Z","end":"2026-10-09T05:38:43.600103Z","steps":["trace[649839657] 'read index received' (duration: 218.637269ms)","trace[649839657] 'applied index is now lower than readState.Index' (duration: 210.955µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:38:43.600193Z","caller":"traceutil/trace.go:171","msg":"trace[1669645421] transaction","detail":"{read_only:false; response_revision:8603; number_of_response:1; }","duration":"228.739941ms","start":"2026-10-09T05:38:43.371426Z","end":"2026-10-09T05:38:43.600166Z","steps":["trace[1669645421] 'process raft request' (duration: 228.427495ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:38:43.600408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.027101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.20\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-10-09T05:38:43.600446Z","caller":"traceutil/trace.go:171","msg":"trace[1903070412] range","detail":"{range_begin:/registry/masterleases/199.204.45.20; range_end:; response_count:1; response_revision:8603; }","duration":"215.098682ms","start":"2026-10-09T05:38:43.385336Z","end":"2026-10-09T05:38:43.600435Z","steps":["trace[1903070412] 'agreement among raft nodes before linearized reading' (duration: 214.972119ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:38:43.600639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.651371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:38:43.600665Z","caller":"traceutil/trace.go:171","msg":"trace[1085476635] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:8603; }","duration":"180.726242ms","start":"2026-10-09T05:38:43.419932Z","end":"2026-10-09T05:38:43.600658Z","steps":["trace[1085476635] 'agreement among raft nodes before linearized reading' (duration: 180.655371ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:38:43.601431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.182349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-09T05:38:43.601479Z","caller":"traceutil/trace.go:171","msg":"trace[1951945666] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8603; }","duration":"220.275191ms","start":"2026-10-09T05:38:43.381189Z","end":"2026-10-09T05:38:43.601465Z","steps":["trace[1951945666] 'agreement among raft nodes before linearized reading' (duration: 219.052437ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:38:43.784979Z","caller":"traceutil/trace.go:171","msg":"trace[40357544] linearizableReadLoop","detail":"{readStateIndex:8917; appliedIndex:8916; }","duration":"114.064729ms","start":"2026-10-09T05:38:43.670886Z","end":"2026-10-09T05:38:43.784951Z","steps":["trace[40357544] 'read index received' (duration: 91.304439ms)","trace[40357544] 'applied index is now lower than readState.Index' (duration: 22.75902ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:38:43.785093Z","caller":"traceutil/trace.go:171","msg":"trace[1989424430] transaction","detail":"{read_only:false; response_revision:8605; number_of_response:1; }","duration":"152.928375ms","start":"2026-10-09T05:38:43.632125Z","end":"2026-10-09T05:38:43.785053Z","steps":["trace[1989424430] 'process raft request' (duration: 130.148764ms)","trace[1989424430] 'compare' (duration: 22.527106ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:38:43.785276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.333514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:38:43.785338Z","caller":"traceutil/trace.go:171","msg":"trace[478136286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8605; }","duration":"114.481597ms","start":"2026-10-09T05:38:43.670841Z","end":"2026-10-09T05:38:43.785323Z","steps":["trace[478136286] 'agreement among raft nodes before linearized reading' (duration: 114.308204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:38:43.785526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.583367ms","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-10-09T05:38:43.78561Z","caller":"traceutil/trace.go:171","msg":"trace[1519310071] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8605; }","duration":"107.7016ms","start":"2026-10-09T05:38:43.677886Z","end":"2026-10-09T05:38:43.785588Z","steps":["trace[1519310071] 'agreement among raft nodes before linearized reading' (duration: 107.439335ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:38:44.412535Z","caller":"traceutil/trace.go:171","msg":"trace[1869053046] transaction","detail":"{read_only:false; response_revision:8608; number_of_response:1; }","duration":"121.257286ms","start":"2026-10-09T05:38:44.291251Z","end":"2026-10-09T05:38:44.412508Z","steps":["trace[1869053046] 'process raft request' (duration: 121.089243ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:39:13.329583Z","caller":"traceutil/trace.go:171","msg":"trace[1317933121] transaction","detail":"{read_only:false; response_revision:8818; number_of_response:1; }","duration":"178.996319ms","start":"2026-10-09T05:39:13.150562Z","end":"2026-10-09T05:39:13.329558Z","steps":["trace[1317933121] 'process raft request' (duration: 178.835716ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:39:13.50666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.914661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.20\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-10-09T05:39:13.506741Z","caller":"traceutil/trace.go:171","msg":"trace[1414856785] range","detail":"{range_begin:/registry/masterleases/199.204.45.20; range_end:; response_count:1; response_revision:8818; }","duration":"119.046584ms","start":"2026-10-09T05:39:13.387676Z","end":"2026-10-09T05:39:13.506722Z","steps":["trace[1414856785] 'range keys from in-memory index tree' (duration: 118.722237ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:39:27.006637Z","caller":"traceutil/trace.go:171","msg":"trace[1390648967] transaction","detail":"{read_only:false; response_revision:8903; number_of_response:1; }","duration":"109.239968ms","start":"2026-10-09T05:39:26.897371Z","end":"2026-10-09T05:39:27.00661Z","steps":["trace[1390648967] 'process raft request' (duration: 109.087256ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:39:39.223303Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000248d.wal"} {"level":"info","ts":"2026-10-09T05:40:39.119511Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e2d037ebe7b91690","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T05:40:39.126859Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-09T05:40:39.126987Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-09T05:42:29.512882Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7983} {"level":"info","ts":"2026-10-09T05:42:29.58759Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7983,"took":"69.059694ms","hash":2987114351,"current-db-size-bytes":55648256,"current-db-size":"56 MB","current-db-size-in-use-bytes":28180480,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-09T05:42:29.587653Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2987114351,"revision":7983,"compact-revision":5826} {"level":"info","ts":"2026-10-09T05:44:15.654017Z","caller":"traceutil/trace.go:171","msg":"trace[1128590130] transaction","detail":"{read_only:false; response_revision:11465; number_of_response:1; }","duration":"130.911786ms","start":"2026-10-09T05:44:15.523075Z","end":"2026-10-09T05:44:15.653987Z","steps":["trace[1128590130] 'process raft request' (duration: 130.695862ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:44:36.173379Z","caller":"traceutil/trace.go:171","msg":"trace[660227530] transaction","detail":"{read_only:false; response_revision:11579; number_of_response:1; }","duration":"128.588201ms","start":"2026-10-09T05:44:36.04471Z","end":"2026-10-09T05:44:36.173299Z","steps":["trace[660227530] 'process raft request' (duration: 34.281818ms)","trace[660227530] 'compare' (duration: 93.719521ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:44:36.398545Z","caller":"traceutil/trace.go:171","msg":"trace[1860169592] linearizableReadLoop","detail":"{readStateIndex:11982; appliedIndex:11981; }","duration":"129.342985ms","start":"2026-10-09T05:44:36.269154Z","end":"2026-10-09T05:44:36.398497Z","steps":["trace[1860169592] 'read index received' (duration: 59.813231ms)","trace[1860169592] 'applied index is now lower than readState.Index' (duration: 69.527324ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:44:36.398679Z","caller":"traceutil/trace.go:171","msg":"trace[635582583] transaction","detail":"{read_only:false; response_revision:11580; number_of_response:1; }","duration":"213.853624ms","start":"2026-10-09T05:44:36.184754Z","end":"2026-10-09T05:44:36.398608Z","steps":["trace[635582583] 'process raft request' (duration: 144.216387ms)","trace[635582583] 'compare' (duration: 69.31426ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:44:36.398895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.703363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-09T05:44:36.399014Z","caller":"traceutil/trace.go:171","msg":"trace[566016291] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11580; }","duration":"129.903277ms","start":"2026-10-09T05:44:36.269092Z","end":"2026-10-09T05:44:36.398995Z","steps":["trace[566016291] 'agreement among raft nodes before linearized reading' (duration: 129.577541ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:47:29.522421Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10409} {"level":"info","ts":"2026-10-09T05:47:29.608197Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10409,"took":"83.639441ms","hash":2542867472,"current-db-size-bytes":55648256,"current-db-size":"56 MB","current-db-size-in-use-bytes":27717632,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-09T05:47:29.608281Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2542867472,"revision":10409,"compact-revision":7983} {"level":"info","ts":"2026-10-09T05:50:45.900311Z","caller":"traceutil/trace.go:171","msg":"trace[1955752929] transaction","detail":"{read_only:false; response_revision:14074; number_of_response:1; }","duration":"184.29856ms","start":"2026-10-09T05:50:45.715989Z","end":"2026-10-09T05:50:45.900287Z","steps":["trace[1955752929] 'process raft request' (duration: 184.136316ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:50:45.90322Z","caller":"traceutil/trace.go:171","msg":"trace[600165952] transaction","detail":"{read_only:false; response_revision:14075; number_of_response:1; }","duration":"117.054884ms","start":"2026-10-09T05:50:45.786148Z","end":"2026-10-09T05:50:45.903203Z","steps":["trace[600165952] 'process raft request' (duration: 116.623045ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:51:02.509905Z","caller":"traceutil/trace.go:171","msg":"trace[2094664893] transaction","detail":"{read_only:false; response_revision:14175; number_of_response:1; }","duration":"151.232767ms","start":"2026-10-09T05:51:02.358641Z","end":"2026-10-09T05:51:02.509874Z","steps":["trace[2094664893] 'process raft request' (duration: 151.036494ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:51:02.509831Z","caller":"traceutil/trace.go:171","msg":"trace[583670046] linearizableReadLoop","detail":"{readStateIndex:14669; appliedIndex:14668; }","duration":"132.79264ms","start":"2026-10-09T05:51:02.377018Z","end":"2026-10-09T05:51:02.50981Z","steps":["trace[583670046] 'read index received' (duration: 132.636487ms)","trace[583670046] 'applied index is now lower than readState.Index' (duration: 155.333µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:51:02.510189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.157147ms","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-10-09T05:51:02.510249Z","caller":"traceutil/trace.go:171","msg":"trace[1819470230] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14175; }","duration":"133.257549ms","start":"2026-10-09T05:51:02.376976Z","end":"2026-10-09T05:51:02.510233Z","steps":["trace[1819470230] 'agreement among raft nodes before linearized reading' (duration: 133.085556ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:51:02.51483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.439991ms","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:5"} {"level":"info","ts":"2026-10-09T05:51:02.514945Z","caller":"traceutil/trace.go:171","msg":"trace[216108178] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:14176; }","duration":"134.590693ms","start":"2026-10-09T05:51:02.380335Z","end":"2026-10-09T05:51:02.514925Z","steps":["trace[216108178] 'agreement among raft nodes before linearized reading' (duration: 134.40161ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:51:02.515438Z","caller":"traceutil/trace.go:171","msg":"trace[1542720782] transaction","detail":"{read_only:false; response_revision:14176; number_of_response:1; }","duration":"128.61262ms","start":"2026-10-09T05:51:02.386791Z","end":"2026-10-09T05:51:02.515404Z","steps":["trace[1542720782] 'process raft request' (duration: 127.808905ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:51:12.908088Z","caller":"traceutil/trace.go:171","msg":"trace[1029527964] transaction","detail":"{read_only:false; response_revision:14345; number_of_response:1; }","duration":"364.41365ms","start":"2026-10-09T05:51:12.543647Z","end":"2026-10-09T05:51:12.90806Z","steps":["trace[1029527964] 'process raft request' (duration: 364.217526ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:51:12.908251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:51:12.543602Z","time spent":"364.565543ms","remote":"127.0.0.1:52882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3711,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T05:51:12.932304Z","caller":"traceutil/trace.go:171","msg":"trace[293164597] linearizableReadLoop","detail":"{readStateIndex:14843; appliedIndex:14841; }","duration":"368.615908ms","start":"2026-10-09T05:51:12.563662Z","end":"2026-10-09T05:51:12.932278Z","steps":["trace[293164597] 'read index received' (duration: 344.200389ms)","trace[293164597] 'applied index is now lower than readState.Index' (duration: 24.414479ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:51:12.932384Z","caller":"traceutil/trace.go:171","msg":"trace[1674355260] transaction","detail":"{read_only:false; response_revision:14346; number_of_response:1; }","duration":"387.280841ms","start":"2026-10-09T05:51:12.545065Z","end":"2026-10-09T05:51:12.932345Z","steps":["trace[1674355260] 'process raft request' (duration: 387.075197ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:51:12.932548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.874394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-09T05:51:12.932608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.504963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:51:12.932639Z","caller":"traceutil/trace.go:171","msg":"trace[1880959757] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14346; }","duration":"369.009436ms","start":"2026-10-09T05:51:12.563607Z","end":"2026-10-09T05:51:12.932616Z","steps":["trace[1880959757] 'agreement among raft nodes before linearized reading' (duration: 368.779492ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:51:12.932651Z","caller":"traceutil/trace.go:171","msg":"trace[1265838319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14346; }","duration":"261.587584ms","start":"2026-10-09T05:51:12.671049Z","end":"2026-10-09T05:51:12.932637Z","steps":["trace[1265838319] 'agreement among raft nodes before linearized reading' (duration: 261.520143ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:51:12.932685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.138134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lpcgl\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-10-09T05:51:12.932696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:51:12.563588Z","time spent":"369.096148ms","remote":"127.0.0.1:52964","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-10-09T05:51:12.932728Z","caller":"traceutil/trace.go:171","msg":"trace[1387156044] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lpcgl; range_end:; response_count:1; response_revision:14346; }","duration":"323.260326ms","start":"2026-10-09T05:51:12.609459Z","end":"2026-10-09T05:51:12.93272Z","steps":["trace[1387156044] 'agreement among raft nodes before linearized reading' (duration: 323.084543ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:51:12.932759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:51:12.609428Z","time spent":"323.324897ms","remote":"127.0.0.1:52882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-lpcgl\" "} {"level":"warn","ts":"2026-10-09T05:51:12.932755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.353452ms","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-10-09T05:51:12.932656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:51:12.545048Z","time spent":"387.471474ms","remote":"127.0.0.1:52964","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-10-09T05:51:12.932838Z","caller":"traceutil/trace.go:171","msg":"trace[1184113655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14346; }","duration":"285.497685ms","start":"2026-10-09T05:51:12.64732Z","end":"2026-10-09T05:51:12.932818Z","steps":["trace[1184113655] 'agreement among raft nodes before linearized reading' (duration: 285.26154ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:51:12.932969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.275993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-09T05:51:12.933053Z","caller":"traceutil/trace.go:171","msg":"trace[1234393084] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14346; }","duration":"291.409426ms","start":"2026-10-09T05:51:12.641625Z","end":"2026-10-09T05:51:12.933034Z","steps":["trace[1234393084] 'agreement among raft nodes before linearized reading' (duration: 291.072509ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:52:29.529818Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12732} {"level":"info","ts":"2026-10-09T05:52:29.622051Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12732,"took":"88.436949ms","hash":893428833,"current-db-size-bytes":55648256,"current-db-size":"56 MB","current-db-size-in-use-bytes":31031296,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-09T05:52:29.62212Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":893428833,"revision":12732,"compact-revision":10409} {"level":"info","ts":"2026-10-09T05:52:43.904679Z","caller":"traceutil/trace.go:171","msg":"trace[657695963] linearizableReadLoop","detail":"{readStateIndex:15922; appliedIndex:15921; }","duration":"104.581165ms","start":"2026-10-09T05:52:43.800067Z","end":"2026-10-09T05:52:43.904648Z","steps":["trace[657695963] 'read index received' (duration: 104.364271ms)","trace[657695963] 'applied index is now lower than readState.Index' (duration: 215.384µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:52:43.90483Z","caller":"traceutil/trace.go:171","msg":"trace[2114670928] transaction","detail":"{read_only:false; response_revision:15391; number_of_response:1; }","duration":"136.570929ms","start":"2026-10-09T05:52:43.768229Z","end":"2026-10-09T05:52:43.9048Z","steps":["trace[2114670928] 'process raft request' (duration: 136.202642ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:52:43.905174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.049233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-10-09T05:52:43.905263Z","caller":"traceutil/trace.go:171","msg":"trace[686345021] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:15391; }","duration":"105.254136ms","start":"2026-10-09T05:52:43.799988Z","end":"2026-10-09T05:52:43.905242Z","steps":["trace[686345021] 'agreement among raft nodes before linearized reading' (duration: 104.778748ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:57:29.537985Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15317} {"level":"info","ts":"2026-10-09T05:57:29.644023Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15317,"took":"103.047038ms","hash":2882792392,"current-db-size-bytes":55648256,"current-db-size":"56 MB","current-db-size-in-use-bytes":32256000,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T05:57:29.6441Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2882792392,"revision":15317,"compact-revision":12732} {"level":"info","ts":"2026-10-09T05:58:27.612604Z","caller":"traceutil/trace.go:171","msg":"trace[1425950074] linearizableReadLoop","detail":"{readStateIndex:18574; appliedIndex:18573; }","duration":"119.493948ms","start":"2026-10-09T05:58:27.493087Z","end":"2026-10-09T05:58:27.612581Z","steps":["trace[1425950074] 'read index received' (duration: 119.257634ms)","trace[1425950074] 'applied index is now lower than readState.Index' (duration: 235.714µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:58:27.612905Z","caller":"traceutil/trace.go:171","msg":"trace[1092462258] transaction","detail":"{read_only:false; response_revision:17956; number_of_response:1; }","duration":"123.613686ms","start":"2026-10-09T05:58:27.489281Z","end":"2026-10-09T05:58:27.612895Z","steps":["trace[1092462258] 'process raft request' (duration: 123.170428ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:58:27.613092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.987197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1438"} {"level":"info","ts":"2026-10-09T05:58:27.613171Z","caller":"traceutil/trace.go:171","msg":"trace[1279560221] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:17956; }","duration":"120.10226ms","start":"2026-10-09T05:58:27.493049Z","end":"2026-10-09T05:58:27.613151Z","steps":["trace[1279560221] 'agreement among raft nodes before linearized reading' (duration: 119.904416ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:58:27.61317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.382666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1818"} {"level":"info","ts":"2026-10-09T05:58:27.613264Z","caller":"traceutil/trace.go:171","msg":"trace[45165570] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:17956; }","duration":"111.515598ms","start":"2026-10-09T05:58:27.501727Z","end":"2026-10-09T05:58:27.613242Z","steps":["trace[45165570] 'agreement among raft nodes before linearized reading' (duration: 111.231292ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:58:34.709614Z","caller":"traceutil/trace.go:171","msg":"trace[1852823794] transaction","detail":"{read_only:false; response_revision:18045; number_of_response:1; }","duration":"102.554608ms","start":"2026-10-09T05:58:34.60703Z","end":"2026-10-09T05:58:34.709584Z","steps":["trace[1852823794] 'process raft request' (duration: 102.363265ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:58:35.017612Z","caller":"traceutil/trace.go:171","msg":"trace[1273131873] linearizableReadLoop","detail":"{readStateIndex:18668; appliedIndex:18667; }","duration":"265.700152ms","start":"2026-10-09T05:58:34.751889Z","end":"2026-10-09T05:58:35.017589Z","steps":["trace[1273131873] 'read index received' (duration: 211.470908ms)","trace[1273131873] 'applied index is now lower than readState.Index' (duration: 54.228294ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:58:35.018003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.09061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-10-09T05:58:35.018084Z","caller":"traceutil/trace.go:171","msg":"trace[970930800] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:18047; }","duration":"266.229433ms","start":"2026-10-09T05:58:34.751835Z","end":"2026-10-09T05:58:35.018065Z","steps":["trace[970930800] 'agreement among raft nodes before linearized reading' (duration: 265.969277ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:58:35.018289Z","caller":"traceutil/trace.go:171","msg":"trace[362358567] transaction","detail":"{read_only:false; response_revision:18047; number_of_response:1; }","duration":"281.824637ms","start":"2026-10-09T05:58:34.736449Z","end":"2026-10-09T05:58:35.018273Z","steps":["trace[362358567] 'process raft request' (duration: 226.854318ms)","trace[362358567] 'compare' (duration: 54.02117ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:58:35.200456Z","caller":"traceutil/trace.go:171","msg":"trace[805326752] transaction","detail":"{read_only:false; response_revision:18048; number_of_response:1; }","duration":"170.144816ms","start":"2026-10-09T05:58:35.030286Z","end":"2026-10-09T05:58:35.200431Z","steps":["trace[805326752] 'process raft request' (duration: 169.914051ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:58:35.214105Z","caller":"traceutil/trace.go:171","msg":"trace[609156631] transaction","detail":"{read_only:false; response_revision:18049; number_of_response:1; }","duration":"182.062892ms","start":"2026-10-09T05:58:35.032012Z","end":"2026-10-09T05:58:35.214075Z","steps":["trace[609156631] 'process raft request' (duration: 181.837337ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:58:35.214434Z","caller":"traceutil/trace.go:171","msg":"trace[1899627172] transaction","detail":"{read_only:false; response_revision:18050; number_of_response:1; }","duration":"182.203024ms","start":"2026-10-09T05:58:35.032201Z","end":"2026-10-09T05:58:35.214405Z","steps":["trace[1899627172] 'process raft request' (duration: 181.799596ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:58:35.214471Z","caller":"traceutil/trace.go:171","msg":"trace[1016597521] transaction","detail":"{read_only:false; response_revision:18052; number_of_response:1; }","duration":"136.079852ms","start":"2026-10-09T05:58:35.078366Z","end":"2026-10-09T05:58:35.214446Z","steps":["trace[1016597521] 'process raft request' (duration: 136.020861ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:58:35.214523Z","caller":"traceutil/trace.go:171","msg":"trace[1758069823] transaction","detail":"{read_only:false; response_revision:18051; number_of_response:1; }","duration":"182.158483ms","start":"2026-10-09T05:58:35.032317Z","end":"2026-10-09T05:58:35.214476Z","steps":["trace[1758069823] 'process raft request' (duration: 181.952379ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:00:08.582828Z","caller":"traceutil/trace.go:171","msg":"trace[611790698] linearizableReadLoop","detail":"{readStateIndex:19652; appliedIndex:19651; }","duration":"102.273384ms","start":"2026-10-09T06:00:08.480526Z","end":"2026-10-09T06:00:08.582799Z","steps":["trace[611790698] 'read index received' (duration: 102.114841ms)","trace[611790698] 'applied index is now lower than readState.Index' (duration: 157.423µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:00:08.582928Z","caller":"traceutil/trace.go:171","msg":"trace[1128297417] transaction","detail":"{read_only:false; response_revision:19005; number_of_response:1; }","duration":"141.375482ms","start":"2026-10-09T06:00:08.441515Z","end":"2026-10-09T06:00:08.58289Z","steps":["trace[1128297417] 'process raft request' (duration: 141.139138ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:00:08.583052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.502648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-8tj68\" ","response":"range_response_count:1 size:9796"} {"level":"info","ts":"2026-10-09T06:00:08.583099Z","caller":"traceutil/trace.go:171","msg":"trace[1766046251] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-8tj68; range_end:; response_count:1; response_revision:19005; }","duration":"102.579609ms","start":"2026-10-09T06:00:08.480494Z","end":"2026-10-09T06:00:08.583074Z","steps":["trace[1766046251] 'agreement among raft nodes before linearized reading' (duration: 102.424546ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:00:09.257669Z","caller":"traceutil/trace.go:171","msg":"trace[1823143671] transaction","detail":"{read_only:false; response_revision:19006; number_of_response:1; }","duration":"130.513317ms","start":"2026-10-09T06:00:09.127127Z","end":"2026-10-09T06:00:09.25764Z","steps":["trace[1823143671] 'process raft request' (duration: 130.330134ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:00:09.286244Z","caller":"traceutil/trace.go:171","msg":"trace[565476982] linearizableReadLoop","detail":"{readStateIndex:19655; appliedIndex:19653; }","duration":"134.615965ms","start":"2026-10-09T06:00:09.151604Z","end":"2026-10-09T06:00:09.28622Z","steps":["trace[565476982] 'read index received' (duration: 105.828531ms)","trace[565476982] 'applied index is now lower than readState.Index' (duration: 28.786394ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:00:09.28634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.722277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:00:09.286361Z","caller":"traceutil/trace.go:171","msg":"trace[1525500933] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:19007; }","duration":"134.786108ms","start":"2026-10-09T06:00:09.15157Z","end":"2026-10-09T06:00:09.286356Z","steps":["trace[1525500933] 'agreement among raft nodes before linearized reading' (duration: 134.728187ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:00:09.28627Z","caller":"traceutil/trace.go:171","msg":"trace[1417630410] transaction","detail":"{read_only:false; response_revision:19007; number_of_response:1; }","duration":"154.790495ms","start":"2026-10-09T06:00:09.131467Z","end":"2026-10-09T06:00:09.286257Z","steps":["trace[1417630410] 'process raft request' (duration: 154.585742ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:00:09.510688Z","caller":"traceutil/trace.go:171","msg":"trace[638824249] linearizableReadLoop","detail":"{readStateIndex:19656; appliedIndex:19655; }","duration":"104.601186ms","start":"2026-10-09T06:00:09.406061Z","end":"2026-10-09T06:00:09.510662Z","steps":["trace[638824249] 'read index received' (duration: 104.397882ms)","trace[638824249] 'applied index is now lower than readState.Index' (duration: 202.454µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:00:09.51097Z","caller":"traceutil/trace.go:171","msg":"trace[1211410780] transaction","detail":"{read_only:false; response_revision:19008; number_of_response:1; }","duration":"108.179674ms","start":"2026-10-09T06:00:09.402678Z","end":"2026-10-09T06:00:09.510857Z","steps":["trace[1211410780] 'process raft request' (duration: 107.825847ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:00:09.510974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.890942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:1 size:21462"} {"level":"info","ts":"2026-10-09T06:00:09.511162Z","caller":"traceutil/trace.go:171","msg":"trace[109052934] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:1; response_revision:19008; }","duration":"105.163538ms","start":"2026-10-09T06:00:09.40598Z","end":"2026-10-09T06:00:09.511144Z","steps":["trace[109052934] 'agreement among raft nodes before linearized reading' (duration: 104.805721ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:00:11.186211Z","caller":"traceutil/trace.go:171","msg":"trace[1206435012] transaction","detail":"{read_only:false; response_revision:19017; number_of_response:1; }","duration":"364.97598ms","start":"2026-10-09T06:00:10.821212Z","end":"2026-10-09T06:00:11.186188Z","steps":["trace[1206435012] 'process raft request' (duration: 364.797296ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:00:11.186337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:00:10.82119Z","time spent":"365.083042ms","remote":"127.0.0.1:52924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":21444,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:00:13.486849Z","caller":"traceutil/trace.go:171","msg":"trace[1167714905] transaction","detail":"{read_only:false; response_revision:19034; number_of_response:1; }","duration":"139.443326ms","start":"2026-10-09T06:00:13.34738Z","end":"2026-10-09T06:00:13.486823Z","steps":["trace[1167714905] 'process raft request' (duration: 106.599106ms)","trace[1167714905] 'compare' (duration: 32.640376ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:00:13.48727Z","caller":"traceutil/trace.go:171","msg":"trace[434358307] linearizableReadLoop","detail":"{readStateIndex:19683; appliedIndex:19681; }","duration":"100.699593ms","start":"2026-10-09T06:00:13.386535Z","end":"2026-10-09T06:00:13.487234Z","steps":["trace[434358307] 'read index received' (duration: 67.389123ms)","trace[434358307] 'applied index is now lower than readState.Index' (duration: 33.30821ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:00:13.487444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.291319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:1 size:21565"} {"level":"info","ts":"2026-10-09T06:00:13.487525Z","caller":"traceutil/trace.go:171","msg":"trace[1901905286] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:1; response_revision:19035; }","duration":"115.403942ms","start":"2026-10-09T06:00:13.372103Z","end":"2026-10-09T06:00:13.487507Z","steps":["trace[1901905286] 'agreement among raft nodes before linearized reading' (duration: 115.200668ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:00:13.487685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.749798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T06:00:13.4876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.8727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:00:13.487772Z","caller":"traceutil/trace.go:171","msg":"trace[536667063] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:19035; }","duration":"116.898201ms","start":"2026-10-09T06:00:13.370855Z","end":"2026-10-09T06:00:13.487753Z","steps":["trace[536667063] 'agreement among raft nodes before linearized reading' (duration: 116.743408ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:00:13.487799Z","caller":"traceutil/trace.go:171","msg":"trace[1226306321] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:19035; }","duration":"116.158046ms","start":"2026-10-09T06:00:13.37162Z","end":"2026-10-09T06:00:13.487778Z","steps":["trace[1226306321] 'agreement among raft nodes before linearized reading' (duration: 115.783249ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:00:13.487829Z","caller":"traceutil/trace.go:171","msg":"trace[1159649243] transaction","detail":"{read_only:false; response_revision:19035; number_of_response:1; }","duration":"119.303285ms","start":"2026-10-09T06:00:13.368474Z","end":"2026-10-09T06:00:13.487778Z","steps":["trace[1159649243] 'process raft request' (duration: 118.270065ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:00:13.487915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.753754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:1 size:21565"} {"level":"warn","ts":"2026-10-09T06:00:13.487662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.652204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29858760-5llz7\" ","response":"range_response_count:1 size:7042"} {"level":"info","ts":"2026-10-09T06:00:13.488235Z","caller":"traceutil/trace.go:171","msg":"trace[2039012844] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29858760-5llz7; range_end:; response_count:1; response_revision:19035; }","duration":"137.271465ms","start":"2026-10-09T06:00:13.350946Z","end":"2026-10-09T06:00:13.488217Z","steps":["trace[2039012844] 'agreement among raft nodes before linearized reading' (duration: 136.604103ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:00:13.488328Z","caller":"traceutil/trace.go:171","msg":"trace[829461209] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:1; response_revision:19035; }","duration":"111.870415ms","start":"2026-10-09T06:00:13.376124Z","end":"2026-10-09T06:00:13.487995Z","steps":["trace[829461209] 'agreement among raft nodes before linearized reading' (duration: 111.57474ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:00:13.648069Z","caller":"traceutil/trace.go:171","msg":"trace[335256461] linearizableReadLoop","detail":"{readStateIndex:19686; appliedIndex:19685; }","duration":"125.119915ms","start":"2026-10-09T06:00:13.522928Z","end":"2026-10-09T06:00:13.648048Z","steps":["trace[335256461] 'read index received' (duration: 14.854132ms)","trace[335256461] 'applied index is now lower than readState.Index' (duration: 110.264663ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:00:13.64818Z","caller":"traceutil/trace.go:171","msg":"trace[918914781] transaction","detail":"{read_only:false; response_revision:19037; number_of_response:1; }","duration":"133.259059ms","start":"2026-10-09T06:00:13.514908Z","end":"2026-10-09T06:00:13.648167Z","steps":["trace[918914781] 'process raft request' (duration: 82.376436ms)","trace[918914781] 'compare' (duration: 50.640028ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:00:13.648423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.464032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:00:13.648457Z","caller":"traceutil/trace.go:171","msg":"trace[837155043] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:19037; }","duration":"125.545413ms","start":"2026-10-09T06:00:13.522902Z","end":"2026-10-09T06:00:13.648447Z","steps":["trace[837155043] 'agreement among raft nodes before linearized reading' (duration: 125.463782ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:00:13.648536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.113477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:00:13.648562Z","caller":"traceutil/trace.go:171","msg":"trace[674162368] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:19037; }","duration":"124.207148ms","start":"2026-10-09T06:00:13.524349Z","end":"2026-10-09T06:00:13.648556Z","steps":["trace[674162368] 'agreement among raft nodes before linearized reading' (duration: 124.167157ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:00:13.648641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.015762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:1 size:21559"} {"level":"info","ts":"2026-10-09T06:00:13.648664Z","caller":"traceutil/trace.go:171","msg":"trace[1374604819] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:1; response_revision:19037; }","duration":"108.064952ms","start":"2026-10-09T06:00:13.540594Z","end":"2026-10-09T06:00:13.648659Z","steps":["trace[1374604819] 'agreement among raft nodes before linearized reading' (duration: 108.024921ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:00:13.648796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.787987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-10-09T06:00:13.64882Z","caller":"traceutil/trace.go:171","msg":"trace[1711917358] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:19037; }","duration":"108.836007ms","start":"2026-10-09T06:00:13.539978Z","end":"2026-10-09T06:00:13.648814Z","steps":["trace[1711917358] 'agreement among raft nodes before linearized reading' (duration: 108.742015ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:00:13.648943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.172291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:00:13.648964Z","caller":"traceutil/trace.go:171","msg":"trace[996424758] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:19037; }","duration":"112.236772ms","start":"2026-10-09T06:00:13.536723Z","end":"2026-10-09T06:00:13.648959Z","steps":["trace[996424758] 'agreement among raft nodes before linearized reading' (duration: 112.202401ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:00:13.649135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.313126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:1 size:21559"} {"level":"info","ts":"2026-10-09T06:00:13.64916Z","caller":"traceutil/trace.go:171","msg":"trace[2031942397] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:1; response_revision:19037; }","duration":"109.339067ms","start":"2026-10-09T06:00:13.539815Z","end":"2026-10-09T06:00:13.649154Z","steps":["trace[2031942397] 'agreement among raft nodes before linearized reading' (duration: 109.287996ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:00:13.649265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.225402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-09T06:00:13.649382Z","caller":"traceutil/trace.go:171","msg":"trace[305898749] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19037; }","duration":"111.499318ms","start":"2026-10-09T06:00:13.537859Z","end":"2026-10-09T06:00:13.649359Z","steps":["trace[305898749] 'agreement among raft nodes before linearized reading' (duration: 111.056339ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:00:15.984309Z","caller":"traceutil/trace.go:171","msg":"trace[1004808977] transaction","detail":"{read_only:false; response_revision:19063; number_of_response:1; }","duration":"133.26238ms","start":"2026-10-09T06:00:15.851026Z","end":"2026-10-09T06:00:15.984288Z","steps":["trace[1004808977] 'process raft request' (duration: 133.115797ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:00:16.521608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.160284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:00:16.521688Z","caller":"traceutil/trace.go:171","msg":"trace[1137403531] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:19069; }","duration":"182.305946ms","start":"2026-10-09T06:00:16.339362Z","end":"2026-10-09T06:00:16.521668Z","steps":["trace[1137403531] 'count revisions from in-memory index tree' (duration: 182.073892ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:01:00.428179Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e2d037ebe7b91690","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T06:01:00.436649Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-09T06:01:00.436769Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-09T06:02:29.544919Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17510} {"level":"info","ts":"2026-10-09T06:02:29.645468Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17510,"took":"97.525094ms","hash":3878341451,"current-db-size-bytes":55648256,"current-db-size":"56 MB","current-db-size-in-use-bytes":35205120,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-09T06:02:29.645612Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3878341451,"revision":17510,"compact-revision":15317} {"level":"info","ts":"2026-10-09T06:03:50.741445Z","caller":"traceutil/trace.go:171","msg":"trace[457167956] linearizableReadLoop","detail":"{readStateIndex:21260; appliedIndex:21259; }","duration":"125.877889ms","start":"2026-10-09T06:03:50.615533Z","end":"2026-10-09T06:03:50.74141Z","steps":["trace[457167956] 'read index received' (duration: 125.614424ms)","trace[457167956] 'applied index is now lower than readState.Index' (duration: 262.105µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:03:50.741515Z","caller":"traceutil/trace.go:171","msg":"trace[375553650] transaction","detail":"{read_only:false; response_revision:20558; number_of_response:1; }","duration":"127.394338ms","start":"2026-10-09T06:03:50.61408Z","end":"2026-10-09T06:03:50.741474Z","steps":["trace[375553650] 'process raft request' (duration: 127.090502ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:03:50.741792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.226686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T06:03:50.741836Z","caller":"traceutil/trace.go:171","msg":"trace[96394407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20558; }","duration":"126.336078ms","start":"2026-10-09T06:03:50.615487Z","end":"2026-10-09T06:03:50.741824Z","steps":["trace[96394407] 'agreement among raft nodes before linearized reading' (duration: 126.112024ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:03:50.944627Z","caller":"traceutil/trace.go:171","msg":"trace[1830558005] transaction","detail":"{read_only:false; response_revision:20559; number_of_response:1; }","duration":"198.722426ms","start":"2026-10-09T06:03:50.745881Z","end":"2026-10-09T06:03:50.944603Z","steps":["trace[1830558005] 'process raft request' (duration: 190.136604ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:03:50.998592Z","caller":"traceutil/trace.go:171","msg":"trace[1328086188] linearizableReadLoop","detail":"{readStateIndex:21262; appliedIndex:21260; }","duration":"119.791554ms","start":"2026-10-09T06:03:50.878763Z","end":"2026-10-09T06:03:50.998554Z","steps":["trace[1328086188] 'read index received' (duration: 57.228972ms)","trace[1328086188] 'applied index is now lower than readState.Index' (duration: 62.561012ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:03:50.998645Z","caller":"traceutil/trace.go:171","msg":"trace[1960992348] transaction","detail":"{read_only:false; response_revision:20560; number_of_response:1; }","duration":"205.445623ms","start":"2026-10-09T06:03:50.793154Z","end":"2026-10-09T06:03:50.998599Z","steps":["trace[1960992348] 'process raft request' (duration: 205.245969ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:03:50.998803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.017598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T06:03:50.998968Z","caller":"traceutil/trace.go:171","msg":"trace[1274036051] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20560; }","duration":"120.250713ms","start":"2026-10-09T06:03:50.878697Z","end":"2026-10-09T06:03:50.998948Z","steps":["trace[1274036051] 'agreement among raft nodes before linearized reading' (duration: 119.970537ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:04:01.242197Z","caller":"traceutil/trace.go:171","msg":"trace[1857862068] transaction","detail":"{read_only:false; response_revision:20613; number_of_response:1; }","duration":"131.910894ms","start":"2026-10-09T06:04:01.110261Z","end":"2026-10-09T06:04:01.242171Z","steps":["trace[1857862068] 'process raft request' (duration: 130.343794ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:04:07.332892Z","caller":"traceutil/trace.go:171","msg":"trace[1608999893] transaction","detail":"{read_only:false; response_revision:20643; number_of_response:1; }","duration":"213.66966ms","start":"2026-10-09T06:04:07.119176Z","end":"2026-10-09T06:04:07.332846Z","steps":["trace[1608999893] 'process raft request' (duration: 133.707748ms)","trace[1608999893] 'compare' (duration: 79.792488ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:04:07.559549Z","caller":"traceutil/trace.go:171","msg":"trace[2118571412] linearizableReadLoop","detail":"{readStateIndex:21349; appliedIndex:21348; }","duration":"254.018913ms","start":"2026-10-09T06:04:07.3055Z","end":"2026-10-09T06:04:07.559519Z","steps":["trace[2118571412] 'read index received' (duration: 253.767848ms)","trace[2118571412] 'applied index is now lower than readState.Index' (duration: 249.664µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:04:07.559622Z","caller":"traceutil/trace.go:171","msg":"trace[338926200] transaction","detail":"{read_only:false; response_revision:20644; number_of_response:1; }","duration":"293.785863ms","start":"2026-10-09T06:04:07.2658Z","end":"2026-10-09T06:04:07.559585Z","steps":["trace[338926200] 'process raft request' (duration: 293.570349ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:04:07.559833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.320888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-09T06:04:07.559858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.447388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-10-09T06:04:07.559925Z","caller":"traceutil/trace.go:171","msg":"trace[148827213] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20644; }","duration":"254.451051ms","start":"2026-10-09T06:04:07.305457Z","end":"2026-10-09T06:04:07.559909Z","steps":["trace[148827213] 'agreement among raft nodes before linearized reading' (duration: 254.215216ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:04:07.559944Z","caller":"traceutil/trace.go:171","msg":"trace[789483886] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:20644; }","duration":"246.593131ms","start":"2026-10-09T06:04:07.313332Z","end":"2026-10-09T06:04:07.559925Z","steps":["trace[789483886] 'agreement among raft nodes before linearized reading' (duration: 246.368897ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:04:07.560037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.222986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-09T06:04:07.560111Z","caller":"traceutil/trace.go:171","msg":"trace[958110916] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20644; }","duration":"217.336499ms","start":"2026-10-09T06:04:07.342752Z","end":"2026-10-09T06:04:07.560088Z","steps":["trace[958110916] 'agreement among raft nodes before linearized reading' (duration: 217.009373ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:04:07.78196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.914613ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T06:04:07.782484Z","caller":"traceutil/trace.go:171","msg":"trace[1880129520] transaction","detail":"{read_only:false; response_revision:20646; number_of_response:1; }","duration":"217.45091ms","start":"2026-10-09T06:04:07.564998Z","end":"2026-10-09T06:04:07.782449Z","steps":["trace[1880129520] 'process raft request' (duration: 217.065523ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:04:07.782507Z","caller":"traceutil/trace.go:171","msg":"trace[994223488] transaction","detail":"{read_only:false; response_revision:20647; number_of_response:1; }","duration":"217.471411ms","start":"2026-10-09T06:04:07.565019Z","end":"2026-10-09T06:04:07.782491Z","steps":["trace[994223488] 'process raft request' (duration: 217.124604ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:04:07.78267Z","caller":"traceutil/trace.go:171","msg":"trace[439697949] transaction","detail":"{read_only:false; response_revision:20645; number_of_response:1; }","duration":"217.9238ms","start":"2026-10-09T06:04:07.564715Z","end":"2026-10-09T06:04:07.782639Z","steps":["trace[439697949] 'process raft request' (duration: 67.260572ms)","trace[439697949] 'compare' (duration: 149.696039ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:07:29.553561Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20022} {"level":"info","ts":"2026-10-09T06:07:29.685567Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20022,"took":"128.143372ms","hash":4129230839,"current-db-size-bytes":55648256,"current-db-size":"56 MB","current-db-size-in-use-bytes":30826496,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-09T06:07:29.685642Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4129230839,"revision":20022,"compact-revision":17510} {"level":"info","ts":"2026-10-09T06:08:02.814521Z","caller":"traceutil/trace.go:171","msg":"trace[1014635949] transaction","detail":"{read_only:false; response_revision:23124; number_of_response:1; }","duration":"275.239835ms","start":"2026-10-09T06:08:02.539246Z","end":"2026-10-09T06:08:02.814486Z","steps":["trace[1014635949] 'process raft request' (duration: 275.048731ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:08:02.815219Z","caller":"traceutil/trace.go:171","msg":"trace[934009061] transaction","detail":"{read_only:false; response_revision:23125; number_of_response:1; }","duration":"268.452857ms","start":"2026-10-09T06:08:02.546743Z","end":"2026-10-09T06:08:02.815196Z","steps":["trace[934009061] 'process raft request' (duration: 268.280763ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:08:02.815404Z","caller":"traceutil/trace.go:171","msg":"trace[356047581] linearizableReadLoop","detail":"{readStateIndex:23905; appliedIndex:23903; }","duration":"144.974081ms","start":"2026-10-09T06:08:02.670393Z","end":"2026-10-09T06:08:02.815367Z","steps":["trace[356047581] 'read index received' (duration: 143.954921ms)","trace[356047581] 'applied index is now lower than readState.Index' (duration: 1.0178ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:08:02.815633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.801919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T06:08:02.815663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.243376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-09T06:08:02.815712Z","caller":"traceutil/trace.go:171","msg":"trace[205099660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23126; }","duration":"144.932912ms","start":"2026-10-09T06:08:02.670738Z","end":"2026-10-09T06:08:02.815671Z","steps":["trace[205099660] 'agreement among raft nodes before linearized reading' (duration: 144.810399ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:08:02.815721Z","caller":"traceutil/trace.go:171","msg":"trace[1330803712] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23126; }","duration":"145.347138ms","start":"2026-10-09T06:08:02.670353Z","end":"2026-10-09T06:08:02.8157Z","steps":["trace[1330803712] 'agreement among raft nodes before linearized reading' (duration: 145.150754ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:28.396626Z","caller":"traceutil/trace.go:171","msg":"trace[210839371] linearizableReadLoop","detail":"{readStateIndex:26060; appliedIndex:26059; }","duration":"108.829538ms","start":"2026-10-09T06:11:28.287772Z","end":"2026-10-09T06:11:28.396601Z","steps":["trace[210839371] 'read index received' (duration: 108.652354ms)","trace[210839371] 'applied index is now lower than readState.Index' (duration: 176.154µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:11:28.396951Z","caller":"traceutil/trace.go:171","msg":"trace[450393110] transaction","detail":"{read_only:false; response_revision:25228; number_of_response:1; }","duration":"114.447064ms","start":"2026-10-09T06:11:28.28249Z","end":"2026-10-09T06:11:28.396937Z","steps":["trace[450393110] 'process raft request' (duration: 113.963815ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:28.397265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.44844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-09T06:11:28.397308Z","caller":"traceutil/trace.go:171","msg":"trace[1218607440] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25228; }","duration":"109.563232ms","start":"2026-10-09T06:11:28.287732Z","end":"2026-10-09T06:11:28.397296Z","steps":["trace[1218607440] 'agreement among raft nodes before linearized reading' (duration: 109.393649ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:28.397469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.704274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-09T06:11:28.3975Z","caller":"traceutil/trace.go:171","msg":"trace[1686229593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25228; }","duration":"109.779705ms","start":"2026-10-09T06:11:28.287712Z","end":"2026-10-09T06:11:28.397492Z","steps":["trace[1686229593] 'agreement among raft nodes before linearized reading' (duration: 109.702024ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:28.679964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.980565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T06:11:28.68023Z","caller":"traceutil/trace.go:171","msg":"trace[677339521] transaction","detail":"{read_only:false; response_revision:25231; number_of_response:1; }","duration":"277.149282ms","start":"2026-10-09T06:11:28.403059Z","end":"2026-10-09T06:11:28.680208Z","steps":["trace[677339521] 'process raft request' (duration: 277.0962ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:28.680669Z","caller":"traceutil/trace.go:171","msg":"trace[1506699206] transaction","detail":"{read_only:false; response_revision:25229; number_of_response:1; }","duration":"278.909814ms","start":"2026-10-09T06:11:28.401724Z","end":"2026-10-09T06:11:28.680634Z","steps":["trace[1506699206] 'process raft request' (duration: 108.164215ms)","trace[1506699206] 'compare' (duration: 169.823502ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:11:28.681196Z","caller":"traceutil/trace.go:171","msg":"trace[1077820867] transaction","detail":"{read_only:false; response_revision:25230; number_of_response:1; }","duration":"277.949146ms","start":"2026-10-09T06:11:28.402646Z","end":"2026-10-09T06:11:28.680595Z","steps":["trace[1077820867] 'process raft request' (duration: 277.443406ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:28.966872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.896087ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-09T06:11:28.967385Z","caller":"traceutil/trace.go:171","msg":"trace[1212470886] transaction","detail":"{read_only:false; response_revision:25234; number_of_response:1; }","duration":"141.114699ms","start":"2026-10-09T06:11:28.826252Z","end":"2026-10-09T06:11:28.967366Z","steps":["trace[1212470886] 'process raft request' (duration: 141.071569ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:28.967497Z","caller":"traceutil/trace.go:171","msg":"trace[207075190] transaction","detail":"{read_only:false; response_revision:25232; number_of_response:1; }","duration":"359.825825ms","start":"2026-10-09T06:11:28.607606Z","end":"2026-10-09T06:11:28.967432Z","steps":["trace[207075190] 'process raft request' (duration: 359.367076ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:28.967571Z","caller":"traceutil/trace.go:171","msg":"trace[471271642] transaction","detail":"{read_only:false; response_revision:25233; number_of_response:1; }","duration":"259.386496ms","start":"2026-10-09T06:11:28.708164Z","end":"2026-10-09T06:11:28.967551Z","steps":["trace[471271642] 'process raft request' (duration: 259.113561ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:28.967602Z","caller":"traceutil/trace.go:171","msg":"trace[658920876] linearizableReadLoop","detail":"{readStateIndex:26065; appliedIndex:26063; }","duration":"296.045388ms","start":"2026-10-09T06:11:28.671473Z","end":"2026-10-09T06:11:28.967519Z","steps":["trace[658920876] 'read index received' (duration: 176.886995ms)","trace[658920876] 'applied index is now lower than readState.Index' (duration: 119.149093ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:11:28.967705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:28.607576Z","time spent":"360.021059ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:11:28.967837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.304913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T06:11:28.967929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.854758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T06:11:28.96795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.218623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:11:28.967977Z","caller":"traceutil/trace.go:171","msg":"trace[1538484189] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:25234; }","duration":"127.92412ms","start":"2026-10-09T06:11:28.840039Z","end":"2026-10-09T06:11:28.967963Z","steps":["trace[1538484189] 'agreement among raft nodes before linearized reading' (duration: 127.847068ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:28.967983Z","caller":"traceutil/trace.go:171","msg":"trace[1444290399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25234; }","duration":"296.528838ms","start":"2026-10-09T06:11:28.67142Z","end":"2026-10-09T06:11:28.967949Z","steps":["trace[1444290399] 'agreement among raft nodes before linearized reading' (duration: 296.248732ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:28.968Z","caller":"traceutil/trace.go:171","msg":"trace[136596749] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:25234; }","duration":"129.311075ms","start":"2026-10-09T06:11:28.838672Z","end":"2026-10-09T06:11:28.967983Z","steps":["trace[136596749] 'agreement among raft nodes before linearized reading' (duration: 129.203463ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:29.78427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.973494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:11:29.784358Z","caller":"traceutil/trace.go:171","msg":"trace[1938730696] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:25235; }","duration":"253.109757ms","start":"2026-10-09T06:11:29.531225Z","end":"2026-10-09T06:11:29.784335Z","steps":["trace[1938730696] 'count revisions from in-memory index tree' (duration: 252.828492ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:29.784409Z","caller":"traceutil/trace.go:171","msg":"trace[1099847725] transaction","detail":"{read_only:false; response_revision:25236; number_of_response:1; }","duration":"186.717211ms","start":"2026-10-09T06:11:29.597665Z","end":"2026-10-09T06:11:29.784382Z","steps":["trace[1099847725] 'process raft request' (duration: 182.598543ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:29.924131Z","caller":"traceutil/trace.go:171","msg":"trace[2086512611] transaction","detail":"{read_only:false; response_revision:25237; number_of_response:1; }","duration":"322.928257ms","start":"2026-10-09T06:11:29.601179Z","end":"2026-10-09T06:11:29.924107Z","steps":["trace[2086512611] 'process raft request' (duration: 322.688592ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:29.924313Z","caller":"traceutil/trace.go:171","msg":"trace[1080321308] linearizableReadLoop","detail":"{readStateIndex:26071; appliedIndex:26068; }","duration":"148.726182ms","start":"2026-10-09T06:11:29.775556Z","end":"2026-10-09T06:11:29.924282Z","steps":["trace[1080321308] 'read index received' (duration: 4.758439ms)","trace[1080321308] 'applied index is now lower than readState.Index' (duration: 143.965743ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:11:29.924366Z","caller":"traceutil/trace.go:171","msg":"trace[1405783705] transaction","detail":"{read_only:false; response_revision:25238; number_of_response:1; }","duration":"179.904702ms","start":"2026-10-09T06:11:29.744421Z","end":"2026-10-09T06:11:29.924326Z","steps":["trace[1405783705] 'process raft request' (duration: 179.642257ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:29.924385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:29.601152Z","time spent":"323.117931ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:11:29.924648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.05643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-09T06:11:29.924721Z","caller":"traceutil/trace.go:171","msg":"trace[2032124634] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:25238; }","duration":"149.181092ms","start":"2026-10-09T06:11:29.775522Z","end":"2026-10-09T06:11:29.924703Z","steps":["trace[2032124634] 'agreement among raft nodes before linearized reading' (duration: 148.873526ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:35.141042Z","caller":"traceutil/trace.go:171","msg":"trace[712685606] transaction","detail":"{read_only:false; response_revision:25281; number_of_response:1; }","duration":"151.157828ms","start":"2026-10-09T06:11:34.989856Z","end":"2026-10-09T06:11:35.141014Z","steps":["trace[712685606] 'process raft request' (duration: 150.986625ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:35.457529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.610957ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T06:11:35.457605Z","caller":"traceutil/trace.go:171","msg":"trace[1785997857] linearizableReadLoop","detail":"{readStateIndex:26116; appliedIndex:26115; }","duration":"179.168308ms","start":"2026-10-09T06:11:35.278426Z","end":"2026-10-09T06:11:35.457595Z","steps":["trace[1785997857] 'read index received' (duration: 241.054µs)","trace[1785997857] 'applied index is now lower than readState.Index' (duration: 178.926334ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:11:35.457653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.220839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:11:35.457667Z","caller":"traceutil/trace.go:171","msg":"trace[687769623] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:25282; }","duration":"179.23968ms","start":"2026-10-09T06:11:35.278421Z","end":"2026-10-09T06:11:35.45766Z","steps":["trace[687769623] 'agreement among raft nodes before linearized reading' (duration: 179.198139ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:35.457673Z","caller":"traceutil/trace.go:171","msg":"trace[1806655521] transaction","detail":"{read_only:false; response_revision:25282; number_of_response:1; }","duration":"465.939772ms","start":"2026-10-09T06:11:34.991701Z","end":"2026-10-09T06:11:35.45764Z","steps":["trace[1806655521] 'process raft request' (duration: 212.152212ms)","trace[1806655521] 'compare' (duration: 253.477404ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:11:35.457845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:34.991676Z","time spent":"466.069304ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:11:35.743414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.63913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:11:35.743495Z","caller":"traceutil/trace.go:171","msg":"trace[2146615562] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25283; }","duration":"185.730842ms","start":"2026-10-09T06:11:35.557747Z","end":"2026-10-09T06:11:35.743478Z","steps":["trace[2146615562] 'range keys from in-memory index tree' (duration: 185.525838ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:35.956181Z","caller":"traceutil/trace.go:171","msg":"trace[1860085113] transaction","detail":"{read_only:false; response_revision:25285; number_of_response:1; }","duration":"149.649249ms","start":"2026-10-09T06:11:35.806501Z","end":"2026-10-09T06:11:35.95615Z","steps":["trace[1860085113] 'process raft request' (duration: 149.174231ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:36.258877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.022456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T06:11:36.258979Z","caller":"traceutil/trace.go:171","msg":"trace[307109874] linearizableReadLoop","detail":"{readStateIndex:26122; appliedIndex:26120; }","duration":"283.162245ms","start":"2026-10-09T06:11:35.975802Z","end":"2026-10-09T06:11:36.258965Z","steps":["trace[307109874] 'read index received' (duration: 58.342424ms)","trace[307109874] 'applied index is now lower than readState.Index' (duration: 224.818761ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:11:36.259044Z","caller":"traceutil/trace.go:171","msg":"trace[1047089183] transaction","detail":"{read_only:false; response_revision:25288; number_of_response:1; }","duration":"285.076151ms","start":"2026-10-09T06:11:35.973932Z","end":"2026-10-09T06:11:36.259009Z","steps":["trace[1047089183] 'process raft request' (duration: 142.864932ms)","trace[1047089183] 'compare' (duration: 141.881123ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:11:36.259109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.324519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-09T06:11:36.259156Z","caller":"traceutil/trace.go:171","msg":"trace[798059053] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25288; }","duration":"283.401079ms","start":"2026-10-09T06:11:35.975739Z","end":"2026-10-09T06:11:36.259141Z","steps":["trace[798059053] 'agreement among raft nodes before linearized reading' (duration: 283.274227ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:36.268142Z","caller":"traceutil/trace.go:171","msg":"trace[1220662863] transaction","detail":"{read_only:false; response_revision:25289; number_of_response:1; }","duration":"233.685728ms","start":"2026-10-09T06:11:36.034425Z","end":"2026-10-09T06:11:36.268111Z","steps":["trace[1220662863] 'process raft request' (duration: 233.541195ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:36.632866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.071276ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T06:11:36.633104Z","caller":"traceutil/trace.go:171","msg":"trace[297488053] transaction","detail":"{read_only:false; response_revision:25291; number_of_response:1; }","duration":"159.679759ms","start":"2026-10-09T06:11:36.473405Z","end":"2026-10-09T06:11:36.633085Z","steps":["trace[297488053] 'process raft request' (duration: 159.607658ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:36.633352Z","caller":"traceutil/trace.go:171","msg":"trace[261788575] transaction","detail":"{read_only:false; response_revision:25290; number_of_response:1; }","duration":"369.507937ms","start":"2026-10-09T06:11:36.26383Z","end":"2026-10-09T06:11:36.633338Z","steps":["trace[261788575] 'process raft request' (duration: 125.815339ms)","trace[261788575] 'compare' (duration: 242.845872ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:11:36.633501Z","caller":"traceutil/trace.go:171","msg":"trace[2087237991] linearizableReadLoop","detail":"{readStateIndex:26124; appliedIndex:26123; }","duration":"360.072939ms","start":"2026-10-09T06:11:36.273371Z","end":"2026-10-09T06:11:36.633444Z","steps":["trace[2087237991] 'read index received' (duration: 116.164537ms)","trace[2087237991] 'applied index is now lower than readState.Index' (duration: 243.906912ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:11:36.634014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:36.263802Z","time spent":"369.579669ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:11:36.634245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.848594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:11:36.634448Z","caller":"traceutil/trace.go:171","msg":"trace[1976425191] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25291; }","duration":"361.106748ms","start":"2026-10-09T06:11:36.273322Z","end":"2026-10-09T06:11:36.634428Z","steps":["trace[1976425191] 'agreement among raft nodes before linearized reading' (duration: 360.253352ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:36.634723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.139227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:539"} {"level":"info","ts":"2026-10-09T06:11:36.634813Z","caller":"traceutil/trace.go:171","msg":"trace[592165085] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:25291; }","duration":"170.27469ms","start":"2026-10-09T06:11:36.46452Z","end":"2026-10-09T06:11:36.634795Z","steps":["trace[592165085] 'agreement among raft nodes before linearized reading' (duration: 169.081396ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:36.634839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:36.273302Z","time spent":"361.520897ms","remote":"127.0.0.1:52886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-10-09T06:11:36.635195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.363927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-09T06:11:36.63523Z","caller":"traceutil/trace.go:171","msg":"trace[427970233] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25291; }","duration":"202.449369ms","start":"2026-10-09T06:11:36.432769Z","end":"2026-10-09T06:11:36.635219Z","steps":["trace[427970233] 'agreement among raft nodes before linearized reading' (duration: 200.902129ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:36.635298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.752076ms","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:8"} {"level":"info","ts":"2026-10-09T06:11:36.635345Z","caller":"traceutil/trace.go:171","msg":"trace[1612503641] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:25291; }","duration":"338.834468ms","start":"2026-10-09T06:11:36.296498Z","end":"2026-10-09T06:11:36.635332Z","steps":["trace[1612503641] 'agreement among raft nodes before linearized reading' (duration: 337.201596ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:36.635343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.223827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T06:11:36.635369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:36.296481Z","time spent":"338.880828ms","remote":"127.0.0.1:44386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":31,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true "} {"level":"info","ts":"2026-10-09T06:11:36.635403Z","caller":"traceutil/trace.go:171","msg":"trace[714986686] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:25291; }","duration":"346.37933ms","start":"2026-10-09T06:11:36.289005Z","end":"2026-10-09T06:11:36.635385Z","steps":["trace[714986686] 'agreement among raft nodes before linearized reading' (duration: 344.584665ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:36.635451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:36.288977Z","time spent":"346.460672ms","remote":"127.0.0.1:53066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true "} {"level":"info","ts":"2026-10-09T06:11:36.889424Z","caller":"traceutil/trace.go:171","msg":"trace[1632650656] linearizableReadLoop","detail":"{readStateIndex:26127; appliedIndex:26126; }","duration":"161.518794ms","start":"2026-10-09T06:11:36.727883Z","end":"2026-10-09T06:11:36.889402Z","steps":["trace[1632650656] 'read index received' (duration: 68.544106ms)","trace[1632650656] 'applied index is now lower than readState.Index' (duration: 92.973008ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:11:36.88943Z","caller":"traceutil/trace.go:171","msg":"trace[331996068] transaction","detail":"{read_only:false; response_revision:25293; number_of_response:1; }","duration":"163.708335ms","start":"2026-10-09T06:11:36.725678Z","end":"2026-10-09T06:11:36.889386Z","steps":["trace[331996068] 'process raft request' (duration: 70.736737ms)","trace[331996068] 'compare' (duration: 92.268454ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:11:36.889582Z","caller":"traceutil/trace.go:171","msg":"trace[768012374] transaction","detail":"{read_only:false; response_revision:25294; number_of_response:1; }","duration":"160.228371ms","start":"2026-10-09T06:11:36.728982Z","end":"2026-10-09T06:11:36.88921Z","steps":["trace[768012374] 'process raft request' (duration: 160.140139ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:36.889625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.722818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-09T06:11:36.889684Z","caller":"traceutil/trace.go:171","msg":"trace[1789525433] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25294; }","duration":"161.81294ms","start":"2026-10-09T06:11:36.727837Z","end":"2026-10-09T06:11:36.88965Z","steps":["trace[1789525433] 'agreement among raft nodes before linearized reading' (duration: 161.645597ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:37.395038Z","caller":"traceutil/trace.go:171","msg":"trace[239861349] transaction","detail":"{read_only:false; response_revision:25296; number_of_response:1; }","duration":"345.091306ms","start":"2026-10-09T06:11:37.049917Z","end":"2026-10-09T06:11:37.395008Z","steps":["trace[239861349] 'process raft request' (duration: 344.891292ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:37.395225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:37.049893Z","time spent":"345.234318ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:11:37.40249Z","caller":"traceutil/trace.go:171","msg":"trace[210423689] linearizableReadLoop","detail":"{readStateIndex:26131; appliedIndex:26129; }","duration":"250.162611ms","start":"2026-10-09T06:11:37.152305Z","end":"2026-10-09T06:11:37.402467Z","steps":["trace[210423689] 'read index received' (duration: 242.576528ms)","trace[210423689] 'applied index is now lower than readState.Index' (duration: 7.585133ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:11:37.40252Z","caller":"traceutil/trace.go:171","msg":"trace[407086525] transaction","detail":"{read_only:false; response_revision:25297; number_of_response:1; }","duration":"251.444235ms","start":"2026-10-09T06:11:37.15106Z","end":"2026-10-09T06:11:37.402505Z","steps":["trace[407086525] 'process raft request' (duration: 251.276652ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:37.402592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.277273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T06:11:37.402621Z","caller":"traceutil/trace.go:171","msg":"trace[2084948568] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25297; }","duration":"250.342425ms","start":"2026-10-09T06:11:37.15227Z","end":"2026-10-09T06:11:37.402613Z","steps":["trace[2084948568] 'agreement among raft nodes before linearized reading' (duration: 250.279444ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:37.402757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.218032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:11:37.402841Z","caller":"traceutil/trace.go:171","msg":"trace[944396160] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25297; }","duration":"113.355084ms","start":"2026-10-09T06:11:37.289469Z","end":"2026-10-09T06:11:37.402824Z","steps":["trace[944396160] 'agreement among raft nodes before linearized reading' (duration: 113.217321ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:37.661333Z","caller":"traceutil/trace.go:171","msg":"trace[2010023930] linearizableReadLoop","detail":"{readStateIndex:26132; appliedIndex:26131; }","duration":"148.05977ms","start":"2026-10-09T06:11:37.513241Z","end":"2026-10-09T06:11:37.661301Z","steps":["trace[2010023930] 'read index received' (duration: 64.976529ms)","trace[2010023930] 'applied index is now lower than readState.Index' (duration: 83.081921ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:11:37.661352Z","caller":"traceutil/trace.go:171","msg":"trace[1008319228] transaction","detail":"{read_only:false; response_revision:25298; number_of_response:1; }","duration":"199.163505ms","start":"2026-10-09T06:11:37.462156Z","end":"2026-10-09T06:11:37.66132Z","steps":["trace[1008319228] 'process raft request' (duration: 116.112915ms)","trace[1008319228] 'compare' (duration: 82.726454ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:11:37.661545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.316874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T06:11:37.661574Z","caller":"traceutil/trace.go:171","msg":"trace[299756976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25298; }","duration":"148.396916ms","start":"2026-10-09T06:11:37.513169Z","end":"2026-10-09T06:11:37.661566Z","steps":["trace[299756976] 'agreement among raft nodes before linearized reading' (duration: 148.238153ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:38.086719Z","caller":"traceutil/trace.go:171","msg":"trace[646954361] linearizableReadLoop","detail":"{readStateIndex:26134; appliedIndex:26133; }","duration":"117.3776ms","start":"2026-10-09T06:11:37.969319Z","end":"2026-10-09T06:11:38.086696Z","steps":["trace[646954361] 'read index received' (duration: 117.261788ms)","trace[646954361] 'applied index is now lower than readState.Index' (duration: 114.792µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:11:38.086817Z","caller":"traceutil/trace.go:171","msg":"trace[1702189348] transaction","detail":"{read_only:false; response_revision:25300; number_of_response:1; }","duration":"125.078395ms","start":"2026-10-09T06:11:37.961716Z","end":"2026-10-09T06:11:38.086794Z","steps":["trace[1702189348] 'process raft request' (duration: 124.83781ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:38.086888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.544713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:11:38.086932Z","caller":"traceutil/trace.go:171","msg":"trace[1840915805] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:25300; }","duration":"117.640165ms","start":"2026-10-09T06:11:37.969276Z","end":"2026-10-09T06:11:38.086917Z","steps":["trace[1840915805] 'agreement among raft nodes before linearized reading' (duration: 117.526343ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:38.282171Z","caller":"traceutil/trace.go:171","msg":"trace[2076516247] transaction","detail":"{read_only:false; response_revision:25302; number_of_response:1; }","duration":"241.282043ms","start":"2026-10-09T06:11:38.040868Z","end":"2026-10-09T06:11:38.28215Z","steps":["trace[2076516247] 'process raft request' (duration: 241.237332ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:38.282223Z","caller":"traceutil/trace.go:171","msg":"trace[2135201417] transaction","detail":"{read_only:false; response_revision:25301; number_of_response:1; }","duration":"307.048278ms","start":"2026-10-09T06:11:37.975146Z","end":"2026-10-09T06:11:38.282195Z","steps":["trace[2135201417] 'process raft request' (duration: 306.811293ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:38.282365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:37.975134Z","time spent":"307.145459ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:11:38.561924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.907168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T06:11:38.562068Z","caller":"traceutil/trace.go:171","msg":"trace[314417411] transaction","detail":"{read_only:false; response_revision:25304; number_of_response:1; }","duration":"289.362733ms","start":"2026-10-09T06:11:38.272693Z","end":"2026-10-09T06:11:38.562056Z","steps":["trace[314417411] 'process raft request' (duration: 289.296672ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:38.562135Z","caller":"traceutil/trace.go:171","msg":"trace[1485847702] transaction","detail":"{read_only:false; response_revision:25303; number_of_response:1; }","duration":"297.899973ms","start":"2026-10-09T06:11:38.264181Z","end":"2026-10-09T06:11:38.562081Z","steps":["trace[1485847702] 'process raft request' (duration: 150.777061ms)","trace[1485847702] 'compare' (duration: 146.687204ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:11:38.732747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.732004ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-09T06:11:39.101526Z","caller":"traceutil/trace.go:171","msg":"trace[217187309] linearizableReadLoop","detail":"{readStateIndex:26141; appliedIndex:26140; }","duration":"311.935989ms","start":"2026-10-09T06:11:38.789568Z","end":"2026-10-09T06:11:39.101504Z","steps":["trace[217187309] 'read index received' (duration: 311.48097ms)","trace[217187309] 'applied index is now lower than readState.Index' (duration: 454.139µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:11:39.101667Z","caller":"traceutil/trace.go:171","msg":"trace[938266765] transaction","detail":"{read_only:false; response_revision:25306; number_of_response:1; }","duration":"362.223891ms","start":"2026-10-09T06:11:38.73941Z","end":"2026-10-09T06:11:39.101634Z","steps":["trace[938266765] 'process raft request' (duration: 361.933885ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:39.101933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.247288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-09T06:11:39.101977Z","caller":"traceutil/trace.go:171","msg":"trace[1327977431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25306; }","duration":"208.32537ms","start":"2026-10-09T06:11:38.89364Z","end":"2026-10-09T06:11:39.101965Z","steps":["trace[1327977431] 'agreement among raft nodes before linearized reading' (duration: 208.200158ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:39.101951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:38.739389Z","time spent":"362.428434ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:11:39.102133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.491723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-09T06:11:39.102213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.248708ms","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:6"} {"level":"info","ts":"2026-10-09T06:11:39.102248Z","caller":"traceutil/trace.go:171","msg":"trace[887607485] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:25306; }","duration":"163.306308ms","start":"2026-10-09T06:11:38.938931Z","end":"2026-10-09T06:11:39.102238Z","steps":["trace[887607485] 'agreement among raft nodes before linearized reading' (duration: 163.244947ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:39.102229Z","caller":"traceutil/trace.go:171","msg":"trace[1084987421] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25306; }","duration":"153.677506ms","start":"2026-10-09T06:11:38.948533Z","end":"2026-10-09T06:11:39.10221Z","steps":["trace[1084987421] 'agreement among raft nodes before linearized reading' (duration: 153.342169ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:39.101662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.077713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:11:39.10247Z","caller":"traceutil/trace.go:171","msg":"trace[1495016856] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:25306; }","duration":"312.94361ms","start":"2026-10-09T06:11:38.789515Z","end":"2026-10-09T06:11:39.102458Z","steps":["trace[1495016856] 'agreement among raft nodes before linearized reading' (duration: 312.081493ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:39.102561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:38.789494Z","time spent":"313.051131ms","remote":"127.0.0.1:49802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"warn","ts":"2026-10-09T06:11:39.102568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.269919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-09T06:11:39.102827Z","caller":"traceutil/trace.go:171","msg":"trace[1333091435] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25306; }","duration":"208.531834ms","start":"2026-10-09T06:11:38.894239Z","end":"2026-10-09T06:11:39.102771Z","steps":["trace[1333091435] 'agreement among raft nodes before linearized reading' (duration: 208.015655ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:39.696629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.639388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:11:39.697184Z","caller":"traceutil/trace.go:171","msg":"trace[1256108353] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25310; }","duration":"179.2519ms","start":"2026-10-09T06:11:39.517912Z","end":"2026-10-09T06:11:39.697164Z","steps":["trace[1256108353] 'count revisions from in-memory index tree' (duration: 178.507715ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:40.065407Z","caller":"traceutil/trace.go:171","msg":"trace[1743587820] linearizableReadLoop","detail":"{readStateIndex:26147; appliedIndex:26146; }","duration":"321.176874ms","start":"2026-10-09T06:11:39.744205Z","end":"2026-10-09T06:11:40.065382Z","steps":["trace[1743587820] 'read index received' (duration: 321.050122ms)","trace[1743587820] 'applied index is now lower than readState.Index' (duration: 125.662µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:11:40.065539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.311177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:11:40.065579Z","caller":"traceutil/trace.go:171","msg":"trace[1166894138] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25312; }","duration":"321.408969ms","start":"2026-10-09T06:11:39.744158Z","end":"2026-10-09T06:11:40.065567Z","steps":["trace[1166894138] 'agreement among raft nodes before linearized reading' (duration: 321.317277ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:40.065622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:39.744137Z","time spent":"321.472371ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":29,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true "} {"level":"info","ts":"2026-10-09T06:11:40.065622Z","caller":"traceutil/trace.go:171","msg":"trace[1810928100] transaction","detail":"{read_only:false; response_revision:25312; number_of_response:1; }","duration":"325.574397ms","start":"2026-10-09T06:11:39.740022Z","end":"2026-10-09T06:11:40.065596Z","steps":["trace[1810928100] 'process raft request' (duration: 325.145179ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:40.066177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:39.739987Z","time spent":"325.69031ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:11:40.271456Z","caller":"traceutil/trace.go:171","msg":"trace[479620594] linearizableReadLoop","detail":"{readStateIndex:26148; appliedIndex:26147; }","duration":"163.511132ms","start":"2026-10-09T06:11:40.107919Z","end":"2026-10-09T06:11:40.27143Z","steps":["trace[479620594] 'read index received' (duration: 97.834211ms)","trace[479620594] 'applied index is now lower than readState.Index' (duration: 65.675661ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:11:40.271636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.680236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:11:40.271677Z","caller":"traceutil/trace.go:171","msg":"trace[346275021] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:25313; }","duration":"163.761848ms","start":"2026-10-09T06:11:40.107902Z","end":"2026-10-09T06:11:40.271664Z","steps":["trace[346275021] 'agreement among raft nodes before linearized reading' (duration: 163.616205ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:40.271745Z","caller":"traceutil/trace.go:171","msg":"trace[337293199] transaction","detail":"{read_only:false; response_revision:25313; number_of_response:1; }","duration":"179.609097ms","start":"2026-10-09T06:11:40.092098Z","end":"2026-10-09T06:11:40.271707Z","steps":["trace[337293199] 'process raft request' (duration: 113.72133ms)","trace[337293199] 'compare' (duration: 65.356496ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:11:40.483244Z","caller":"traceutil/trace.go:171","msg":"trace[1667096553] linearizableReadLoop","detail":"{readStateIndex:26151; appliedIndex:26149; }","duration":"108.41153ms","start":"2026-10-09T06:11:40.374811Z","end":"2026-10-09T06:11:40.483222Z","steps":["trace[1667096553] 'read index received' (duration: 18.638112ms)","trace[1667096553] 'applied index is now lower than readState.Index' (duration: 89.772418ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:11:40.483361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.541782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:11:40.483392Z","caller":"traceutil/trace.go:171","msg":"trace[2013688760] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:25316; }","duration":"108.649324ms","start":"2026-10-09T06:11:40.374732Z","end":"2026-10-09T06:11:40.483381Z","steps":["trace[2013688760] 'agreement among raft nodes before linearized reading' (duration: 108.573613ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:40.483389Z","caller":"traceutil/trace.go:171","msg":"trace[813258587] transaction","detail":"{read_only:false; response_revision:25315; number_of_response:1; }","duration":"195.602899ms","start":"2026-10-09T06:11:40.287755Z","end":"2026-10-09T06:11:40.483358Z","steps":["trace[813258587] 'process raft request' (duration: 105.77581ms)","trace[813258587] 'compare' (duration: 89.405331ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:11:40.483456Z","caller":"traceutil/trace.go:171","msg":"trace[474295906] transaction","detail":"{read_only:false; response_revision:25316; number_of_response:1; }","duration":"181.451312ms","start":"2026-10-09T06:11:40.301966Z","end":"2026-10-09T06:11:40.483417Z","steps":["trace[474295906] 'process raft request' (duration: 181.168076ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:49.032088Z","caller":"traceutil/trace.go:171","msg":"trace[703237751] transaction","detail":"{read_only:false; response_revision:25379; number_of_response:1; }","duration":"197.353833ms","start":"2026-10-09T06:11:48.834713Z","end":"2026-10-09T06:11:49.032067Z","steps":["trace[703237751] 'process raft request' (duration: 197.230771ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:49.288287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.342762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-09T06:11:49.288377Z","caller":"traceutil/trace.go:171","msg":"trace[1007277379] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25379; }","duration":"115.454754ms","start":"2026-10-09T06:11:49.172894Z","end":"2026-10-09T06:11:49.288349Z","steps":["trace[1007277379] 'range keys from in-memory index tree' (duration: 115.192198ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:49.288405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.785738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-09T06:11:49.288475Z","caller":"traceutil/trace.go:171","msg":"trace[448046011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25379; }","duration":"117.9203ms","start":"2026-10-09T06:11:49.170538Z","end":"2026-10-09T06:11:49.288459Z","steps":["trace[448046011] 'range keys from in-memory index tree' (duration: 117.605344ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:49.288536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.605614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-09T06:11:49.288611Z","caller":"traceutil/trace.go:171","msg":"trace[749031641] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25379; }","duration":"126.688486ms","start":"2026-10-09T06:11:49.161906Z","end":"2026-10-09T06:11:49.288595Z","steps":["trace[749031641] 'range keys from in-memory index tree' (duration: 126.481512ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:49.288657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.000474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-09T06:11:49.288719Z","caller":"traceutil/trace.go:171","msg":"trace[894340152] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25379; }","duration":"125.087805ms","start":"2026-10-09T06:11:49.163616Z","end":"2026-10-09T06:11:49.288704Z","steps":["trace[894340152] 'range keys from in-memory index tree' (duration: 124.8408ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:49.53428Z","caller":"traceutil/trace.go:171","msg":"trace[1987431980] transaction","detail":"{read_only:false; response_revision:25380; number_of_response:1; }","duration":"241.854413ms","start":"2026-10-09T06:11:49.2924Z","end":"2026-10-09T06:11:49.534254Z","steps":["trace[1987431980] 'process raft request' (duration: 241.697ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:49.83625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.298043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T06:11:49.836474Z","caller":"traceutil/trace.go:171","msg":"trace[1990468493] linearizableReadLoop","detail":"{readStateIndex:26220; appliedIndex:26216; }","duration":"537.410253ms","start":"2026-10-09T06:11:49.299049Z","end":"2026-10-09T06:11:49.836459Z","steps":["trace[1990468493] 'read index received' (duration: 235.114766ms)","trace[1990468493] 'applied index is now lower than readState.Index' (duration: 302.294537ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:11:49.836547Z","caller":"traceutil/trace.go:171","msg":"trace[94929453] transaction","detail":"{read_only:false; response_revision:25383; number_of_response:1; }","duration":"541.838317ms","start":"2026-10-09T06:11:49.294697Z","end":"2026-10-09T06:11:49.836535Z","steps":["trace[94929453] 'process raft request' (duration: 541.691354ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:49.836527Z","caller":"traceutil/trace.go:171","msg":"trace[327123542] transaction","detail":"{read_only:false; response_revision:25382; number_of_response:1; }","duration":"542.617881ms","start":"2026-10-09T06:11:49.293877Z","end":"2026-10-09T06:11:49.836495Z","steps":["trace[327123542] 'process raft request' (duration: 542.451928ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:49.836604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:49.294681Z","time spent":"541.892368ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:11:49.836641Z","caller":"traceutil/trace.go:171","msg":"trace[84358025] transaction","detail":"{read_only:false; response_revision:25381; number_of_response:1; }","duration":"543.995647ms","start":"2026-10-09T06:11:49.292614Z","end":"2026-10-09T06:11:49.83661Z","steps":["trace[84358025] 'process raft request' (duration: 393.830998ms)","trace[84358025] 'compare' (duration: 149.153821ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:11:49.836671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:49.29387Z","time spent":"542.723064ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:11:49.836802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:49.292596Z","time spent":"544.113739ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:11:49.836894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.855312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-10-09T06:11:49.836949Z","caller":"traceutil/trace.go:171","msg":"trace[1635985121] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:25384; }","duration":"537.949414ms","start":"2026-10-09T06:11:49.298988Z","end":"2026-10-09T06:11:49.836937Z","steps":["trace[1635985121] 'agreement among raft nodes before linearized reading' (duration: 537.77733ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:49.836983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:49.298957Z","time spent":"538.018884ms","remote":"127.0.0.1:52858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1883,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" "} {"level":"info","ts":"2026-10-09T06:11:49.837084Z","caller":"traceutil/trace.go:171","msg":"trace[987341746] transaction","detail":"{read_only:false; response_revision:25384; number_of_response:1; }","duration":"346.845719ms","start":"2026-10-09T06:11:49.490071Z","end":"2026-10-09T06:11:49.836917Z","steps":["trace[987341746] 'process raft request' (duration: 346.3518ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:49.837323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:49.49005Z","time spent":"347.159336ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:11:49.857667Z","caller":"traceutil/trace.go:171","msg":"trace[1297208518] transaction","detail":"{read_only:false; response_revision:25385; number_of_response:1; }","duration":"105.600987ms","start":"2026-10-09T06:11:49.752001Z","end":"2026-10-09T06:11:49.857602Z","steps":["trace[1297208518] 'process raft request' (duration: 105.393113ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:49.857675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.945324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T06:11:49.857747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.102978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:11:49.857831Z","caller":"traceutil/trace.go:171","msg":"trace[1706764853] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:25385; }","duration":"390.119498ms","start":"2026-10-09T06:11:49.467693Z","end":"2026-10-09T06:11:49.857812Z","steps":["trace[1706764853] 'agreement among raft nodes before linearized reading' (duration: 389.893493ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:49.857851Z","caller":"traceutil/trace.go:171","msg":"trace[773277649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25385; }","duration":"187.276131ms","start":"2026-10-09T06:11:49.670553Z","end":"2026-10-09T06:11:49.857829Z","steps":["trace[773277649] 'agreement among raft nodes before linearized reading' (duration: 187.037836ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:49.857882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:49.467634Z","time spent":"390.23692ms","remote":"127.0.0.1:52666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true "} {"level":"warn","ts":"2026-10-09T06:11:50.326064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.753702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-09T06:11:50.326187Z","caller":"traceutil/trace.go:171","msg":"trace[1887374456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25385; }","duration":"223.963835ms","start":"2026-10-09T06:11:50.102197Z","end":"2026-10-09T06:11:50.326161Z","steps":["trace[1887374456] 'range keys from in-memory index tree' (duration: 223.421046ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:50.807094Z","caller":"traceutil/trace.go:171","msg":"trace[377544512] linearizableReadLoop","detail":"{readStateIndex:26223; appliedIndex:26222; }","duration":"494.024313ms","start":"2026-10-09T06:11:50.313054Z","end":"2026-10-09T06:11:50.807078Z","steps":["trace[377544512] 'read index received' (duration: 493.893051ms)","trace[377544512] 'applied index is now lower than readState.Index' (duration: 130.322µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:11:50.807217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.145585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T06:11:50.807239Z","caller":"traceutil/trace.go:171","msg":"trace[1639936195] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:25386; }","duration":"494.225387ms","start":"2026-10-09T06:11:50.313007Z","end":"2026-10-09T06:11:50.807232Z","steps":["trace[1639936195] 'agreement among raft nodes before linearized reading' (duration: 494.168846ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:50.807262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:50.312985Z","time spent":"494.270407ms","remote":"127.0.0.1:52872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-10-09T06:11:50.807221Z","caller":"traceutil/trace.go:171","msg":"trace[1471380492] transaction","detail":"{read_only:false; response_revision:25386; number_of_response:1; }","duration":"496.608092ms","start":"2026-10-09T06:11:50.310567Z","end":"2026-10-09T06:11:50.807175Z","steps":["trace[1471380492] 'process raft request' (duration: 496.377257ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:50.808129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:50.310536Z","time spent":"497.462387ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:11:51.22691Z","caller":"traceutil/trace.go:171","msg":"trace[211242445] transaction","detail":"{read_only:false; response_revision:25388; number_of_response:1; }","duration":"801.419166ms","start":"2026-10-09T06:11:50.425464Z","end":"2026-10-09T06:11:51.226884Z","steps":["trace[211242445] 'process raft request' (duration: 801.300804ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:51.227079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:50.425434Z","time spent":"801.550778ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:11:51.227203Z","caller":"traceutil/trace.go:171","msg":"trace[1344226027] transaction","detail":"{read_only:false; response_revision:25387; number_of_response:1; }","duration":"895.920563ms","start":"2026-10-09T06:11:50.331228Z","end":"2026-10-09T06:11:51.227148Z","steps":["trace[1344226027] 'process raft request' (duration: 895.322382ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:51.227357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:50.331206Z","time spent":"896.069856ms","remote":"127.0.0.1:52964","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-10-09T06:11:51.227436Z","caller":"traceutil/trace.go:171","msg":"trace[366134764] linearizableReadLoop","detail":"{readStateIndex:26229; appliedIndex:26223; }","duration":"420.270488ms","start":"2026-10-09T06:11:50.807142Z","end":"2026-10-09T06:11:51.227412Z","steps":["trace[366134764] 'read index received' (duration: 419.31771ms)","trace[366134764] 'applied index is now lower than readState.Index' (duration: 951.788µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:11:51.227509Z","caller":"traceutil/trace.go:171","msg":"trace[1272237670] transaction","detail":"{read_only:false; response_revision:25391; number_of_response:1; }","duration":"612.752979ms","start":"2026-10-09T06:11:50.614737Z","end":"2026-10-09T06:11:51.22749Z","steps":["trace[1272237670] 'process raft request' (duration: 612.556455ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:51.227536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.10752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T06:11:51.227548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.965454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T06:11:51.227577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:50.614724Z","time spent":"612.81765ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:11:51.227571Z","caller":"traceutil/trace.go:171","msg":"trace[1377196457] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25392; }","duration":"452.13917ms","start":"2026-10-09T06:11:50.77542Z","end":"2026-10-09T06:11:51.227559Z","steps":["trace[1377196457] 'agreement among raft nodes before linearized reading' (duration: 452.07069ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:51.227594Z","caller":"traceutil/trace.go:171","msg":"trace[1331109141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25392; }","duration":"557.014864ms","start":"2026-10-09T06:11:50.670566Z","end":"2026-10-09T06:11:51.22758Z","steps":["trace[1331109141] 'agreement among raft nodes before linearized reading' (duration: 556.927003ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:51.227617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:50.775404Z","time spent":"452.207081ms","remote":"127.0.0.1:49804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-10-09T06:11:51.227626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:50.67052Z","time spent":"557.098755ms","remote":"127.0.0.1:52710","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-10-09T06:11:51.227599Z","caller":"traceutil/trace.go:171","msg":"trace[1413347256] transaction","detail":"{read_only:false; response_revision:25389; number_of_response:1; }","duration":"707.641912ms","start":"2026-10-09T06:11:50.519914Z","end":"2026-10-09T06:11:51.227556Z","steps":["trace[1413347256] 'process raft request' (duration: 706.926759ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:51.227646Z","caller":"traceutil/trace.go:171","msg":"trace[2042959420] transaction","detail":"{read_only:false; response_revision:25392; number_of_response:1; }","duration":"611.941182ms","start":"2026-10-09T06:11:50.615679Z","end":"2026-10-09T06:11:51.227621Z","steps":["trace[2042959420] 'process raft request' (duration: 611.652868ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:51.227741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.876477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T06:11:51.227801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:50.615668Z","time spent":"612.037444ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:11:51.227807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:50.519884Z","time spent":"707.801216ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:11:51.227834Z","caller":"traceutil/trace.go:171","msg":"trace[79592204] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:25392; }","duration":"322.02242ms","start":"2026-10-09T06:11:50.905792Z","end":"2026-10-09T06:11:51.227814Z","steps":["trace[79592204] 'agreement among raft nodes before linearized reading' (duration: 321.853347ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:51.227828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.227241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:11:51.227837Z","caller":"traceutil/trace.go:171","msg":"trace[688676404] transaction","detail":"{read_only:false; response_revision:25390; number_of_response:1; }","duration":"615.722644ms","start":"2026-10-09T06:11:50.612076Z","end":"2026-10-09T06:11:51.227799Z","steps":["trace[688676404] 'process raft request' (duration: 615.119913ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:51.227836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.998511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-09T06:11:51.227882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:50.905741Z","time spent":"322.125702ms","remote":"127.0.0.1:38196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true "} {"level":"info","ts":"2026-10-09T06:11:51.227906Z","caller":"traceutil/trace.go:171","msg":"trace[1677253975] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:25392; }","duration":"223.388594ms","start":"2026-10-09T06:11:51.004496Z","end":"2026-10-09T06:11:51.227885Z","steps":["trace[1677253975] 'agreement among raft nodes before linearized reading' (duration: 223.188381ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:51.22792Z","caller":"traceutil/trace.go:171","msg":"trace[1940472368] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25392; }","duration":"191.151194ms","start":"2026-10-09T06:11:51.036757Z","end":"2026-10-09T06:11:51.227908Z","steps":["trace[1940472368] 'agreement among raft nodes before linearized reading' (duration: 190.95752ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:51.227973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.895746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-10-09T06:11:51.228122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.941959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-10-09T06:11:51.228035Z","caller":"traceutil/trace.go:171","msg":"trace[331592448] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25392; }","duration":"240.987127ms","start":"2026-10-09T06:11:50.987032Z","end":"2026-10-09T06:11:51.228019Z","steps":["trace[331592448] 'agreement among raft nodes before linearized reading' (duration: 240.757133ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:51.228299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:50.612048Z","time spent":"615.864417ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:11:51.228372Z","caller":"traceutil/trace.go:171","msg":"trace[14726214] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:25392; }","duration":"854.01507ms","start":"2026-10-09T06:11:50.374126Z","end":"2026-10-09T06:11:51.228141Z","steps":["trace[14726214] 'agreement among raft nodes before linearized reading' (duration: 853.40602ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:51.228409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:11:50.374106Z","time spent":"854.292285ms","remote":"127.0.0.1:52858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1873,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" "} {"level":"info","ts":"2026-10-09T06:11:51.428387Z","caller":"traceutil/trace.go:171","msg":"trace[136854075] linearizableReadLoop","detail":"{readStateIndex:26230; appliedIndex:26229; }","duration":"193.638842ms","start":"2026-10-09T06:11:51.234728Z","end":"2026-10-09T06:11:51.428367Z","steps":["trace[136854075] 'read index received' (duration: 97.729558ms)","trace[136854075] 'applied index is now lower than readState.Index' (duration: 95.908384ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:11:51.428477Z","caller":"traceutil/trace.go:171","msg":"trace[749272159] transaction","detail":"{read_only:false; response_revision:25393; number_of_response:1; }","duration":"194.784044ms","start":"2026-10-09T06:11:51.233657Z","end":"2026-10-09T06:11:51.428441Z","steps":["trace[749272159] 'process raft request' (duration: 98.884731ms)","trace[749272159] 'compare' (duration: 95.71553ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:11:51.428633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.892657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19232"} {"level":"info","ts":"2026-10-09T06:11:51.428673Z","caller":"traceutil/trace.go:171","msg":"trace[160851284] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25393; }","duration":"193.957878ms","start":"2026-10-09T06:11:51.234703Z","end":"2026-10-09T06:11:51.428661Z","steps":["trace[160851284] 'agreement among raft nodes before linearized reading' (duration: 193.790704ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:51.42869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.830626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:11:51.428767Z","caller":"traceutil/trace.go:171","msg":"trace[824734859] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:25393; }","duration":"193.922127ms","start":"2026-10-09T06:11:51.234827Z","end":"2026-10-09T06:11:51.428749Z","steps":["trace[824734859] 'agreement among raft nodes before linearized reading' (duration: 193.792255ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:57.986978Z","caller":"traceutil/trace.go:171","msg":"trace[337953862] transaction","detail":"{read_only:false; response_revision:25517; number_of_response:1; }","duration":"117.794528ms","start":"2026-10-09T06:11:57.869157Z","end":"2026-10-09T06:11:57.986952Z","steps":["trace[337953862] 'process raft request' (duration: 117.560203ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:57.987415Z","caller":"traceutil/trace.go:171","msg":"trace[1015573830] linearizableReadLoop","detail":"{readStateIndex:26356; appliedIndex:26356; }","duration":"113.61661ms","start":"2026-10-09T06:11:57.873791Z","end":"2026-10-09T06:11:57.987407Z","steps":["trace[1015573830] 'read index received' (duration: 113.613089ms)","trace[1015573830] 'applied index is now lower than readState.Index' (duration: 3.021µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:11:57.987535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.758742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-09T06:11:57.987559Z","caller":"traceutil/trace.go:171","msg":"trace[1358456304] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25517; }","duration":"113.835313ms","start":"2026-10-09T06:11:57.873718Z","end":"2026-10-09T06:11:57.987553Z","steps":["trace[1358456304] 'agreement among raft nodes before linearized reading' (duration: 113.735201ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:58.127025Z","caller":"traceutil/trace.go:171","msg":"trace[1127784866] linearizableReadLoop","detail":"{readStateIndex:26357; appliedIndex:26356; }","duration":"139.570859ms","start":"2026-10-09T06:11:57.987435Z","end":"2026-10-09T06:11:58.127006Z","steps":["trace[1127784866] 'read index received' (duration: 139.461117ms)","trace[1127784866] 'applied index is now lower than readState.Index' (duration: 108.922µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:11:58.127042Z","caller":"traceutil/trace.go:171","msg":"trace[1449251639] transaction","detail":"{read_only:false; response_revision:25518; number_of_response:1; }","duration":"245.851189ms","start":"2026-10-09T06:11:57.881171Z","end":"2026-10-09T06:11:58.127022Z","steps":["trace[1449251639] 'process raft request' (duration: 245.717376ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:58.127298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.410797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-09T06:11:58.127376Z","caller":"traceutil/trace.go:171","msg":"trace[1962805097] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25518; }","duration":"249.528859ms","start":"2026-10-09T06:11:57.877827Z","end":"2026-10-09T06:11:58.127356Z","steps":["trace[1962805097] 'agreement among raft nodes before linearized reading' (duration: 249.304265ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:11:58.127432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.91138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-09T06:11:58.127882Z","caller":"traceutil/trace.go:171","msg":"trace[1709698143] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25518; }","duration":"218.3836ms","start":"2026-10-09T06:11:57.909424Z","end":"2026-10-09T06:11:58.127807Z","steps":["trace[1709698143] 'agreement among raft nodes before linearized reading' (duration: 217.617975ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:11:58.135678Z","caller":"traceutil/trace.go:171","msg":"trace[1279635733] transaction","detail":"{read_only:false; response_revision:25519; number_of_response:1; }","duration":"145.049363ms","start":"2026-10-09T06:11:57.99061Z","end":"2026-10-09T06:11:58.135659Z","steps":["trace[1279635733] 'process raft request' (duration: 144.947891ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:12:10.453499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.273709ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T06:12:10.453595Z","caller":"traceutil/trace.go:171","msg":"trace[1125822643] linearizableReadLoop","detail":"{readStateIndex:26462; appliedIndex:26461; }","duration":"244.601466ms","start":"2026-10-09T06:12:10.208977Z","end":"2026-10-09T06:12:10.453578Z","steps":["trace[1125822643] 'read index received' (duration: 109.103833ms)","trace[1125822643] 'applied index is now lower than readState.Index' (duration: 135.496513ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:12:10.453671Z","caller":"traceutil/trace.go:171","msg":"trace[26077118] transaction","detail":"{read_only:false; response_revision:25620; number_of_response:1; }","duration":"247.004421ms","start":"2026-10-09T06:12:10.206634Z","end":"2026-10-09T06:12:10.453639Z","steps":["trace[26077118] 'process raft request' (duration: 111.511748ms)","trace[26077118] 'compare' (duration: 134.949733ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:12:10.453759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.788269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-09T06:12:10.453814Z","caller":"traceutil/trace.go:171","msg":"trace[827751471] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25620; }","duration":"244.882761ms","start":"2026-10-09T06:12:10.208921Z","end":"2026-10-09T06:12:10.453803Z","steps":["trace[827751471] 'agreement among raft nodes before linearized reading' (duration: 244.737039ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:12:10.453914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.127888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-09T06:12:10.453997Z","caller":"traceutil/trace.go:171","msg":"trace[1464193978] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25620; }","duration":"243.25249ms","start":"2026-10-09T06:12:10.210726Z","end":"2026-10-09T06:12:10.453978Z","steps":["trace[1464193978] 'agreement among raft nodes before linearized reading' (duration: 242.993955ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:12:10.760013Z","caller":"traceutil/trace.go:171","msg":"trace[1847206465] transaction","detail":"{read_only:false; response_revision:25621; number_of_response:1; }","duration":"301.018312ms","start":"2026-10-09T06:12:10.458955Z","end":"2026-10-09T06:12:10.759973Z","steps":["trace[1847206465] 'process raft request' (duration: 267.875366ms)","trace[1847206465] 'compare' (duration: 32.871692ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:12:10.760205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:12:10.458934Z","time spent":"301.158915ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:12:10.760535Z","caller":"traceutil/trace.go:171","msg":"trace[1749204302] linearizableReadLoop","detail":"{readStateIndex:26464; appliedIndex:26462; }","duration":"291.757397ms","start":"2026-10-09T06:12:10.468753Z","end":"2026-10-09T06:12:10.760511Z","steps":["trace[1749204302] 'read index received' (duration: 258.09103ms)","trace[1749204302] 'applied index is now lower than readState.Index' (duration: 33.665137ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:12:10.760815Z","caller":"traceutil/trace.go:171","msg":"trace[1340350240] transaction","detail":"{read_only:false; response_revision:25622; number_of_response:1; }","duration":"301.536272ms","start":"2026-10-09T06:12:10.459266Z","end":"2026-10-09T06:12:10.760802Z","steps":["trace[1340350240] 'process raft request' (duration: 301.114654ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:12:10.760914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:12:10.459242Z","time spent":"301.604673ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:12:10.760912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.134264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T06:12:10.76098Z","caller":"traceutil/trace.go:171","msg":"trace[379829995] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:25622; }","duration":"292.253756ms","start":"2026-10-09T06:12:10.468709Z","end":"2026-10-09T06:12:10.760963Z","steps":["trace[379829995] 'agreement among raft nodes before linearized reading' (duration: 292.114264ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:12:11.076961Z","caller":"traceutil/trace.go:171","msg":"trace[331732575] transaction","detail":"{read_only:false; response_revision:25623; number_of_response:1; }","duration":"127.93081ms","start":"2026-10-09T06:12:10.948999Z","end":"2026-10-09T06:12:11.076929Z","steps":["trace[331732575] 'process raft request' (duration: 127.686035ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:12:16.214407Z","caller":"traceutil/trace.go:171","msg":"trace[1966806978] transaction","detail":"{read_only:false; response_revision:25662; number_of_response:1; }","duration":"103.477217ms","start":"2026-10-09T06:12:16.110902Z","end":"2026-10-09T06:12:16.214379Z","steps":["trace[1966806978] 'process raft request' (duration: 103.272943ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:12:16.272729Z","caller":"traceutil/trace.go:171","msg":"trace[1575102189] transaction","detail":"{read_only:false; response_revision:25663; number_of_response:1; }","duration":"128.99719ms","start":"2026-10-09T06:12:16.143714Z","end":"2026-10-09T06:12:16.272711Z","steps":["trace[1575102189] 'process raft request' (duration: 124.371293ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:12:16.448626Z","caller":"traceutil/trace.go:171","msg":"trace[1242140819] transaction","detail":"{read_only:false; response_revision:25664; number_of_response:1; }","duration":"232.285473ms","start":"2026-10-09T06:12:16.216316Z","end":"2026-10-09T06:12:16.448602Z","steps":["trace[1242140819] 'process raft request' (duration: 199.549224ms)","trace[1242140819] 'compare' (duration: 32.22858ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:12:16.660573Z","caller":"traceutil/trace.go:171","msg":"trace[932064223] linearizableReadLoop","detail":"{readStateIndex:26508; appliedIndex:26507; }","duration":"164.054543ms","start":"2026-10-09T06:12:16.496484Z","end":"2026-10-09T06:12:16.660538Z","steps":["trace[932064223] 'read index received' (duration: 88.156037ms)","trace[932064223] 'applied index is now lower than readState.Index' (duration: 75.896886ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:12:16.66061Z","caller":"traceutil/trace.go:171","msg":"trace[764401122] transaction","detail":"{read_only:false; response_revision:25665; number_of_response:1; }","duration":"182.722406ms","start":"2026-10-09T06:12:16.477861Z","end":"2026-10-09T06:12:16.660584Z","steps":["trace[764401122] 'process raft request' (duration: 106.948303ms)","trace[764401122] 'compare' (duration: 75.452106ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:12:16.660839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.328419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-10-09T06:12:16.6609Z","caller":"traceutil/trace.go:171","msg":"trace[1809139862] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25665; }","duration":"164.43474ms","start":"2026-10-09T06:12:16.496448Z","end":"2026-10-09T06:12:16.660883Z","steps":["trace[1809139862] 'agreement among raft nodes before linearized reading' (duration: 164.202146ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:12:16.905898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.370147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T06:12:16.906002Z","caller":"traceutil/trace.go:171","msg":"trace[1797935106] linearizableReadLoop","detail":"{readStateIndex:26509; appliedIndex:26508; }","duration":"123.065737ms","start":"2026-10-09T06:12:16.782921Z","end":"2026-10-09T06:12:16.905986Z","steps":["trace[1797935106] 'read index received' (duration: 3.406154ms)","trace[1797935106] 'applied index is now lower than readState.Index' (duration: 119.658443ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:12:16.906318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.390334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-09T06:12:16.906355Z","caller":"traceutil/trace.go:171","msg":"trace[2109756372] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25666; }","duration":"123.476695ms","start":"2026-10-09T06:12:16.782869Z","end":"2026-10-09T06:12:16.906346Z","steps":["trace[2109756372] 'agreement among raft nodes before linearized reading' (duration: 123.330562ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:12:16.906336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.088876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:12:16.906396Z","caller":"traceutil/trace.go:171","msg":"trace[721486315] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25666; }","duration":"106.196339ms","start":"2026-10-09T06:12:16.800183Z","end":"2026-10-09T06:12:16.90638Z","steps":["trace[721486315] 'agreement among raft nodes before linearized reading' (duration: 106.099097ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:12:16.906426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.882936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-09T06:12:16.906477Z","caller":"traceutil/trace.go:171","msg":"trace[1613346069] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25666; }","duration":"120.979488ms","start":"2026-10-09T06:12:16.78548Z","end":"2026-10-09T06:12:16.90646Z","steps":["trace[1613346069] 'agreement among raft nodes before linearized reading' (duration: 120.785284ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:12:16.906151Z","caller":"traceutil/trace.go:171","msg":"trace[254595898] transaction","detail":"{read_only:false; response_revision:25666; number_of_response:1; }","duration":"196.70651ms","start":"2026-10-09T06:12:16.709333Z","end":"2026-10-09T06:12:16.90604Z","steps":["trace[254595898] 'process raft request' (duration: 77.100298ms)","trace[254595898] 'compare' (duration: 119.228415ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:12:17.068981Z","caller":"traceutil/trace.go:171","msg":"trace[668593823] linearizableReadLoop","detail":"{readStateIndex:26511; appliedIndex:26509; }","duration":"157.648311ms","start":"2026-10-09T06:12:16.911315Z","end":"2026-10-09T06:12:17.068963Z","steps":["trace[668593823] 'read index received' (duration: 123.378823ms)","trace[668593823] 'applied index is now lower than readState.Index' (duration: 34.268728ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:12:17.069005Z","caller":"traceutil/trace.go:171","msg":"trace[516041060] transaction","detail":"{read_only:false; response_revision:25667; number_of_response:1; }","duration":"159.12111ms","start":"2026-10-09T06:12:16.909857Z","end":"2026-10-09T06:12:17.068978Z","steps":["trace[516041060] 'process raft request' (duration: 124.744659ms)","trace[516041060] 'compare' (duration: 34.219038ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:12:17.069096Z","caller":"traceutil/trace.go:171","msg":"trace[1298701682] transaction","detail":"{read_only:false; response_revision:25668; number_of_response:1; }","duration":"159.17881ms","start":"2026-10-09T06:12:16.90989Z","end":"2026-10-09T06:12:17.069069Z","steps":["trace[1298701682] 'process raft request' (duration: 159.036928ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:12:17.069137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.786114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:12:17.069159Z","caller":"traceutil/trace.go:171","msg":"trace[800798900] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25668; }","duration":"157.863366ms","start":"2026-10-09T06:12:16.911289Z","end":"2026-10-09T06:12:17.069152Z","steps":["trace[800798900] 'agreement among raft nodes before linearized reading' (duration: 157.773104ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:12:23.648451Z","caller":"traceutil/trace.go:171","msg":"trace[1701512455] transaction","detail":"{read_only:false; response_revision:25732; number_of_response:1; }","duration":"106.099946ms","start":"2026-10-09T06:12:23.542322Z","end":"2026-10-09T06:12:23.648422Z","steps":["trace[1701512455] 'process raft request' (duration: 22.851901ms)","trace[1701512455] 'compare' (duration: 83.017411ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:12:25.906927Z","caller":"traceutil/trace.go:171","msg":"trace[247993439] transaction","detail":"{read_only:false; response_revision:25747; number_of_response:1; }","duration":"134.073616ms","start":"2026-10-09T06:12:25.772831Z","end":"2026-10-09T06:12:25.906905Z","steps":["trace[247993439] 'process raft request' (duration: 94.034328ms)","trace[247993439] 'compare' (duration: 39.946716ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:12:28.034656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.19393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-09T06:12:28.034744Z","caller":"traceutil/trace.go:171","msg":"trace[1618964223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25761; }","duration":"123.324922ms","start":"2026-10-09T06:12:27.911396Z","end":"2026-10-09T06:12:28.034721Z","steps":["trace[1618964223] 'range keys from in-memory index tree' (duration: 122.998906ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:12:28.170835Z","caller":"traceutil/trace.go:171","msg":"trace[275146286] transaction","detail":"{read_only:false; response_revision:25762; number_of_response:1; }","duration":"130.581839ms","start":"2026-10-09T06:12:28.040226Z","end":"2026-10-09T06:12:28.170808Z","steps":["trace[275146286] 'process raft request' (duration: 130.398376ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:12:29.559246Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22327} {"level":"warn","ts":"2026-10-09T06:12:29.818546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.751444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:12:29.818625Z","caller":"traceutil/trace.go:171","msg":"trace[2050817695] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25775; }","duration":"147.889826ms","start":"2026-10-09T06:12:29.670717Z","end":"2026-10-09T06:12:29.818607Z","steps":["trace[2050817695] 'range keys from in-memory index tree' (duration: 147.627953ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:12:30.218451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.541869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-09T06:12:30.218525Z","caller":"traceutil/trace.go:171","msg":"trace[244024978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25775; }","duration":"378.654761ms","start":"2026-10-09T06:12:29.839854Z","end":"2026-10-09T06:12:30.218508Z","steps":["trace[244024978] 'range keys from in-memory index tree' (duration: 378.319614ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:12:30.218565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:12:29.839842Z","time spent":"378.713991ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-09T06:12:30.218803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.735179ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:12:30.218938Z","caller":"traceutil/trace.go:171","msg":"trace[711882091] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25775; }","duration":"371.909713ms","start":"2026-10-09T06:12:29.847008Z","end":"2026-10-09T06:12:30.218918Z","steps":["trace[711882091] 'range keys from in-memory index tree' (duration: 371.721699ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:12:30.432055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.235152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-10-09T06:12:30.432139Z","caller":"traceutil/trace.go:171","msg":"trace[1234130995] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25775; }","duration":"213.383105ms","start":"2026-10-09T06:12:30.218734Z","end":"2026-10-09T06:12:30.432117Z","steps":["trace[1234130995] 'range keys from in-memory index tree' (duration: 213.063679ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:12:30.432584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.089094ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T06:12:30.432686Z","caller":"traceutil/trace.go:171","msg":"trace[1018432977] transaction","detail":"{read_only:false; response_revision:25776; number_of_response:1; }","duration":"210.044362ms","start":"2026-10-09T06:12:30.222623Z","end":"2026-10-09T06:12:30.432667Z","steps":["trace[1018432977] 'process raft request' (duration: 65.768564ms)","trace[1018432977] 'compare' (duration: 143.963142ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:12:30.632375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.399656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T06:12:30.632532Z","caller":"traceutil/trace.go:171","msg":"trace[1978643079] transaction","detail":"{read_only:false; response_revision:25777; number_of_response:1; }","duration":"409.559685ms","start":"2026-10-09T06:12:30.22293Z","end":"2026-10-09T06:12:30.632489Z","steps":["trace[1978643079] 'process raft request' (duration: 259.954736ms)","trace[1978643079] 'compare' (duration: 107.621665ms)","trace[1978643079] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:419; } (duration: 41.635187ms)"],"step_count":3} {"level":"info","ts":"2026-10-09T06:12:30.632634Z","caller":"traceutil/trace.go:171","msg":"trace[2002765363] linearizableReadLoop","detail":"{readStateIndex:26625; appliedIndex:26624; }","duration":"263.793818ms","start":"2026-10-09T06:12:30.368818Z","end":"2026-10-09T06:12:30.632612Z","steps":["trace[2002765363] 'read index received' (duration: 114.077517ms)","trace[2002765363] 'applied index is now lower than readState.Index' (duration: 149.714111ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:12:30.632695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:12:30.222908Z","time spent":"409.701808ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:12:30.632799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.975913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T06:12:30.632839Z","caller":"traceutil/trace.go:171","msg":"trace[843393061] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25777; }","duration":"264.081715ms","start":"2026-10-09T06:12:30.368745Z","end":"2026-10-09T06:12:30.632827Z","steps":["trace[843393061] 'agreement among raft nodes before linearized reading' (duration: 263.978803ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:12:30.633412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.267668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T06:12:30.633463Z","caller":"traceutil/trace.go:171","msg":"trace[20032248] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:25780; }","duration":"136.346319ms","start":"2026-10-09T06:12:30.497103Z","end":"2026-10-09T06:12:30.633449Z","steps":["trace[20032248] 'agreement among raft nodes before linearized reading' (duration: 136.259477ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:12:30.633623Z","caller":"traceutil/trace.go:171","msg":"trace[2124411920] transaction","detail":"{read_only:false; response_revision:25779; number_of_response:1; }","duration":"194.191332ms","start":"2026-10-09T06:12:30.439403Z","end":"2026-10-09T06:12:30.633594Z","steps":["trace[2124411920] 'process raft request' (duration: 193.871756ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:12:30.63362Z","caller":"traceutil/trace.go:171","msg":"trace[742531769] transaction","detail":"{read_only:false; response_revision:25778; number_of_response:1; }","duration":"196.653079ms","start":"2026-10-09T06:12:30.436926Z","end":"2026-10-09T06:12:30.633579Z","steps":["trace[742531769] 'process raft request' (duration: 195.627319ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:12:30.695708Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22327,"took":"1.132673669s","hash":3825859321,"current-db-size-bytes":56070144,"current-db-size":"56 MB","current-db-size-in-use-bytes":45395968,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-09T06:12:30.695806Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3825859321,"revision":22327,"compact-revision":20022} {"level":"info","ts":"2026-10-09T06:12:46.873948Z","caller":"traceutil/trace.go:171","msg":"trace[1870675726] transaction","detail":"{read_only:false; response_revision:25894; number_of_response:1; }","duration":"114.616188ms","start":"2026-10-09T06:12:46.759306Z","end":"2026-10-09T06:12:46.873922Z","steps":["trace[1870675726] 'process raft request' (duration: 114.436915ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:12:47.171674Z","caller":"traceutil/trace.go:171","msg":"trace[1039810806] linearizableReadLoop","detail":"{readStateIndex:26747; appliedIndex:26744; }","duration":"357.248176ms","start":"2026-10-09T06:12:46.814402Z","end":"2026-10-09T06:12:47.171651Z","steps":["trace[1039810806] 'read index received' (duration: 59.388534ms)","trace[1039810806] 'applied index is now lower than readState.Index' (duration: 297.858572ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:12:47.17175Z","caller":"traceutil/trace.go:171","msg":"trace[1848760353] transaction","detail":"{read_only:false; response_revision:25895; number_of_response:1; }","duration":"410.822609ms","start":"2026-10-09T06:12:46.760896Z","end":"2026-10-09T06:12:47.171719Z","steps":["trace[1848760353] 'process raft request' (duration: 369.08093ms)","trace[1848760353] 'compare' (duration: 41.450333ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:12:47.17191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:12:46.760883Z","time spent":"410.951851ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:12:47.171898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.47668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T06:12:47.171952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.822254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-09T06:12:47.171992Z","caller":"traceutil/trace.go:171","msg":"trace[1103512004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25896; }","duration":"295.904136ms","start":"2026-10-09T06:12:46.876076Z","end":"2026-10-09T06:12:47.17198Z","steps":["trace[1103512004] 'agreement among raft nodes before linearized reading' (duration: 295.725313ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:12:47.171991Z","caller":"traceutil/trace.go:171","msg":"trace[1654613081] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:25896; }","duration":"357.589562ms","start":"2026-10-09T06:12:46.814359Z","end":"2026-10-09T06:12:47.171949Z","steps":["trace[1654613081] 'agreement among raft nodes before linearized reading' (duration: 357.429729ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:12:47.172034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:12:46.814342Z","time spent":"357.681544ms","remote":"127.0.0.1:60634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true "} {"level":"info","ts":"2026-10-09T06:12:47.172175Z","caller":"traceutil/trace.go:171","msg":"trace[415793824] transaction","detail":"{read_only:false; response_revision:25896; number_of_response:1; }","duration":"396.214904ms","start":"2026-10-09T06:12:46.775948Z","end":"2026-10-09T06:12:47.172163Z","steps":["trace[415793824] 'process raft request' (duration: 395.637252ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:12:47.172233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:12:46.775923Z","time spent":"396.276233ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:12:47.172594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.868785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-09T06:12:47.172636Z","caller":"traceutil/trace.go:171","msg":"trace[1545837216] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25896; }","duration":"278.930865ms","start":"2026-10-09T06:12:46.893696Z","end":"2026-10-09T06:12:47.172626Z","steps":["trace[1545837216] 'agreement among raft nodes before linearized reading' (duration: 278.832854ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:12:47.172661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.25975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-10-09T06:12:47.172742Z","caller":"traceutil/trace.go:171","msg":"trace[2053521822] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25896; }","duration":"252.395323ms","start":"2026-10-09T06:12:46.920324Z","end":"2026-10-09T06:12:47.172719Z","steps":["trace[2053521822] 'agreement among raft nodes before linearized reading' (duration: 252.129318ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:12:47.33639Z","caller":"traceutil/trace.go:171","msg":"trace[257387611] transaction","detail":"{read_only:false; response_revision:25898; number_of_response:1; }","duration":"158.402665ms","start":"2026-10-09T06:12:47.177962Z","end":"2026-10-09T06:12:47.336365Z","steps":["trace[257387611] 'process raft request' (duration: 153.83767ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:12:47.337076Z","caller":"traceutil/trace.go:171","msg":"trace[663639570] transaction","detail":"{read_only:false; response_revision:25899; number_of_response:1; }","duration":"158.928975ms","start":"2026-10-09T06:12:47.178091Z","end":"2026-10-09T06:12:47.33702Z","steps":["trace[663639570] 'process raft request' (duration: 158.211751ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:12:47.33712Z","caller":"traceutil/trace.go:171","msg":"trace[383035545] linearizableReadLoop","detail":"{readStateIndex:26750; appliedIndex:26747; }","duration":"158.67921ms","start":"2026-10-09T06:12:47.178424Z","end":"2026-10-09T06:12:47.337103Z","steps":["trace[383035545] 'read index received' (duration: 17.116164ms)","trace[383035545] 'applied index is now lower than readState.Index' (duration: 141.561156ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:12:47.337305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.862854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:12:47.337348Z","caller":"traceutil/trace.go:171","msg":"trace[104673325] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25900; }","duration":"158.943705ms","start":"2026-10-09T06:12:47.178391Z","end":"2026-10-09T06:12:47.337335Z","steps":["trace[104673325] 'agreement among raft nodes before linearized reading' (duration: 158.807572ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:12:47.337479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.093923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-09T06:12:47.337528Z","caller":"traceutil/trace.go:171","msg":"trace[237065562] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25900; }","duration":"108.182545ms","start":"2026-10-09T06:12:47.229334Z","end":"2026-10-09T06:12:47.337516Z","steps":["trace[237065562] 'agreement among raft nodes before linearized reading' (duration: 108.055633ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:12:47.337814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.610543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-09T06:12:47.337892Z","caller":"traceutil/trace.go:171","msg":"trace[503423854] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25900; }","duration":"109.736935ms","start":"2026-10-09T06:12:47.228143Z","end":"2026-10-09T06:12:47.33788Z","steps":["trace[503423854] 'agreement among raft nodes before linearized reading' (duration: 109.161994ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:12:47.453862Z","caller":"traceutil/trace.go:171","msg":"trace[301840355] transaction","detail":"{read_only:false; response_revision:25901; number_of_response:1; }","duration":"111.515799ms","start":"2026-10-09T06:12:47.342317Z","end":"2026-10-09T06:12:47.453833Z","steps":["trace[301840355] 'process raft request' (duration: 111.265044ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:12:47.456729Z","caller":"traceutil/trace.go:171","msg":"trace[323886477] linearizableReadLoop","detail":"{readStateIndex:26753; appliedIndex:26751; }","duration":"110.284965ms","start":"2026-10-09T06:12:47.346414Z","end":"2026-10-09T06:12:47.456699Z","steps":["trace[323886477] 'read index received' (duration: 107.183556ms)","trace[323886477] 'applied index is now lower than readState.Index' (duration: 3.100079ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:12:47.45694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.501599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:12:47.456983Z","caller":"traceutil/trace.go:171","msg":"trace[350257491] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25902; }","duration":"110.591581ms","start":"2026-10-09T06:12:47.34638Z","end":"2026-10-09T06:12:47.456971Z","steps":["trace[350257491] 'agreement among raft nodes before linearized reading' (duration: 110.469329ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:12:47.457367Z","caller":"traceutil/trace.go:171","msg":"trace[1589968821] transaction","detail":"{read_only:false; response_revision:25902; number_of_response:1; }","duration":"114.79772ms","start":"2026-10-09T06:12:47.342556Z","end":"2026-10-09T06:12:47.457354Z","steps":["trace[1589968821] 'process raft request' (duration: 113.988005ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:13:04.890973Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006912.wal"} {"level":"info","ts":"2026-10-09T06:13:08.771912Z","caller":"traceutil/trace.go:171","msg":"trace[1911357403] transaction","detail":"{read_only:false; response_revision:26210; number_of_response:1; }","duration":"121.962497ms","start":"2026-10-09T06:13:08.649917Z","end":"2026-10-09T06:13:08.77188Z","steps":["trace[1911357403] 'process raft request' (duration: 121.734882ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:13:09.097777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.407558ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-09T06:13:09.098131Z","caller":"traceutil/trace.go:171","msg":"trace[1688375641] linearizableReadLoop","detail":"{readStateIndex:27068; appliedIndex:27066; }","duration":"142.383593ms","start":"2026-10-09T06:13:08.955725Z","end":"2026-10-09T06:13:09.098109Z","steps":["trace[1688375641] 'read index received' (duration: 54.841987ms)","trace[1688375641] 'applied index is now lower than readState.Index' (duration: 87.540066ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:13:09.09825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.514015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T06:13:09.098287Z","caller":"traceutil/trace.go:171","msg":"trace[1184606143] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26211; }","duration":"142.607407ms","start":"2026-10-09T06:13:08.955668Z","end":"2026-10-09T06:13:09.098275Z","steps":["trace[1184606143] 'agreement among raft nodes before linearized reading' (duration: 142.507275ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:13:09.098265Z","caller":"traceutil/trace.go:171","msg":"trace[739189649] transaction","detail":"{read_only:false; response_revision:26211; number_of_response:1; }","duration":"154.864029ms","start":"2026-10-09T06:13:08.943351Z","end":"2026-10-09T06:13:09.098215Z","steps":["trace[739189649] 'process raft request' (duration: 154.571453ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:13:09.358422Z","caller":"traceutil/trace.go:171","msg":"trace[518244969] transaction","detail":"{read_only:false; response_revision:26212; number_of_response:1; }","duration":"103.935236ms","start":"2026-10-09T06:13:09.254449Z","end":"2026-10-09T06:13:09.358384Z","steps":["trace[518244969] 'process raft request' (duration: 103.63935ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:13:09.988149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.001878ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:13:09.988271Z","caller":"traceutil/trace.go:171","msg":"trace[1641834518] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26215; }","duration":"141.145011ms","start":"2026-10-09T06:13:09.847107Z","end":"2026-10-09T06:13:09.988252Z","steps":["trace[1641834518] 'range keys from in-memory index tree' (duration: 140.984198ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:13:09.988934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.11663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T06:13:09.989005Z","caller":"traceutil/trace.go:171","msg":"trace[32492475] linearizableReadLoop","detail":"{readStateIndex:27073; appliedIndex:27072; }","duration":"427.930703ms","start":"2026-10-09T06:13:09.561064Z","end":"2026-10-09T06:13:09.988994Z","steps":["trace[32492475] 'read index received' (duration: 250.70921ms)","trace[32492475] 'applied index is now lower than readState.Index' (duration: 177.220563ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T06:13:09.989073Z","caller":"traceutil/trace.go:171","msg":"trace[1081633282] transaction","detail":"{read_only:false; response_revision:26216; number_of_response:1; }","duration":"432.376018ms","start":"2026-10-09T06:13:09.556655Z","end":"2026-10-09T06:13:09.989031Z","steps":["trace[1081633282] 'process raft request' (duration: 255.118194ms)","trace[1081633282] 'compare' (duration: 176.188513ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:13:09.98915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.093647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-09T06:13:09.98918Z","caller":"traceutil/trace.go:171","msg":"trace[332095876] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26216; }","duration":"428.155547ms","start":"2026-10-09T06:13:09.561014Z","end":"2026-10-09T06:13:09.989169Z","steps":["trace[332095876] 'agreement among raft nodes before linearized reading' (duration: 428.035565ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:13:09.989208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:13:09.560995Z","time spent":"428.206249ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-09T06:13:09.989273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:13:09.556639Z","time spent":"432.50674ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:13:10.182087Z","caller":"traceutil/trace.go:171","msg":"trace[1315011390] transaction","detail":"{read_only:false; response_revision:26217; number_of_response:1; }","duration":"575.995833ms","start":"2026-10-09T06:13:09.606074Z","end":"2026-10-09T06:13:10.18207Z","steps":["trace[1315011390] 'process raft request' (duration: 567.662285ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:13:10.182201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:13:09.606042Z","time spent":"576.097385ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:13:10.182393Z","caller":"traceutil/trace.go:171","msg":"trace[1266371698] transaction","detail":"{read_only:false; response_revision:26218; number_of_response:1; }","duration":"549.362729ms","start":"2026-10-09T06:13:09.633002Z","end":"2026-10-09T06:13:10.182365Z","steps":["trace[1266371698] 'process raft request' (duration: 549.042383ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:13:10.182507Z","caller":"traceutil/trace.go:171","msg":"trace[860631322] transaction","detail":"{read_only:false; response_revision:26219; number_of_response:1; }","duration":"548.387001ms","start":"2026-10-09T06:13:09.634114Z","end":"2026-10-09T06:13:10.182501Z","steps":["trace[860631322] 'process raft request' (duration: 548.168087ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:13:10.182523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:13:09.632985Z","time spent":"549.465091ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T06:13:10.182542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:13:09.634097Z","time spent":"548.424931ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:13:10.18267Z","caller":"traceutil/trace.go:171","msg":"trace[2120548813] linearizableReadLoop","detail":"{readStateIndex:27077; appliedIndex:27073; }","duration":"193.640451ms","start":"2026-10-09T06:13:09.989022Z","end":"2026-10-09T06:13:10.182663Z","steps":["trace[2120548813] 'read index received' (duration: 184.714592ms)","trace[2120548813] 'applied index is now lower than readState.Index' (duration: 8.925209ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T06:13:10.182712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.252868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:13:10.182725Z","caller":"traceutil/trace.go:171","msg":"trace[1133957982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26220; }","duration":"558.310939ms","start":"2026-10-09T06:13:09.624411Z","end":"2026-10-09T06:13:10.182721Z","steps":["trace[1133957982] 'agreement among raft nodes before linearized reading' (duration: 558.271618ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:13:10.18274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:13:09.624385Z","time spent":"558.352349ms","remote":"127.0.0.1:52710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-09T06:13:10.182891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.712557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T06:13:10.18289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.034434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:13:10.182927Z","caller":"traceutil/trace.go:171","msg":"trace[818116206] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:26220; }","duration":"492.116826ms","start":"2026-10-09T06:13:09.690801Z","end":"2026-10-09T06:13:10.182917Z","steps":["trace[818116206] 'agreement among raft nodes before linearized reading' (duration: 492.053736ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:13:10.182955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:13:09.690779Z","time spent":"492.169988ms","remote":"127.0.0.1:52924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-db-sync\" "} {"level":"warn","ts":"2026-10-09T06:13:10.182965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.0153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T06:13:10.182978Z","caller":"traceutil/trace.go:171","msg":"trace[1294950865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26220; }","duration":"505.02815ms","start":"2026-10-09T06:13:09.677946Z","end":"2026-10-09T06:13:10.182974Z","steps":["trace[1294950865] 'agreement among raft nodes before linearized reading' (duration: 504.967459ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:13:10.182989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:13:09.677914Z","time spent":"505.072311ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-09T06:13:10.183051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.122614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:13:10.183062Z","caller":"traceutil/trace.go:171","msg":"trace[1697613335] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26220; }","duration":"512.158386ms","start":"2026-10-09T06:13:09.670899Z","end":"2026-10-09T06:13:10.183058Z","steps":["trace[1697613335] 'agreement among raft nodes before linearized reading' (duration: 512.137415ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:13:10.183074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:13:09.670888Z","time spent":"512.183946ms","remote":"127.0.0.1:52712","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-10-09T06:13:10.183081Z","caller":"traceutil/trace.go:171","msg":"trace[423506233] transaction","detail":"{read_only:false; response_revision:26220; number_of_response:1; }","duration":"548.017644ms","start":"2026-10-09T06:13:09.635053Z","end":"2026-10-09T06:13:10.183071Z","steps":["trace[423506233] 'process raft request' (duration: 547.416413ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:13:10.183139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:13:09.635033Z","time spent":"548.069635ms","remote":"127.0.0.1:52964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T06:13:10.182904Z","caller":"traceutil/trace.go:171","msg":"trace[1680014080] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:26220; }","duration":"492.744729ms","start":"2026-10-09T06:13:09.690155Z","end":"2026-10-09T06:13:10.1829Z","steps":["trace[1680014080] 'agreement among raft nodes before linearized reading' (duration: 492.719558ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:13:10.183266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:13:09.690145Z","time spent":"493.115865ms","remote":"127.0.0.1:52924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"warn","ts":"2026-10-09T06:13:10.183343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.971181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:13:10.183358Z","caller":"traceutil/trace.go:171","msg":"trace[1121877162] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:26220; }","duration":"412.037661ms","start":"2026-10-09T06:13:09.771314Z","end":"2026-10-09T06:13:10.183352Z","steps":["trace[1121877162] 'agreement among raft nodes before linearized reading' (duration: 412.010661ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:13:10.18337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:13:09.771282Z","time spent":"412.083663ms","remote":"127.0.0.1:33662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-10-09T06:13:10.183434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.722439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:13:10.183463Z","caller":"traceutil/trace.go:171","msg":"trace[425545881] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26220; }","duration":"491.768669ms","start":"2026-10-09T06:13:09.691686Z","end":"2026-10-09T06:13:10.183455Z","steps":["trace[425545881] 'agreement among raft nodes before linearized reading' (duration: 491.723278ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:13:10.183489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:13:09.691678Z","time spent":"491.80502ms","remote":"127.0.0.1:52924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-rabbit-init\" "} {"level":"warn","ts":"2026-10-09T06:13:10.183539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.876773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T06:13:10.183553Z","caller":"traceutil/trace.go:171","msg":"trace[1575191300] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26220; }","duration":"490.907783ms","start":"2026-10-09T06:13:09.692641Z","end":"2026-10-09T06:13:10.183548Z","steps":["trace[1575191300] 'agreement among raft nodes before linearized reading' (duration: 490.881963ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T06:13:10.183566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T06:13:09.692632Z","time spent":"490.931463ms","remote":"127.0.0.1:52924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"info","ts":"2026-10-09T06:13:10.186095Z","caller":"traceutil/trace.go:171","msg":"trace[1937511302] transaction","detail":"{read_only:false; response_revision:26221; number_of_response:1; }","duration":"189.732437ms","start":"2026-10-09T06:13:09.996346Z","end":"2026-10-09T06:13:10.186079Z","steps":["trace[1937511302] 'process raft request' (duration: 189.636795ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T06:17:29.565807Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25775} {"level":"info","ts":"2026-10-09T06:17:29.742658Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25775,"took":"172.068006ms","hash":2298306230,"current-db-size-bytes":59031552,"current-db-size":"59 MB","current-db-size-in-use-bytes":36212736,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-09T06:17:29.742755Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2298306230,"revision":25775,"compact-revision":22327} {"level":"info","ts":"2026-10-09T06:18:26.59576Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e2d037ebe7b91690","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T06:18:26.602028Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-09T06:18:26.602264Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-09T06:22:29.578871Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28652} {"level":"info","ts":"2026-10-09T06:22:29.721869Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28652,"took":"138.736953ms","hash":291745020,"current-db-size-bytes":59031552,"current-db-size":"59 MB","current-db-size-in-use-bytes":31084544,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-09T06:22:29.721938Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":291745020,"revision":28652,"compact-revision":25775} {"level":"info","ts":"2026-10-09T06:27:29.585463Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30873} {"level":"info","ts":"2026-10-09T06:27:29.727765Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30873,"took":"138.344111ms","hash":3130861505,"current-db-size-bytes":59031552,"current-db-size":"59 MB","current-db-size-in-use-bytes":30625792,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-09T06:27:29.727846Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3130861505,"revision":30873,"compact-revision":28652} {"level":"info","ts":"2026-10-09T06:32:29.593796Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33132} {"level":"info","ts":"2026-10-09T06:32:29.756631Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33132,"took":"158.119901ms","hash":1275016080,"current-db-size-bytes":59031552,"current-db-size":"59 MB","current-db-size-in-use-bytes":30851072,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-09T06:32:29.756728Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1275016080,"revision":33132,"compact-revision":30873} {"level":"info","ts":"2026-10-09T06:37:29.602169Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35428} {"level":"info","ts":"2026-10-09T06:37:29.757475Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35428,"took":"150.877645ms","hash":3540300491,"current-db-size-bytes":59031552,"current-db-size":"59 MB","current-db-size-in-use-bytes":31612928,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T06:37:29.757555Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3540300491,"revision":35428,"compact-revision":33132} {"level":"info","ts":"2026-10-09T06:39:58.738948Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e2d037ebe7b91690","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T06:39:58.744011Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-09T06:39:58.744238Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-09T06:42:29.60938Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37651} {"level":"info","ts":"2026-10-09T06:42:29.740256Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37651,"took":"126.83092ms","hash":1388576795,"current-db-size-bytes":59031552,"current-db-size":"59 MB","current-db-size-in-use-bytes":31547392,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T06:42:29.740326Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1388576795,"revision":37651,"compact-revision":35428} {"level":"info","ts":"2026-10-09T06:47:29.625854Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39928} {"level":"info","ts":"2026-10-09T06:47:29.764596Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39928,"took":"133.592028ms","hash":311586060,"current-db-size-bytes":59031552,"current-db-size":"59 MB","current-db-size-in-use-bytes":31748096,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T06:47:29.764683Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":311586060,"revision":39928,"compact-revision":37651}