{"level":"warn","ts":"2026-10-16T00:06:01.340366Z","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-16T00:06:01.340469Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.156:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.156:2380","--initial-cluster=instance=https://162.253.55.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.156: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-16T00:06:01.340541Z","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-16T00:06:01.340575Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.156:2380"]} {"level":"info","ts":"2026-10-16T00:06:01.3406Z","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-16T00:06:01.341314Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.156:2379"]} {"level":"info","ts":"2026-10-16T00:06:01.341406Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.156:2380"],"listen-peer-urls":["https://162.253.55.156:2380"],"advertise-client-urls":["https://162.253.55.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.156: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-16T00:06:01.344574Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.877324ms"} {"level":"info","ts":"2026-10-16T00:06:01.349351Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"40fb1eca294b6a5a","cluster-id":"17c6999805fcc0eb"} {"level":"info","ts":"2026-10-16T00:06:01.349428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a switched to configuration voters=()"} {"level":"info","ts":"2026-10-16T00:06:01.34945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became follower at term 0"} {"level":"info","ts":"2026-10-16T00:06:01.349463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 40fb1eca294b6a5a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-16T00:06:01.349478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became follower at term 1"} {"level":"info","ts":"2026-10-16T00:06:01.349518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a switched to configuration voters=(4682370091206797914)"} {"level":"warn","ts":"2026-10-16T00:06:01.361363Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-16T00:06:01.362975Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-16T00:06:01.364503Z","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-16T00:06:01.366871Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"40fb1eca294b6a5a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-16T00:06:01.36753Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"40fb1eca294b6a5a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-16T00:06:01.367527Z","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-16T00:06:01.36782Z","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-16T00:06:01.369155Z","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-16T00:06:01.369389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a switched to configuration voters=(4682370091206797914)"} {"level":"info","ts":"2026-10-16T00:06:01.369532Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"17c6999805fcc0eb","local-member-id":"40fb1eca294b6a5a","added-peer-id":"40fb1eca294b6a5a","added-peer-peer-urls":["https://162.253.55.156:2380"]} {"level":"info","ts":"2026-10-16T00:06:01.37244Z","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-16T00:06:01.372531Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.156:2380"} {"level":"info","ts":"2026-10-16T00:06:01.372598Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.156:2380"} {"level":"info","ts":"2026-10-16T00:06:01.372888Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"40fb1eca294b6a5a","initial-advertise-peer-urls":["https://162.253.55.156:2380"],"listen-peer-urls":["https://162.253.55.156:2380"],"advertise-client-urls":["https://162.253.55.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-16T00:06:01.372965Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-16T00:06:01.450129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a is starting a new election at term 1"} {"level":"info","ts":"2026-10-16T00:06:01.450172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became pre-candidate at term 1"} {"level":"info","ts":"2026-10-16T00:06:01.450214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a received MsgPreVoteResp from 40fb1eca294b6a5a at term 1"} {"level":"info","ts":"2026-10-16T00:06:01.450614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became candidate at term 2"} {"level":"info","ts":"2026-10-16T00:06:01.450627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a received MsgVoteResp from 40fb1eca294b6a5a at term 2"} {"level":"info","ts":"2026-10-16T00:06:01.450643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became leader at term 2"} {"level":"info","ts":"2026-10-16T00:06:01.450754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 40fb1eca294b6a5a elected leader 40fb1eca294b6a5a at term 2"} {"level":"info","ts":"2026-10-16T00:06:01.452062Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:06:01.453712Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"17c6999805fcc0eb","local-member-id":"40fb1eca294b6a5a","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:06:01.453881Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:06:01.453795Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"40fb1eca294b6a5a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.156:2379]}","request-path":"/0/members/40fb1eca294b6a5a/attributes","cluster-id":"17c6999805fcc0eb","publish-timeout":"7s"} {"level":"info","ts":"2026-10-16T00:06:01.453927Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T00:06:01.453991Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T00:06:01.454329Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-16T00:06:01.454363Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-16T00:06:01.453966Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T00:06:01.457284Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.156:2379"} {"level":"info","ts":"2026-10-16T00:06:01.460229Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-16T00:06:49.970947Z","caller":"traceutil/trace.go:171","msg":"trace[1230908364] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"114.168707ms","start":"2026-10-16T00:06:49.856752Z","end":"2026-10-16T00:06:49.970921Z","steps":["trace[1230908364] 'process raft request' (duration: 114.102765ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:49.970982Z","caller":"traceutil/trace.go:171","msg":"trace[897998864] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"120.579921ms","start":"2026-10-16T00:06:49.850369Z","end":"2026-10-16T00:06:49.970948Z","steps":["trace[897998864] 'process raft request' (duration: 91.360972ms)","trace[897998864] 'compare' (duration: 28.981873ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:06:55.932259Z","caller":"traceutil/trace.go:171","msg":"trace[752483681] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"145.358796ms","start":"2026-10-16T00:06:55.786859Z","end":"2026-10-16T00:06:55.932218Z","steps":["trace[752483681] 'process raft request' (duration: 137.33176ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:56.156877Z","caller":"traceutil/trace.go:171","msg":"trace[260532935] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"140.105402ms","start":"2026-10-16T00:06:56.016747Z","end":"2026-10-16T00:06:56.156852Z","steps":["trace[260532935] 'process raft request' (duration: 139.872816ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:56.156845Z","caller":"traceutil/trace.go:171","msg":"trace[825565203] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"111.95729ms","start":"2026-10-16T00:06:56.044855Z","end":"2026-10-16T00:06:56.156812Z","steps":["trace[825565203] 'read index received' (duration: 111.826577ms)","trace[825565203] 'applied index is now lower than readState.Index' (duration: 129.323µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:06:56.157155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.259218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-16T00:06:56.157273Z","caller":"traceutil/trace.go:171","msg":"trace[1177677865] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:613; }","duration":"112.473903ms","start":"2026-10-16T00:06:56.044777Z","end":"2026-10-16T00:06:56.157251Z","steps":["trace[1177677865] 'agreement among raft nodes before linearized reading' (duration: 112.135865ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:56.755674Z","caller":"traceutil/trace.go:171","msg":"trace[709014512] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"294.771487ms","start":"2026-10-16T00:06:56.460873Z","end":"2026-10-16T00:06:56.755644Z","steps":["trace[709014512] 'process raft request' (duration: 294.4789ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:57.019984Z","caller":"traceutil/trace.go:171","msg":"trace[1642778016] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"559.011501ms","start":"2026-10-16T00:06:56.460921Z","end":"2026-10-16T00:06:57.019932Z","steps":["trace[1642778016] 'process raft request' (duration: 558.377705ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:57.020481Z","caller":"traceutil/trace.go:171","msg":"trace[69434006] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:640; }","duration":"490.970956ms","start":"2026-10-16T00:06:56.529476Z","end":"2026-10-16T00:06:57.020447Z","steps":["trace[69434006] 'read index received' (duration: 225.961423ms)","trace[69434006] 'applied index is now lower than readState.Index' (duration: 265.007633ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:06:57.020876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:06:56.460886Z","time spent":"559.213586ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:06:57.020868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.480721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:06:57.020984Z","caller":"traceutil/trace.go:171","msg":"trace[1057932404] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"358.789658ms","start":"2026-10-16T00:06:56.662168Z","end":"2026-10-16T00:06:57.020958Z","steps":["trace[1057932404] 'process raft request' (duration: 357.661489ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:06:57.021005Z","caller":"traceutil/trace.go:171","msg":"trace[729139080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"328.646185ms","start":"2026-10-16T00:06:56.692331Z","end":"2026-10-16T00:06:57.020977Z","steps":["trace[729139080] 'agreement among raft nodes before linearized reading' (duration: 328.437569ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:06:57.021069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:06:56.692208Z","time spent":"328.84515ms","remote":"127.0.0.1:44980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-16T00:06:57.021091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:06:56.662124Z","time spent":"358.907891ms","remote":"127.0.0.1:45082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:06:57.02122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.678215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bgvhg\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-16T00:06:57.02134Z","caller":"traceutil/trace.go:171","msg":"trace[140554062] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bgvhg; range_end:; response_count:1; response_revision:617; }","duration":"491.89487ms","start":"2026-10-16T00:06:56.529422Z","end":"2026-10-16T00:06:57.021317Z","steps":["trace[140554062] 'agreement among raft nodes before linearized reading' (duration: 491.249294ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:06:57.021446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:06:56.529408Z","time spent":"492.021073ms","remote":"127.0.0.1:45196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-bgvhg\" "} {"level":"warn","ts":"2026-10-16T00:07:13.170792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.327148ms","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-16T00:07:13.170932Z","caller":"traceutil/trace.go:171","msg":"trace[367265767] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:785; }","duration":"298.555384ms","start":"2026-10-16T00:07:12.872349Z","end":"2026-10-16T00:07:13.170904Z","steps":["trace[367265767] 'range keys from in-memory index tree' (duration: 298.04817ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:09:19.975634Z","caller":"traceutil/trace.go:171","msg":"trace[1759231977] linearizableReadLoop","detail":"{readStateIndex:2269; appliedIndex:2268; }","duration":"108.136313ms","start":"2026-10-16T00:09:19.867454Z","end":"2026-10-16T00:09:19.97559Z","steps":["trace[1759231977] 'read index received' (duration: 99.069041ms)","trace[1759231977] 'applied index is now lower than readState.Index' (duration: 9.065482ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:09:19.975701Z","caller":"traceutil/trace.go:171","msg":"trace[155340464] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"128.542525ms","start":"2026-10-16T00:09:19.847103Z","end":"2026-10-16T00:09:19.975646Z","steps":["trace[155340464] 'process raft request' (duration: 119.564205ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:09:19.975917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.41518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:09:19.97606Z","caller":"traceutil/trace.go:171","msg":"trace[1866193850] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2183; }","duration":"108.555894ms","start":"2026-10-16T00:09:19.867429Z","end":"2026-10-16T00:09:19.975985Z","steps":["trace[1866193850] 'agreement among raft nodes before linearized reading' (duration: 108.38555ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:09:20.202444Z","caller":"traceutil/trace.go:171","msg":"trace[696852394] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"107.022524ms","start":"2026-10-16T00:09:20.095381Z","end":"2026-10-16T00:09:20.202404Z","steps":["trace[696852394] 'process raft request' (duration: 105.416923ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:10:42.310491Z","caller":"traceutil/trace.go:171","msg":"trace[108549154] transaction","detail":"{read_only:false; response_revision:2821; number_of_response:1; }","duration":"117.07152ms","start":"2026-10-16T00:10:42.193388Z","end":"2026-10-16T00:10:42.31046Z","steps":["trace[108549154] 'process raft request' (duration: 116.882065ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:10:42.647351Z","caller":"traceutil/trace.go:171","msg":"trace[2028640221] transaction","detail":"{read_only:false; response_revision:2822; number_of_response:1; }","duration":"149.188849ms","start":"2026-10-16T00:10:42.498118Z","end":"2026-10-16T00:10:42.647306Z","steps":["trace[2028640221] 'process raft request' (duration: 148.977213ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:13:27.513169Z","caller":"traceutil/trace.go:171","msg":"trace[1380418012] transaction","detail":"{read_only:false; response_revision:3814; number_of_response:1; }","duration":"109.542982ms","start":"2026-10-16T00:13:27.403594Z","end":"2026-10-16T00:13:27.513137Z","steps":["trace[1380418012] 'process raft request' (duration: 83.18282ms)","trace[1380418012] 'compare' (duration: 26.217748ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:13:27.513616Z","caller":"traceutil/trace.go:171","msg":"trace[1745382944] transaction","detail":"{read_only:false; response_revision:3816; number_of_response:1; }","duration":"105.466678ms","start":"2026-10-16T00:13:27.408134Z","end":"2026-10-16T00:13:27.513601Z","steps":["trace[1745382944] 'process raft request' (duration: 105.425917ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:13:27.513875Z","caller":"traceutil/trace.go:171","msg":"trace[1927550003] transaction","detail":"{read_only:false; response_revision:3815; number_of_response:1; }","duration":"110.158418ms","start":"2026-10-16T00:13:27.403681Z","end":"2026-10-16T00:13:27.513839Z","steps":["trace[1927550003] 'process raft request' (duration: 109.786438ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:14:00.094096Z","caller":"traceutil/trace.go:171","msg":"trace[1618716871] linearizableReadLoop","detail":"{readStateIndex:4222; appliedIndex:4221; }","duration":"155.670057ms","start":"2026-10-16T00:13:59.938381Z","end":"2026-10-16T00:14:00.094052Z","steps":["trace[1618716871] 'read index received' (duration: 139.689489ms)","trace[1618716871] 'applied index is now lower than readState.Index' (duration: 15.978397ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:14:00.0943Z","caller":"traceutil/trace.go:171","msg":"trace[561279678] transaction","detail":"{read_only:false; response_revision:4064; number_of_response:1; }","duration":"157.117383ms","start":"2026-10-16T00:13:59.937146Z","end":"2026-10-16T00:14:00.094264Z","steps":["trace[561279678] 'process raft request' (duration: 140.828838ms)","trace[561279678] 'compare' (duration: 15.759671ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:14:00.094607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.155529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-16T00:14:00.094678Z","caller":"traceutil/trace.go:171","msg":"trace[500079864] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4064; }","duration":"156.336893ms","start":"2026-10-16T00:13:59.938321Z","end":"2026-10-16T00:14:00.094658Z","steps":["trace[500079864] 'agreement among raft nodes before linearized reading' (duration: 155.965163ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:14:00.095464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.986135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sd5z5\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-16T00:14:00.095612Z","caller":"traceutil/trace.go:171","msg":"trace[198682502] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sd5z5; range_end:; response_count:1; response_revision:4064; }","duration":"136.165649ms","start":"2026-10-16T00:13:59.959431Z","end":"2026-10-16T00:14:00.095597Z","steps":["trace[198682502] 'agreement among raft nodes before linearized reading' (duration: 135.841441ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:15:32.775372Z","caller":"traceutil/trace.go:171","msg":"trace[990075447] transaction","detail":"{read_only:false; response_revision:5263; number_of_response:1; }","duration":"292.133243ms","start":"2026-10-16T00:15:32.483212Z","end":"2026-10-16T00:15:32.775345Z","steps":["trace[990075447] 'process raft request' (duration: 291.927728ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:15:32.801093Z","caller":"traceutil/trace.go:171","msg":"trace[1872585887] linearizableReadLoop","detail":"{readStateIndex:5469; appliedIndex:5466; }","duration":"313.873778ms","start":"2026-10-16T00:15:32.487177Z","end":"2026-10-16T00:15:32.801051Z","steps":["trace[1872585887] 'read index received' (duration: 287.995498ms)","trace[1872585887] 'applied index is now lower than readState.Index' (duration: 25.87679ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:15:32.801148Z","caller":"traceutil/trace.go:171","msg":"trace[123267728] transaction","detail":"{read_only:false; response_revision:5264; number_of_response:1; }","duration":"317.395728ms","start":"2026-10-16T00:15:32.483709Z","end":"2026-10-16T00:15:32.801105Z","steps":["trace[123267728] 'process raft request' (duration: 317.122171ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:15:32.80118Z","caller":"traceutil/trace.go:171","msg":"trace[408092614] transaction","detail":"{read_only:false; response_revision:5265; number_of_response:1; }","duration":"316.096905ms","start":"2026-10-16T00:15:32.485047Z","end":"2026-10-16T00:15:32.801143Z","steps":["trace[408092614] 'process raft request' (duration: 315.957701ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:15:32.801325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:15:32.483677Z","time spent":"317.552662ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:15:32.801466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.259798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-edaf8ebf-63db-454a-ad00-0516dd1a23ea\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-16T00:15:32.802152Z","caller":"traceutil/trace.go:171","msg":"trace[687892603] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-edaf8ebf-63db-454a-ad00-0516dd1a23ea; range_end:; response_count:1; response_revision:5265; }","duration":"314.994387ms","start":"2026-10-16T00:15:32.487142Z","end":"2026-10-16T00:15:32.802137Z","steps":["trace[687892603] 'agreement among raft nodes before linearized reading' (duration: 314.081494ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:15:32.802188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:15:32.48713Z","time spent":"315.044958ms","remote":"127.0.0.1:45196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3623,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-edaf8ebf-63db-454a-ad00-0516dd1a23ea\" "} {"level":"warn","ts":"2026-10-16T00:15:32.801462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.109001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:15:32.802363Z","caller":"traceutil/trace.go:171","msg":"trace[1913526974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5265; }","duration":"113.069245ms","start":"2026-10-16T00:15:32.689277Z","end":"2026-10-16T00:15:32.802347Z","steps":["trace[1913526974] 'agreement among raft nodes before linearized reading' (duration: 112.102221ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:15:32.801714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.960742ms","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-16T00:15:32.802504Z","caller":"traceutil/trace.go:171","msg":"trace[861361625] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5265; }","duration":"142.779603ms","start":"2026-10-16T00:15:32.659696Z","end":"2026-10-16T00:15:32.802476Z","steps":["trace[861361625] 'agreement among raft nodes before linearized reading' (duration: 141.586972ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:15:32.801961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:15:32.485034Z","time spent":"316.834603ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:15:34.928266Z","caller":"traceutil/trace.go:171","msg":"trace[1820235157] linearizableReadLoop","detail":"{readStateIndex:5502; appliedIndex:5501; }","duration":"121.428658ms","start":"2026-10-16T00:15:34.806805Z","end":"2026-10-16T00:15:34.928234Z","steps":["trace[1820235157] 'read index received' (duration: 38.334817ms)","trace[1820235157] 'applied index is now lower than readState.Index' (duration: 83.092311ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:15:34.928363Z","caller":"traceutil/trace.go:171","msg":"trace[1051541459] transaction","detail":"{read_only:false; response_revision:5296; number_of_response:1; }","duration":"143.04268ms","start":"2026-10-16T00:15:34.785264Z","end":"2026-10-16T00:15:34.928306Z","steps":["trace[1051541459] 'process raft request' (duration: 59.885778ms)","trace[1051541459] 'compare' (duration: 82.915545ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:15:34.928594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.760687ms","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-16T00:15:34.928641Z","caller":"traceutil/trace.go:171","msg":"trace[1615951508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5296; }","duration":"121.88107ms","start":"2026-10-16T00:15:34.806745Z","end":"2026-10-16T00:15:34.928626Z","steps":["trace[1615951508] 'agreement among raft nodes before linearized reading' (duration: 121.676044ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:15:34.928784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.21859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sd5z5\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-10-16T00:15:34.928781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.413414ms","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-16T00:15:34.928862Z","caller":"traceutil/trace.go:171","msg":"trace[1311634389] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sd5z5; range_end:; response_count:1; response_revision:5296; }","duration":"115.336063ms","start":"2026-10-16T00:15:34.813506Z","end":"2026-10-16T00:15:34.928843Z","steps":["trace[1311634389] 'agreement among raft nodes before linearized reading' (duration: 115.131628ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:15:34.928872Z","caller":"traceutil/trace.go:171","msg":"trace[1990125793] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5296; }","duration":"104.540997ms","start":"2026-10-16T00:15:34.824312Z","end":"2026-10-16T00:15:34.928853Z","steps":["trace[1990125793] 'agreement among raft nodes before linearized reading' (duration: 104.294831ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:15:34.929514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.555687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-16T00:15:34.929682Z","caller":"traceutil/trace.go:171","msg":"trace[93216329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5296; }","duration":"122.775383ms","start":"2026-10-16T00:15:34.806876Z","end":"2026-10-16T00:15:34.929651Z","steps":["trace[93216329] 'agreement among raft nodes before linearized reading' (duration: 121.691975ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:15:57.52064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.17287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-54c8f768-687d-4833-bbed-3678ea1b4efb\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-16T00:15:57.520783Z","caller":"traceutil/trace.go:171","msg":"trace[129677847] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-54c8f768-687d-4833-bbed-3678ea1b4efb; range_end:; response_count:1; response_revision:5672; }","duration":"186.410465ms","start":"2026-10-16T00:15:57.334343Z","end":"2026-10-16T00:15:57.520754Z","steps":["trace[129677847] 'range keys from in-memory index tree' (duration: 185.882462ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:15:58.018381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.36365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-54c8f768-687d-4833-bbed-3678ea1b4efb\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-16T00:15:58.018521Z","caller":"traceutil/trace.go:171","msg":"trace[1976387966] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-54c8f768-687d-4833-bbed-3678ea1b4efb; range_end:; response_count:1; response_revision:5674; }","duration":"236.538465ms","start":"2026-10-16T00:15:57.781945Z","end":"2026-10-16T00:15:58.018483Z","steps":["trace[1976387966] 'range keys from in-memory index tree' (duration: 236.107024ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:15:58.018116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.029852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:15:58.019824Z","caller":"traceutil/trace.go:171","msg":"trace[361086671] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5674; }","duration":"190.775027ms","start":"2026-10-16T00:15:57.829017Z","end":"2026-10-16T00:15:58.019792Z","steps":["trace[361086671] 'range keys from in-memory index tree' (duration: 188.894359ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:15:58.25266Z","caller":"traceutil/trace.go:171","msg":"trace[232415286] linearizableReadLoop","detail":"{readStateIndex:5890; appliedIndex:5889; }","duration":"223.404869ms","start":"2026-10-16T00:15:58.029223Z","end":"2026-10-16T00:15:58.252628Z","steps":["trace[232415286] 'read index received' (duration: 223.120281ms)","trace[232415286] 'applied index is now lower than readState.Index' (duration: 283.108µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:15:58.25276Z","caller":"traceutil/trace.go:171","msg":"trace[2018929505] transaction","detail":"{read_only:false; response_revision:5675; number_of_response:1; }","duration":"224.881657ms","start":"2026-10-16T00:15:58.027822Z","end":"2026-10-16T00:15:58.252704Z","steps":["trace[2018929505] 'process raft request' (duration: 224.566859ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:15:58.252964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.717097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-16T00:15:58.253044Z","caller":"traceutil/trace.go:171","msg":"trace[1022318768] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5675; }","duration":"223.880021ms","start":"2026-10-16T00:15:58.029149Z","end":"2026-10-16T00:15:58.253029Z","steps":["trace[1022318768] 'agreement among raft nodes before linearized reading' (duration: 223.589773ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:16:01.982104Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2931} {"level":"info","ts":"2026-10-16T00:16:02.067471Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2931,"took":"83.335326ms","hash":3369477698,"current-db-size-bytes":56107008,"current-db-size":"56 MB","current-db-size-in-use-bytes":31858688,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T00:16:02.067596Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3369477698,"revision":2931,"compact-revision":-1} {"level":"info","ts":"2026-10-16T00:16:11.254233Z","caller":"traceutil/trace.go:171","msg":"trace[569412456] transaction","detail":"{read_only:false; response_revision:5870; number_of_response:1; }","duration":"111.491514ms","start":"2026-10-16T00:16:11.142704Z","end":"2026-10-16T00:16:11.254196Z","steps":["trace[569412456] 'process raft request' (duration: 111.266489ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:21:01.990675Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5754} {"level":"info","ts":"2026-10-16T00:21:02.084373Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5754,"took":"91.281123ms","hash":3267199098,"current-db-size-bytes":56107008,"current-db-size":"56 MB","current-db-size-in-use-bytes":26038272,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-16T00:21:02.084449Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3267199098,"revision":5754,"compact-revision":2931} {"level":"info","ts":"2026-10-16T00:22:11.550114Z","caller":"traceutil/trace.go:171","msg":"trace[276710519] linearizableReadLoop","detail":"{readStateIndex:8889; appliedIndex:8888; }","duration":"200.309989ms","start":"2026-10-16T00:22:11.349777Z","end":"2026-10-16T00:22:11.550087Z","steps":["trace[276710519] 'read index received' (duration: 200.102893ms)","trace[276710519] 'applied index is now lower than readState.Index' (duration: 205.816µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:22:11.55028Z","caller":"traceutil/trace.go:171","msg":"trace[730986857] transaction","detail":"{read_only:false; response_revision:8581; number_of_response:1; }","duration":"269.847636ms","start":"2026-10-16T00:22:11.280379Z","end":"2026-10-16T00:22:11.550226Z","steps":["trace[730986857] 'process raft request' (duration: 269.527478ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:22:11.550439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.222692ms","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-16T00:22:11.550486Z","caller":"traceutil/trace.go:171","msg":"trace[1581732334] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8581; }","duration":"109.338034ms","start":"2026-10-16T00:22:11.441134Z","end":"2026-10-16T00:22:11.550472Z","steps":["trace[1581732334] 'agreement among raft nodes before linearized reading' (duration: 109.14967ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:22:11.550588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.753731ms","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-16T00:22:11.550716Z","caller":"traceutil/trace.go:171","msg":"trace[778395227] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8581; }","duration":"200.969956ms","start":"2026-10-16T00:22:11.349701Z","end":"2026-10-16T00:22:11.550671Z","steps":["trace[778395227] 'agreement among raft nodes before linearized reading' (duration: 200.550665ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:22:22.855597Z","caller":"traceutil/trace.go:171","msg":"trace[397549497] transaction","detail":"{read_only:false; response_revision:8642; number_of_response:1; }","duration":"131.06102ms","start":"2026-10-16T00:22:22.724504Z","end":"2026-10-16T00:22:22.855565Z","steps":["trace[397549497] 'process raft request' (duration: 98.18317ms)","trace[397549497] 'compare' (duration: 32.757237ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:22:24.412248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.684501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T00:22:24.412394Z","caller":"traceutil/trace.go:171","msg":"trace[19579806] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:8649; }","duration":"310.901416ms","start":"2026-10-16T00:22:24.101458Z","end":"2026-10-16T00:22:24.412359Z","steps":["trace[19579806] 'count revisions from in-memory index tree' (duration: 310.563597ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:22:24.412461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:22:24.10143Z","time spent":"311.010979ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"warn","ts":"2026-10-16T00:23:07.082442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.210267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-10-16T00:23:07.082573Z","caller":"traceutil/trace.go:171","msg":"trace[1745195969] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:8971; }","duration":"132.411792ms","start":"2026-10-16T00:23:06.95014Z","end":"2026-10-16T00:23:07.082552Z","steps":["trace[1745195969] 'range keys from in-memory index tree' (duration: 131.95044ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:07.377895Z","caller":"traceutil/trace.go:171","msg":"trace[1600237940] transaction","detail":"{read_only:false; response_revision:8973; number_of_response:1; }","duration":"186.049632ms","start":"2026-10-16T00:23:07.191735Z","end":"2026-10-16T00:23:07.377784Z","steps":["trace[1600237940] 'process raft request' (duration: 185.739424ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:07.378067Z","caller":"traceutil/trace.go:171","msg":"trace[2127530596] transaction","detail":"{read_only:false; response_revision:8974; number_of_response:1; }","duration":"170.431373ms","start":"2026-10-16T00:23:07.207591Z","end":"2026-10-16T00:23:07.378022Z","steps":["trace[2127530596] 'process raft request' (duration: 170.125605ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:23:19.363739Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024b0.wal"} {"level":"info","ts":"2026-10-16T00:24:14.588593Z","caller":"traceutil/trace.go:171","msg":"trace[208574578] transaction","detail":"{read_only:false; response_revision:9602; number_of_response:1; }","duration":"127.928597ms","start":"2026-10-16T00:24:14.460642Z","end":"2026-10-16T00:24:14.58857Z","steps":["trace[208574578] 'process raft request' (duration: 127.769943ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:24:14.590478Z","caller":"traceutil/trace.go:171","msg":"trace[441433339] linearizableReadLoop","detail":"{readStateIndex:9946; appliedIndex:9944; }","duration":"116.395113ms","start":"2026-10-16T00:24:14.474063Z","end":"2026-10-16T00:24:14.590459Z","steps":["trace[441433339] 'read index received' (duration: 114.33156ms)","trace[441433339] 'applied index is now lower than readState.Index' (duration: 2.062493ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:24:14.590553Z","caller":"traceutil/trace.go:171","msg":"trace[1185038159] transaction","detail":"{read_only:false; response_revision:9603; number_of_response:1; }","duration":"128.950143ms","start":"2026-10-16T00:24:14.461565Z","end":"2026-10-16T00:24:14.590515Z","steps":["trace[1185038159] 'process raft request' (duration: 128.773689ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:24:14.590736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.265503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-16T00:24:14.590783Z","caller":"traceutil/trace.go:171","msg":"trace[1208341341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9603; }","duration":"104.325825ms","start":"2026-10-16T00:24:14.486446Z","end":"2026-10-16T00:24:14.590772Z","steps":["trace[1208341341] 'agreement among raft nodes before linearized reading' (duration: 104.165081ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:24:14.59085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.764952ms","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-16T00:24:14.590908Z","caller":"traceutil/trace.go:171","msg":"trace[1928280344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9603; }","duration":"116.874645ms","start":"2026-10-16T00:24:14.474021Z","end":"2026-10-16T00:24:14.590896Z","steps":["trace[1928280344] 'agreement among raft nodes before linearized reading' (duration: 116.535086ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:24:22.79004Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"40fb1eca294b6a5a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T00:24:22.796802Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-16T00:24:22.796958Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-10-16T00:24:26.514617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.022379ms","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-16T00:24:26.514707Z","caller":"traceutil/trace.go:171","msg":"trace[413577764] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9669; }","duration":"240.187482ms","start":"2026-10-16T00:24:26.274501Z","end":"2026-10-16T00:24:26.514689Z","steps":["trace[413577764] 'range keys from in-memory index tree' (duration: 239.749352ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:26:01.998542Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7982} {"level":"info","ts":"2026-10-16T00:26:02.077698Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7982,"took":"76.997304ms","hash":3373419071,"current-db-size-bytes":56107008,"current-db-size":"56 MB","current-db-size-in-use-bytes":28995584,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-16T00:26:02.077845Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3373419071,"revision":7982,"compact-revision":5754} {"level":"info","ts":"2026-10-16T00:28:06.745155Z","caller":"traceutil/trace.go:171","msg":"trace[1334161146] transaction","detail":"{read_only:false; response_revision:11598; number_of_response:1; }","duration":"177.614022ms","start":"2026-10-16T00:28:06.567501Z","end":"2026-10-16T00:28:06.745115Z","steps":["trace[1334161146] 'process raft request' (duration: 177.401977ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:31:02.005343Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10444} {"level":"info","ts":"2026-10-16T00:31:02.093797Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10444,"took":"85.852113ms","hash":3368881501,"current-db-size-bytes":56107008,"current-db-size":"56 MB","current-db-size-in-use-bytes":27602944,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-16T00:31:02.093897Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3368881501,"revision":10444,"compact-revision":7982} {"level":"info","ts":"2026-10-16T00:34:37.555596Z","caller":"traceutil/trace.go:171","msg":"trace[1648374898] linearizableReadLoop","detail":"{readStateIndex:14711; appliedIndex:14710; }","duration":"294.356454ms","start":"2026-10-16T00:34:37.261194Z","end":"2026-10-16T00:34:37.555551Z","steps":["trace[1648374898] 'read index received' (duration: 294.124889ms)","trace[1648374898] 'applied index is now lower than readState.Index' (duration: 230.675µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:34:37.555925Z","caller":"traceutil/trace.go:171","msg":"trace[2004254091] transaction","detail":"{read_only:false; response_revision:14216; number_of_response:1; }","duration":"354.636294ms","start":"2026-10-16T00:34:37.201271Z","end":"2026-10-16T00:34:37.555907Z","steps":["trace[2004254091] 'process raft request' (duration: 354.10525ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:34:37.556069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:34:37.201232Z","time spent":"354.746136ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:34:37.556435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.244397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-2kfg2\" ","response":"range_response_count:1 size:12364"} {"level":"info","ts":"2026-10-16T00:34:37.556486Z","caller":"traceutil/trace.go:171","msg":"trace[951997960] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-2kfg2; range_end:; response_count:1; response_revision:14216; }","duration":"295.342999ms","start":"2026-10-16T00:34:37.261131Z","end":"2026-10-16T00:34:37.556474Z","steps":["trace[951997960] 'agreement among raft nodes before linearized reading' (duration: 295.168605ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:34:39.492093Z","caller":"traceutil/trace.go:171","msg":"trace[1438377623] transaction","detail":"{read_only:false; response_revision:14234; number_of_response:1; }","duration":"109.366732ms","start":"2026-10-16T00:34:39.382697Z","end":"2026-10-16T00:34:39.492063Z","steps":["trace[1438377623] 'process raft request' (duration: 109.169427ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:34:48.11811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.062147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sd5z5\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-16T00:34:48.118291Z","caller":"traceutil/trace.go:171","msg":"trace[1968037167] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sd5z5; range_end:; response_count:1; response_revision:14381; }","duration":"142.292122ms","start":"2026-10-16T00:34:47.975974Z","end":"2026-10-16T00:34:48.118266Z","steps":["trace[1968037167] 'range keys from in-memory index tree' (duration: 141.858461ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:34:48.118379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.575784ms","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-16T00:34:48.118465Z","caller":"traceutil/trace.go:171","msg":"trace[1905670116] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14381; }","duration":"161.709828ms","start":"2026-10-16T00:34:47.956737Z","end":"2026-10-16T00:34:48.118447Z","steps":["trace[1905670116] 'range keys from in-memory index tree' (duration: 161.453291ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:34:48.22991Z","caller":"traceutil/trace.go:171","msg":"trace[1937268466] linearizableReadLoop","detail":"{readStateIndex:14880; appliedIndex:14879; }","duration":"106.999092ms","start":"2026-10-16T00:34:48.122882Z","end":"2026-10-16T00:34:48.229881Z","steps":["trace[1937268466] 'read index received' (duration: 106.768736ms)","trace[1937268466] 'applied index is now lower than readState.Index' (duration: 229.175µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:34:48.230355Z","caller":"traceutil/trace.go:171","msg":"trace[1885578977] transaction","detail":"{read_only:false; response_revision:14382; number_of_response:1; }","duration":"171.858957ms","start":"2026-10-16T00:34:48.05848Z","end":"2026-10-16T00:34:48.230339Z","steps":["trace[1885578977] 'process raft request' (duration: 171.123128ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:34:48.231138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.229643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:67 size:629830"} {"level":"info","ts":"2026-10-16T00:34:48.231401Z","caller":"traceutil/trace.go:171","msg":"trace[1082794828] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:67; response_revision:14382; }","duration":"108.50454ms","start":"2026-10-16T00:34:48.122856Z","end":"2026-10-16T00:34:48.23136Z","steps":["trace[1082794828] 'agreement among raft nodes before linearized reading' (duration: 107.271769ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:34:48.422629Z","caller":"traceutil/trace.go:171","msg":"trace[1361652692] transaction","detail":"{read_only:false; response_revision:14383; number_of_response:1; }","duration":"299.613029ms","start":"2026-10-16T00:34:48.122986Z","end":"2026-10-16T00:34:48.422599Z","steps":["trace[1361652692] 'process raft request' (duration: 278.189702ms)","trace[1361652692] 'compare' (duration: 21.235192ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:34:48.422974Z","caller":"traceutil/trace.go:171","msg":"trace[1046339234] transaction","detail":"{read_only:false; response_revision:14384; number_of_response:1; }","duration":"299.844115ms","start":"2026-10-16T00:34:48.123117Z","end":"2026-10-16T00:34:48.422961Z","steps":["trace[1046339234] 'process raft request' (duration: 299.424854ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:34:48.423017Z","caller":"traceutil/trace.go:171","msg":"trace[448580650] linearizableReadLoop","detail":"{readStateIndex:14884; appliedIndex:14880; }","duration":"192.866944ms","start":"2026-10-16T00:34:48.230126Z","end":"2026-10-16T00:34:48.422993Z","steps":["trace[448580650] 'read index received' (duration: 170.953924ms)","trace[448580650] 'applied index is now lower than readState.Index' (duration: 21.91191ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:34:48.42305Z","caller":"traceutil/trace.go:171","msg":"trace[1369087074] transaction","detail":"{read_only:false; response_revision:14386; number_of_response:1; }","duration":"299.758913ms","start":"2026-10-16T00:34:48.123268Z","end":"2026-10-16T00:34:48.423027Z","steps":["trace[1369087074] 'process raft request' (duration: 299.67477ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:34:48.423055Z","caller":"traceutil/trace.go:171","msg":"trace[1718589640] transaction","detail":"{read_only:false; response_revision:14385; number_of_response:1; }","duration":"299.890025ms","start":"2026-10-16T00:34:48.123136Z","end":"2026-10-16T00:34:48.423026Z","steps":["trace[1718589640] 'process raft request' (duration: 299.696041ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:34:48.423213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:34:48.123107Z","time spent":"300.018839ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:34:48.423252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.494701ms","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-16T00:34:48.423289Z","caller":"traceutil/trace.go:171","msg":"trace[1594464306] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14386; }","duration":"298.565292ms","start":"2026-10-16T00:34:48.124711Z","end":"2026-10-16T00:34:48.423276Z","steps":["trace[1594464306] 'agreement among raft nodes before linearized reading' (duration: 298.420248ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:34:48.423513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.745916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-16T00:34:48.423628Z","caller":"traceutil/trace.go:171","msg":"trace[2083169495] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14386; }","duration":"135.959911ms","start":"2026-10-16T00:34:48.287644Z","end":"2026-10-16T00:34:48.423604Z","steps":["trace[2083169495] 'agreement among raft nodes before linearized reading' (duration: 135.584092ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:36:02.012054Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12765} {"level":"info","ts":"2026-10-16T00:36:02.104845Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12765,"took":"90.011888ms","hash":3518553516,"current-db-size-bytes":56107008,"current-db-size":"56 MB","current-db-size-in-use-bytes":30789632,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T00:36:02.104904Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3518553516,"revision":12765,"compact-revision":10444} {"level":"warn","ts":"2026-10-16T00:36:28.530849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.195881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-10-16T00:36:28.530962Z","caller":"traceutil/trace.go:171","msg":"trace[497198365] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:15470; }","duration":"115.302664ms","start":"2026-10-16T00:36:28.41562Z","end":"2026-10-16T00:36:28.530922Z","steps":["trace[497198365] 'range keys from in-memory index tree' (duration: 114.984375ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:36:28.763291Z","caller":"traceutil/trace.go:171","msg":"trace[1302350696] transaction","detail":"{read_only:false; response_revision:15471; number_of_response:1; }","duration":"151.112098ms","start":"2026-10-16T00:36:28.612152Z","end":"2026-10-16T00:36:28.763264Z","steps":["trace[1302350696] 'process raft request' (duration: 150.943464ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:41:02.0193Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15335} {"level":"info","ts":"2026-10-16T00:41:02.128399Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15335,"took":"105.519271ms","hash":1984850572,"current-db-size-bytes":56107008,"current-db-size":"56 MB","current-db-size-in-use-bytes":31715328,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T00:41:02.128504Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1984850572,"revision":15335,"compact-revision":12765} {"level":"info","ts":"2026-10-16T00:42:09.660341Z","caller":"traceutil/trace.go:171","msg":"trace[1478730367] linearizableReadLoop","detail":"{readStateIndex:18660; appliedIndex:18659; }","duration":"116.835692ms","start":"2026-10-16T00:42:09.543486Z","end":"2026-10-16T00:42:09.660321Z","steps":["trace[1478730367] 'read index received' (duration: 116.583655ms)","trace[1478730367] 'applied index is now lower than readState.Index' (duration: 251.317µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:42:09.660624Z","caller":"traceutil/trace.go:171","msg":"trace[225818850] transaction","detail":"{read_only:false; response_revision:18041; number_of_response:1; }","duration":"266.312296ms","start":"2026-10-16T00:42:09.394301Z","end":"2026-10-16T00:42:09.660613Z","steps":["trace[225818850] 'process raft request' (duration: 265.836894ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:42:09.661048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.54458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-10-16T00:42:09.661089Z","caller":"traceutil/trace.go:171","msg":"trace[21262905] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:18041; }","duration":"117.653753ms","start":"2026-10-16T00:42:09.543426Z","end":"2026-10-16T00:42:09.66108Z","steps":["trace[21262905] 'agreement among raft nodes before linearized reading' (duration: 117.338215ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:44:42.615707Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"40fb1eca294b6a5a","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T00:44:42.622064Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-16T00:44:42.622176Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-16T00:46:02.026214Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17490} {"level":"info","ts":"2026-10-16T00:46:02.127069Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17490,"took":"97.512718ms","hash":2603844865,"current-db-size-bytes":56107008,"current-db-size":"56 MB","current-db-size-in-use-bytes":34136064,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-16T00:46:02.127141Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2603844865,"revision":17490,"compact-revision":15335} {"level":"info","ts":"2026-10-16T00:46:44.51778Z","caller":"traceutil/trace.go:171","msg":"trace[1283530417] linearizableReadLoop","detail":"{readStateIndex:20934; appliedIndex:20933; }","duration":"140.133287ms","start":"2026-10-16T00:46:44.37762Z","end":"2026-10-16T00:46:44.517753Z","steps":["trace[1283530417] 'read index received' (duration: 139.998583ms)","trace[1283530417] 'applied index is now lower than readState.Index' (duration: 133.053µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:46:44.518015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.754982ms","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-16T00:46:44.517878Z","caller":"traceutil/trace.go:171","msg":"trace[2026603459] transaction","detail":"{read_only:false; response_revision:20246; number_of_response:1; }","duration":"143.493413ms","start":"2026-10-16T00:46:44.374342Z","end":"2026-10-16T00:46:44.517836Z","steps":["trace[2026603459] 'process raft request' (duration: 143.253166ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:46:44.51816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.920499ms","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-16T00:46:44.518105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.462905ms","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-16T00:46:44.518248Z","caller":"traceutil/trace.go:171","msg":"trace[274643938] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20246; }","duration":"136.046013ms","start":"2026-10-16T00:46:44.382184Z","end":"2026-10-16T00:46:44.51823Z","steps":["trace[274643938] 'agreement among raft nodes before linearized reading' (duration: 135.804887ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:46:44.518061Z","caller":"traceutil/trace.go:171","msg":"trace[1969349789] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20246; }","duration":"138.861884ms","start":"2026-10-16T00:46:44.379186Z","end":"2026-10-16T00:46:44.518048Z","steps":["trace[1969349789] 'agreement among raft nodes before linearized reading' (duration: 138.743611ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:46:44.518272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.017261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-16T00:46:44.518453Z","caller":"traceutil/trace.go:171","msg":"trace[1474879358] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20246; }","duration":"125.215546ms","start":"2026-10-16T00:46:44.393195Z","end":"2026-10-16T00:46:44.51841Z","steps":["trace[1474879358] 'agreement among raft nodes before linearized reading' (duration: 124.807746ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:46:44.518302Z","caller":"traceutil/trace.go:171","msg":"trace[316717044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20246; }","duration":"140.692971ms","start":"2026-10-16T00:46:44.377548Z","end":"2026-10-16T00:46:44.518241Z","steps":["trace[316717044] 'agreement among raft nodes before linearized reading' (duration: 140.366953ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:46:44.7178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.913245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:46:44.717983Z","caller":"traceutil/trace.go:171","msg":"trace[160067345] transaction","detail":"{read_only:false; response_revision:20247; number_of_response:1; }","duration":"194.427942ms","start":"2026-10-16T00:46:44.523534Z","end":"2026-10-16T00:46:44.717962Z","steps":["trace[160067345] 'process raft request' (duration: 28.103337ms)","trace[160067345] 'compare' (duration: 165.73105ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:46:44.740289Z","caller":"traceutil/trace.go:171","msg":"trace[1711393345] transaction","detail":"{read_only:false; response_revision:20248; number_of_response:1; }","duration":"216.699981ms","start":"2026-10-16T00:46:44.523564Z","end":"2026-10-16T00:46:44.740264Z","steps":["trace[1711393345] 'process raft request' (duration: 216.517776ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:46:44.740522Z","caller":"traceutil/trace.go:171","msg":"trace[1128416121] linearizableReadLoop","detail":"{readStateIndex:20937; appliedIndex:20935; }","duration":"150.010948ms","start":"2026-10-16T00:46:44.590472Z","end":"2026-10-16T00:46:44.740483Z","steps":["trace[1128416121] 'read index received' (duration: 149.543706ms)","trace[1128416121] 'applied index is now lower than readState.Index' (duration: 463.272µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:46:44.740571Z","caller":"traceutil/trace.go:171","msg":"trace[1462225737] transaction","detail":"{read_only:false; response_revision:20249; number_of_response:1; }","duration":"216.855455ms","start":"2026-10-16T00:46:44.523704Z","end":"2026-10-16T00:46:44.74056Z","steps":["trace[1462225737] 'process raft request' (duration: 216.516947ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:46:44.74072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.215894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:46:44.740878Z","caller":"traceutil/trace.go:171","msg":"trace[389877669] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:20249; }","duration":"150.417029ms","start":"2026-10-16T00:46:44.590387Z","end":"2026-10-16T00:46:44.740804Z","steps":["trace[389877669] 'agreement among raft nodes before linearized reading' (duration: 150.238475ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:46:55.222569Z","caller":"traceutil/trace.go:171","msg":"trace[643554113] transaction","detail":"{read_only:false; response_revision:20304; number_of_response:1; }","duration":"105.155784ms","start":"2026-10-16T00:46:55.117378Z","end":"2026-10-16T00:46:55.222533Z","steps":["trace[643554113] 'process raft request' (duration: 104.878777ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:47:00.809039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.740586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:47:00.809129Z","caller":"traceutil/trace.go:171","msg":"trace[892822276] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20329; }","duration":"117.891139ms","start":"2026-10-16T00:47:00.691212Z","end":"2026-10-16T00:47:00.809103Z","steps":["trace[892822276] 'range keys from in-memory index tree' (duration: 117.600921ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:47:00.943998Z","caller":"traceutil/trace.go:171","msg":"trace[654495871] linearizableReadLoop","detail":"{readStateIndex:21021; appliedIndex:21020; }","duration":"120.726331ms","start":"2026-10-16T00:47:00.823243Z","end":"2026-10-16T00:47:00.94397Z","steps":["trace[654495871] 'read index received' (duration: 120.520336ms)","trace[654495871] 'applied index is now lower than readState.Index' (duration: 204.685µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:47:00.944045Z","caller":"traceutil/trace.go:171","msg":"trace[1183719591] transaction","detail":"{read_only:false; response_revision:20330; number_of_response:1; }","duration":"124.426605ms","start":"2026-10-16T00:47:00.819574Z","end":"2026-10-16T00:47:00.944Z","steps":["trace[1183719591] 'process raft request' (duration: 124.201879ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:47:00.944393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.08406ms","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-16T00:47:00.944468Z","caller":"traceutil/trace.go:171","msg":"trace[192047173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20330; }","duration":"121.243515ms","start":"2026-10-16T00:47:00.823212Z","end":"2026-10-16T00:47:00.944455Z","steps":["trace[192047173] 'agreement among raft nodes before linearized reading' (duration: 120.895336ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:47:01.113201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.432389ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-16T00:47:01.113639Z","caller":"traceutil/trace.go:171","msg":"trace[693613288] transaction","detail":"{read_only:false; response_revision:20331; number_of_response:1; }","duration":"264.816769ms","start":"2026-10-16T00:47:00.848804Z","end":"2026-10-16T00:47:01.113621Z","steps":["trace[693613288] 'process raft request' (duration: 264.500081ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:47:01.114001Z","caller":"traceutil/trace.go:171","msg":"trace[68024519] linearizableReadLoop","detail":"{readStateIndex:21024; appliedIndex:21021; }","duration":"169.886756ms","start":"2026-10-16T00:47:00.944073Z","end":"2026-10-16T00:47:01.11396Z","steps":["trace[68024519] 'read index received' (duration: 681.237µs)","trace[68024519] 'applied index is now lower than readState.Index' (duration: 169.203889ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:47:01.114185Z","caller":"traceutil/trace.go:171","msg":"trace[1890163921] transaction","detail":"{read_only:false; response_revision:20332; number_of_response:1; }","duration":"230.506533ms","start":"2026-10-16T00:47:00.883658Z","end":"2026-10-16T00:47:01.114165Z","steps":["trace[1890163921] 'process raft request' (duration: 229.919998ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:47:01.11478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.057351ms","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-16T00:47:01.114833Z","caller":"traceutil/trace.go:171","msg":"trace[403822438] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20332; }","duration":"277.134463ms","start":"2026-10-16T00:47:00.837683Z","end":"2026-10-16T00:47:01.114818Z","steps":["trace[403822438] 'agreement among raft nodes before linearized reading' (duration: 276.412245ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:47:01.124423Z","caller":"traceutil/trace.go:171","msg":"trace[1077000925] transaction","detail":"{read_only:false; response_revision:20333; number_of_response:1; }","duration":"174.589346ms","start":"2026-10-16T00:47:00.949795Z","end":"2026-10-16T00:47:01.124384Z","steps":["trace[1077000925] 'process raft request' (duration: 174.319179ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:47:01.379158Z","caller":"traceutil/trace.go:171","msg":"trace[2056931342] transaction","detail":"{read_only:false; response_revision:20335; number_of_response:1; }","duration":"136.468653ms","start":"2026-10-16T00:47:01.242649Z","end":"2026-10-16T00:47:01.379118Z","steps":["trace[2056931342] 'process raft request' (duration: 111.121306ms)","trace[2056931342] 'compare' (duration: 24.722941ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:50:50.051213Z","caller":"traceutil/trace.go:171","msg":"trace[1641578027] transaction","detail":"{read_only:false; response_revision:22740; number_of_response:1; }","duration":"223.981164ms","start":"2026-10-16T00:50:49.827203Z","end":"2026-10-16T00:50:50.051185Z","steps":["trace[1641578027] 'process raft request' (duration: 223.775759ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:51:02.033834Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20022} {"level":"info","ts":"2026-10-16T00:51:02.157679Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20022,"took":"120.090459ms","hash":2236624228,"current-db-size-bytes":56107008,"current-db-size":"56 MB","current-db-size-in-use-bytes":41467904,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-10-16T00:51:02.157782Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2236624228,"revision":20022,"compact-revision":17490} {"level":"warn","ts":"2026-10-16T00:52:06.450751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.986555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:52:06.450835Z","caller":"traceutil/trace.go:171","msg":"trace[1539558619] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:23600; }","duration":"109.115708ms","start":"2026-10-16T00:52:06.341701Z","end":"2026-10-16T00:52:06.450817Z","steps":["trace[1539558619] 'agreement among raft nodes before linearized reading' (duration: 108.962554ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:52:06.45085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.297433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:52:06.4509Z","caller":"traceutil/trace.go:171","msg":"trace[1913379262] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:23600; }","duration":"109.427686ms","start":"2026-10-16T00:52:06.341458Z","end":"2026-10-16T00:52:06.450885Z","steps":["trace[1913379262] 'agreement among raft nodes before linearized reading' (duration: 109.355694ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:52:06.451198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.677642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:52:06.451294Z","caller":"traceutil/trace.go:171","msg":"trace[1845107595] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:23600; }","duration":"109.776935ms","start":"2026-10-16T00:52:06.341496Z","end":"2026-10-16T00:52:06.451273Z","steps":["trace[1845107595] 'agreement among raft nodes before linearized reading' (duration: 109.655382ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:53:56.655357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.692313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-16T00:53:56.655464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.221178ms","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-16T00:53:56.655547Z","caller":"traceutil/trace.go:171","msg":"trace[1519074693] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24624; }","duration":"121.367281ms","start":"2026-10-16T00:53:56.534162Z","end":"2026-10-16T00:53:56.655529Z","steps":["trace[1519074693] 'count revisions from in-memory index tree' (duration: 121.127975ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:53:56.655424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.38557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-16T00:53:56.655564Z","caller":"traceutil/trace.go:171","msg":"trace[1616434684] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24624; }","duration":"217.920649ms","start":"2026-10-16T00:53:56.437587Z","end":"2026-10-16T00:53:56.655507Z","steps":["trace[1616434684] 'range keys from in-memory index tree' (duration: 217.517859ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:53:56.655658Z","caller":"traceutil/trace.go:171","msg":"trace[1988130438] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24624; }","duration":"198.644646ms","start":"2026-10-16T00:53:56.456983Z","end":"2026-10-16T00:53:56.655628Z","steps":["trace[1988130438] 'range keys from in-memory index tree' (duration: 198.227776ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:53:56.955601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.203073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T00:53:56.95572Z","caller":"traceutil/trace.go:171","msg":"trace[1787617573] transaction","detail":"{read_only:false; response_revision:24626; number_of_response:1; }","duration":"294.099256ms","start":"2026-10-16T00:53:56.661595Z","end":"2026-10-16T00:53:56.955695Z","steps":["trace[1787617573] 'process raft request' (duration: 189.697718ms)","trace[1787617573] 'compare' (duration: 103.919296ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:53:57.185188Z","caller":"traceutil/trace.go:171","msg":"trace[1437212999] transaction","detail":"{read_only:false; response_revision:24627; number_of_response:1; }","duration":"362.3264ms","start":"2026-10-16T00:53:56.822827Z","end":"2026-10-16T00:53:57.185154Z","steps":["trace[1437212999] 'process raft request' (duration: 362.138015ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:53:57.186247Z","caller":"traceutil/trace.go:171","msg":"trace[1488925004] transaction","detail":"{read_only:false; response_revision:24628; number_of_response:1; }","duration":"208.029126ms","start":"2026-10-16T00:53:56.97819Z","end":"2026-10-16T00:53:57.18622Z","steps":["trace[1488925004] 'process raft request' (duration: 207.81229ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:53:57.186619Z","caller":"traceutil/trace.go:171","msg":"trace[2026868233] transaction","detail":"{read_only:false; response_revision:24629; number_of_response:1; }","duration":"207.988166ms","start":"2026-10-16T00:53:56.978611Z","end":"2026-10-16T00:53:57.186599Z","steps":["trace[2026868233] 'process raft request' (duration: 207.555985ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:53:57.186841Z","caller":"traceutil/trace.go:171","msg":"trace[99489994] transaction","detail":"{read_only:false; response_revision:24630; number_of_response:1; }","duration":"207.809781ms","start":"2026-10-16T00:53:56.979003Z","end":"2026-10-16T00:53:57.186812Z","steps":["trace[99489994] 'process raft request' (duration: 207.440502ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:53:57.186161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:53:56.822802Z","time spent":"362.475253ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:55:00.665828Z","caller":"traceutil/trace.go:171","msg":"trace[448400387] transaction","detail":"{read_only:false; response_revision:25203; number_of_response:1; }","duration":"124.128202ms","start":"2026-10-16T00:55:00.541391Z","end":"2026-10-16T00:55:00.665519Z","steps":["trace[448400387] 'process raft request' (duration: 123.875686ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:55:01.001784Z","caller":"traceutil/trace.go:171","msg":"trace[125954142] transaction","detail":"{read_only:false; response_revision:25204; number_of_response:1; }","duration":"400.377292ms","start":"2026-10-16T00:55:00.601348Z","end":"2026-10-16T00:55:01.001725Z","steps":["trace[125954142] 'process raft request' (duration: 316.988951ms)","trace[125954142] 'compare' (duration: 82.98879ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:55:01.001969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:00.601318Z","time spent":"400.556336ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:55:01.001963Z","caller":"traceutil/trace.go:171","msg":"trace[2032673904] linearizableReadLoop","detail":"{readStateIndex:26028; appliedIndex:26026; }","duration":"377.6194ms","start":"2026-10-16T00:55:00.624307Z","end":"2026-10-16T00:55:01.001926Z","steps":["trace[2032673904] 'read index received' (duration: 40.978187ms)","trace[2032673904] 'applied index is now lower than readState.Index' (duration: 336.639393ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:55:01.222497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.154765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:55:01.222598Z","caller":"traceutil/trace.go:171","msg":"trace[252480341] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:25204; }","duration":"598.315049ms","start":"2026-10-16T00:55:00.624254Z","end":"2026-10-16T00:55:01.222569Z","steps":["trace[252480341] 'agreement among raft nodes before linearized reading' (duration: 377.831875ms)","trace[252480341] 'count revisions from in-memory index tree' (duration: 220.31291ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:55:01.222645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:00.624235Z","time spent":"598.397732ms","remote":"127.0.0.1:47902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-10-16T00:55:01.222667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.933796ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-16T00:55:01.222813Z","caller":"traceutil/trace.go:171","msg":"trace[1273622297] linearizableReadLoop","detail":"{readStateIndex:26029; appliedIndex:26028; }","duration":"220.771332ms","start":"2026-10-16T00:55:01.002024Z","end":"2026-10-16T00:55:01.222795Z","steps":["trace[1273622297] 'read index received' (duration: 80.992µs)","trace[1273622297] 'applied index is now lower than readState.Index' (duration: 220.68883ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:55:01.222932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.474437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:55:01.222964Z","caller":"traceutil/trace.go:171","msg":"trace[1438933625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25204; }","duration":"532.546619ms","start":"2026-10-16T00:55:00.690408Z","end":"2026-10-16T00:55:01.222955Z","steps":["trace[1438933625] 'agreement among raft nodes before linearized reading' (duration: 532.472297ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:01.222995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:00.690393Z","time spent":"532.59478ms","remote":"127.0.0.1:44980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-16T00:55:01.503059Z","caller":"traceutil/trace.go:171","msg":"trace[1038909469] transaction","detail":"{read_only:false; response_revision:25205; number_of_response:1; }","duration":"392.042288ms","start":"2026-10-16T00:55:01.110991Z","end":"2026-10-16T00:55:01.503034Z","steps":["trace[1038909469] 'process raft request' (duration: 391.874504ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:01.503185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:01.110964Z","time spent":"392.165802ms","remote":"127.0.0.1:38820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2297,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-16T00:55:01.503535Z","caller":"traceutil/trace.go:171","msg":"trace[606441313] linearizableReadLoop","detail":"{readStateIndex:26030; appliedIndex:26030; }","duration":"280.640932ms","start":"2026-10-16T00:55:01.222845Z","end":"2026-10-16T00:55:01.503486Z","steps":["trace[606441313] 'read index received' (duration: 280.619971ms)","trace[606441313] 'applied index is now lower than readState.Index' (duration: 16.831µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:55:01.503699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.337705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-16T00:55:01.503752Z","caller":"traceutil/trace.go:171","msg":"trace[108523655] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25205; }","duration":"489.425267ms","start":"2026-10-16T00:55:01.014315Z","end":"2026-10-16T00:55:01.50374Z","steps":["trace[108523655] 'agreement among raft nodes before linearized reading' (duration: 489.254063ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:01.503797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:01.014297Z","time spent":"489.491469ms","remote":"127.0.0.1:45290","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-16T00:55:01.812538Z","caller":"traceutil/trace.go:171","msg":"trace[1102068250] transaction","detail":"{read_only:false; response_revision:25206; number_of_response:1; }","duration":"378.928263ms","start":"2026-10-16T00:55:01.433568Z","end":"2026-10-16T00:55:01.812497Z","steps":["trace[1102068250] 'process raft request' (duration: 367.394089ms)","trace[1102068250] 'compare' (duration: 11.209816ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:55:01.812687Z","caller":"traceutil/trace.go:171","msg":"trace[2119089799] transaction","detail":"{read_only:false; response_revision:25207; number_of_response:1; }","duration":"376.952373ms","start":"2026-10-16T00:55:01.435711Z","end":"2026-10-16T00:55:01.812663Z","steps":["trace[2119089799] 'process raft request' (duration: 376.629654ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:01.812849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:01.435687Z","time spent":"377.065866ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:55:01.812708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:01.433542Z","time spent":"379.069917ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:55:01.812985Z","caller":"traceutil/trace.go:171","msg":"trace[2106668015] transaction","detail":"{read_only:false; response_revision:25208; number_of_response:1; }","duration":"373.414892ms","start":"2026-10-16T00:55:01.43954Z","end":"2026-10-16T00:55:01.812955Z","steps":["trace[2106668015] 'process raft request' (duration: 373.011022ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:01.813166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:01.439531Z","time spent":"373.535126ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:55:01.813281Z","caller":"traceutil/trace.go:171","msg":"trace[1640074075] linearizableReadLoop","detail":"{readStateIndex:26034; appliedIndex:26030; }","duration":"309.651193ms","start":"2026-10-16T00:55:01.503617Z","end":"2026-10-16T00:55:01.813268Z","steps":["trace[1640074075] 'read index received' (duration: 297.597865ms)","trace[1640074075] 'applied index is now lower than readState.Index' (duration: 12.052578ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T00:55:01.813477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.570668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T00:55:01.813518Z","caller":"traceutil/trace.go:171","msg":"trace[1597682749] transaction","detail":"{read_only:false; response_revision:25209; number_of_response:1; }","duration":"359.144567ms","start":"2026-10-16T00:55:01.454363Z","end":"2026-10-16T00:55:01.813507Z","steps":["trace[1597682749] 'process raft request' (duration: 358.256855ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:55:01.813523Z","caller":"traceutil/trace.go:171","msg":"trace[292151287] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:25209; }","duration":"584.64664ms","start":"2026-10-16T00:55:01.228863Z","end":"2026-10-16T00:55:01.81351Z","steps":["trace[292151287] 'agreement among raft nodes before linearized reading' (duration: 584.572528ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:01.813556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:01.22885Z","time spent":"584.697111ms","remote":"127.0.0.1:35210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-16T00:55:01.813575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:01.454342Z","time spent":"359.201369ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:55:01.81374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.466583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-16T00:55:01.813759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.584952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-10-16T00:55:01.813794Z","caller":"traceutil/trace.go:171","msg":"trace[941951954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25209; }","duration":"589.557255ms","start":"2026-10-16T00:55:01.224228Z","end":"2026-10-16T00:55:01.813785Z","steps":["trace[941951954] 'agreement among raft nodes before linearized reading' (duration: 589.485173ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:55:01.813832Z","caller":"traceutil/trace.go:171","msg":"trace[264225787] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:25209; }","duration":"347.634264ms","start":"2026-10-16T00:55:01.466165Z","end":"2026-10-16T00:55:01.813799Z","steps":["trace[264225787] 'agreement among raft nodes before linearized reading' (duration: 347.524591ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:01.81384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:01.224207Z","time spent":"589.626397ms","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-16T00:55:01.813861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:01.466145Z","time spent":"347.708245ms","remote":"127.0.0.1:45168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1880,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" "} {"level":"warn","ts":"2026-10-16T00:55:01.813482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.122544ms","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-16T00:55:01.813994Z","caller":"traceutil/trace.go:171","msg":"trace[1304437139] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25209; }","duration":"553.664578ms","start":"2026-10-16T00:55:01.26032Z","end":"2026-10-16T00:55:01.813985Z","steps":["trace[1304437139] 'agreement among raft nodes before linearized reading' (duration: 553.067753ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:01.814025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:01.260302Z","time spent":"553.71341ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-16T00:55:02.079283Z","caller":"traceutil/trace.go:171","msg":"trace[631540253] transaction","detail":"{read_only:false; response_revision:25210; number_of_response:1; }","duration":"571.896724ms","start":"2026-10-16T00:55:01.507322Z","end":"2026-10-16T00:55:02.079219Z","steps":["trace[631540253] 'process raft request' (duration: 503.284921ms)","trace[631540253] 'compare' (duration: 68.343397ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:55:02.079806Z","caller":"traceutil/trace.go:171","msg":"trace[829802420] transaction","detail":"{read_only:false; response_revision:25211; number_of_response:1; }","duration":"564.493135ms","start":"2026-10-16T00:55:01.515263Z","end":"2026-10-16T00:55:02.079757Z","steps":["trace[829802420] 'process raft request' (duration: 563.851169ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:02.079948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:01.507294Z","time spent":"572.573662ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:55:02.079953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:01.515235Z","time spent":"564.641909ms","remote":"127.0.0.1:38784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2880,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-16T00:55:02.08063Z","caller":"traceutil/trace.go:171","msg":"trace[108392133] linearizableReadLoop","detail":"{readStateIndex:26038; appliedIndex:26034; }","duration":"267.295481ms","start":"2026-10-16T00:55:01.813313Z","end":"2026-10-16T00:55:02.080609Z","steps":["trace[108392133] 'read index received' (duration: 197.310672ms)","trace[108392133] 'applied index is now lower than readState.Index' (duration: 69.983519ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T00:55:02.080825Z","caller":"traceutil/trace.go:171","msg":"trace[1741410093] transaction","detail":"{read_only:false; response_revision:25213; number_of_response:1; }","duration":"373.219898ms","start":"2026-10-16T00:55:01.707583Z","end":"2026-10-16T00:55:02.080803Z","steps":["trace[1741410093] 'process raft request' (duration: 372.91547ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:02.080957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:01.707557Z","time spent":"373.3314ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T00:55:02.080952Z","caller":"traceutil/trace.go:171","msg":"trace[1124292726] transaction","detail":"{read_only:false; response_revision:25214; number_of_response:1; }","duration":"261.517203ms","start":"2026-10-16T00:55:01.819418Z","end":"2026-10-16T00:55:02.080935Z","steps":["trace[1124292726] 'process raft request' (duration: 261.158194ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:02.080991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.571693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T00:55:02.081104Z","caller":"traceutil/trace.go:171","msg":"trace[664172896] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:25214; }","duration":"439.717067ms","start":"2026-10-16T00:55:01.641374Z","end":"2026-10-16T00:55:02.081091Z","steps":["trace[664172896] 'agreement among raft nodes before linearized reading' (duration: 439.374268ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:02.081251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.170972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-16T00:55:02.081268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:01.641354Z","time spent":"439.84256ms","remote":"127.0.0.1:39544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true "} {"level":"info","ts":"2026-10-16T00:55:02.081295Z","caller":"traceutil/trace.go:171","msg":"trace[1473710692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25214; }","duration":"464.249973ms","start":"2026-10-16T00:55:01.617035Z","end":"2026-10-16T00:55:02.081285Z","steps":["trace[1473710692] 'agreement among raft nodes before linearized reading' (duration: 463.72123ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:02.081324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:01.617012Z","time spent":"464.305105ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-16T00:55:02.081391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.37387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-069f9a89-ab3e-407e-bb01-9bb66ed7f908\" ","response":"range_response_count:1 size:1320"} {"level":"info","ts":"2026-10-16T00:55:02.081441Z","caller":"traceutil/trace.go:171","msg":"trace[1729864263] range","detail":"{range_begin:/registry/persistentvolumes/pvc-069f9a89-ab3e-407e-bb01-9bb66ed7f908; range_end:; response_count:1; response_revision:25215; }","duration":"263.467303ms","start":"2026-10-16T00:55:01.817959Z","end":"2026-10-16T00:55:02.081427Z","steps":["trace[1729864263] 'agreement among raft nodes before linearized reading' (duration: 263.284628ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:02.08148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.049572ms","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-16T00:55:02.081524Z","caller":"traceutil/trace.go:171","msg":"trace[184615338] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25215; }","duration":"245.102584ms","start":"2026-10-16T00:55:01.836405Z","end":"2026-10-16T00:55:02.081508Z","steps":["trace[184615338] 'agreement among raft nodes before linearized reading' (duration: 244.990191ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:02.081471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.030655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-16T00:55:02.081587Z","caller":"traceutil/trace.go:171","msg":"trace[758730123] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25214; }","duration":"440.192069ms","start":"2026-10-16T00:55:01.64137Z","end":"2026-10-16T00:55:02.081562Z","steps":["trace[758730123] 'agreement among raft nodes before linearized reading' (duration: 439.623655ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:02.081622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.024279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-16T00:55:02.081644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:01.641328Z","time spent":"440.306252ms","remote":"127.0.0.1:45290","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":"info","ts":"2026-10-16T00:55:02.081653Z","caller":"traceutil/trace.go:171","msg":"trace[504488269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25215; }","duration":"259.0746ms","start":"2026-10-16T00:55:01.822569Z","end":"2026-10-16T00:55:02.081644Z","steps":["trace[504488269] 'agreement among raft nodes before linearized reading' (duration: 259.023209ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:55:02.081867Z","caller":"traceutil/trace.go:171","msg":"trace[1823685935] transaction","detail":"{read_only:false; response_revision:25212; number_of_response:1; }","duration":"426.301224ms","start":"2026-10-16T00:55:01.654383Z","end":"2026-10-16T00:55:02.080684Z","steps":["trace[1823685935] 'process raft request' (duration: 424.796165ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:02.082277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:01.654348Z","time spent":"427.845353ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T00:55:02.080875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.120926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-10-16T00:55:02.081628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.103928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T00:55:02.082929Z","caller":"traceutil/trace.go:171","msg":"trace[846680034] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:25215; }","duration":"227.473553ms","start":"2026-10-16T00:55:01.855402Z","end":"2026-10-16T00:55:02.082876Z","steps":["trace[846680034] 'agreement among raft nodes before linearized reading' (duration: 225.948644ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T00:55:02.082968Z","caller":"traceutil/trace.go:171","msg":"trace[964771855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25214; }","duration":"539.088076ms","start":"2026-10-16T00:55:01.543698Z","end":"2026-10-16T00:55:02.082786Z","steps":["trace[964771855] 'agreement among raft nodes before linearized reading' (duration: 537.097676ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T00:55:02.083102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:55:01.543678Z","time spent":"539.407204ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-16T00:56:02.04012Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22848} {"level":"info","ts":"2026-10-16T00:56:02.189068Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22848,"took":"140.741657ms","hash":3087650016,"current-db-size-bytes":56950784,"current-db-size":"57 MB","current-db-size-in-use-bytes":35323904,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-16T00:56:02.189167Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3087650016,"revision":22848,"compact-revision":20022} {"level":"info","ts":"2026-10-16T00:56:23.81883Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068f7.wal"} {"level":"info","ts":"2026-10-16T01:01:02.049306Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25840} {"level":"info","ts":"2026-10-16T01:01:02.217839Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25840,"took":"163.723405ms","hash":422598695,"current-db-size-bytes":57794560,"current-db-size":"58 MB","current-db-size-in-use-bytes":34557952,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-16T01:01:02.217942Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":422598695,"revision":25840,"compact-revision":22848} {"level":"info","ts":"2026-10-16T01:02:07.547991Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"40fb1eca294b6a5a","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T01:02:07.554255Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-16T01:02:07.554388Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-16T01:06:02.058473Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28602} {"level":"info","ts":"2026-10-16T01:06:02.208487Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28602,"took":"145.155521ms","hash":328036546,"current-db-size-bytes":57794560,"current-db-size":"58 MB","current-db-size-in-use-bytes":30203904,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-16T01:06:02.208568Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":328036546,"revision":28602,"compact-revision":25840} {"level":"info","ts":"2026-10-16T01:11:02.066341Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30854} {"level":"info","ts":"2026-10-16T01:11:02.185947Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30854,"took":"116.382456ms","hash":3594072146,"current-db-size-bytes":57794560,"current-db-size":"58 MB","current-db-size-in-use-bytes":31211520,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T01:11:02.186011Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3594072146,"revision":30854,"compact-revision":28602} {"level":"info","ts":"2026-10-16T01:14:14.831385Z","caller":"traceutil/trace.go:171","msg":"trace[417300326] transaction","detail":"{read_only:false; response_revision:34580; number_of_response:1; }","duration":"101.782732ms","start":"2026-10-16T01:14:14.72956Z","end":"2026-10-16T01:14:14.831343Z","steps":["trace[417300326] 'process raft request' (duration: 39.538951ms)","trace[417300326] 'compare' (duration: 61.737568ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T01:14:20.111532Z","caller":"traceutil/trace.go:171","msg":"trace[985016717] transaction","detail":"{read_only:false; response_revision:34617; number_of_response:1; }","duration":"140.585865ms","start":"2026-10-16T01:14:19.970916Z","end":"2026-10-16T01:14:20.111502Z","steps":["trace[985016717] 'process raft request' (duration: 53.57601ms)","trace[985016717] 'compare' (duration: 86.615275ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T01:16:02.072807Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33118} {"level":"info","ts":"2026-10-16T01:16:02.236016Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33118,"took":"160.084443ms","hash":1865312672,"current-db-size-bytes":58839040,"current-db-size":"59 MB","current-db-size-in-use-bytes":31170560,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T01:16:02.236089Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1865312672,"revision":33118,"compact-revision":30854} {"level":"info","ts":"2026-10-16T01:21:02.079648Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35407} {"level":"info","ts":"2026-10-16T01:21:02.222015Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35407,"took":"137.30035ms","hash":554082901,"current-db-size-bytes":59744256,"current-db-size":"60 MB","current-db-size-in-use-bytes":32894976,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-16T01:21:02.222084Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":554082901,"revision":35407,"compact-revision":33118} {"level":"info","ts":"2026-10-16T01:23:34.411712Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"40fb1eca294b6a5a","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T01:23:34.417907Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-16T01:23:34.41798Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-16T01:26:02.087927Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37636} {"level":"info","ts":"2026-10-16T01:26:02.220856Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37636,"took":"128.415415ms","hash":239733686,"current-db-size-bytes":59744256,"current-db-size":"60 MB","current-db-size-in-use-bytes":31330304,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T01:26:02.220943Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":239733686,"revision":37636,"compact-revision":35407}