{"level":"warn","ts":"2026-09-08T15:48:35.96523Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-08T15:48:35.965374Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.165: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.165:2380","--initial-cluster=instance=https://162.253.55.165:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.165:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.165:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-08T15:48:35.96551Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-08T15:48:35.965533Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.165:2380"]} {"level":"info","ts":"2026-09-08T15:48:35.965566Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-08T15:48:35.967041Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.165:2379"]} {"level":"info","ts":"2026-09-08T15:48:35.967202Z","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.165:2380"],"listen-peer-urls":["https://162.253.55.165:2380"],"advertise-client-urls":["https://162.253.55.165:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.165:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.165:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-08T15:48:35.973565Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.918236ms"} {"level":"info","ts":"2026-09-08T15:48:35.98074Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e9d471e5f5fb9faf","cluster-id":"fb4c33c289aeb291"} {"level":"info","ts":"2026-09-08T15:48:35.980864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf switched to configuration voters=()"} {"level":"info","ts":"2026-09-08T15:48:35.980897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf became follower at term 0"} {"level":"info","ts":"2026-09-08T15:48:35.980919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e9d471e5f5fb9faf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-08T15:48:35.980933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf became follower at term 1"} {"level":"info","ts":"2026-09-08T15:48:35.981002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf switched to configuration voters=(16849217338388225967)"} {"level":"warn","ts":"2026-09-08T15:48:35.990205Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-08T15:48:35.992443Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-08T15:48:35.994473Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-08T15:48:35.998078Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e9d471e5f5fb9faf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-08T15:48:35.998282Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e9d471e5f5fb9faf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-08T15:48:35.998394Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-08T15:48:35.998476Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-08T15:48:35.998503Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-08T15:48:36.000031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf switched to configuration voters=(16849217338388225967)"} {"level":"info","ts":"2026-09-08T15:48:36.001266Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb4c33c289aeb291","local-member-id":"e9d471e5f5fb9faf","added-peer-id":"e9d471e5f5fb9faf","added-peer-peer-urls":["https://162.253.55.165:2380"]} {"level":"info","ts":"2026-09-08T15:48:36.004064Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-08T15:48:36.004204Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.165:2380"} {"level":"info","ts":"2026-09-08T15:48:36.004262Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.165:2380"} {"level":"info","ts":"2026-09-08T15:48:36.004901Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e9d471e5f5fb9faf","initial-advertise-peer-urls":["https://162.253.55.165:2380"],"listen-peer-urls":["https://162.253.55.165:2380"],"advertise-client-urls":["https://162.253.55.165:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.165:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-08T15:48:36.004958Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-08T15:48:36.882018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf is starting a new election at term 1"} {"level":"info","ts":"2026-09-08T15:48:36.882121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf became pre-candidate at term 1"} {"level":"info","ts":"2026-09-08T15:48:36.882176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf received MsgPreVoteResp from e9d471e5f5fb9faf at term 1"} {"level":"info","ts":"2026-09-08T15:48:36.882197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf became candidate at term 2"} {"level":"info","ts":"2026-09-08T15:48:36.882207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf received MsgVoteResp from e9d471e5f5fb9faf at term 2"} {"level":"info","ts":"2026-09-08T15:48:36.882223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9d471e5f5fb9faf became leader at term 2"} {"level":"info","ts":"2026-09-08T15:48:36.882237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e9d471e5f5fb9faf elected leader e9d471e5f5fb9faf at term 2"} {"level":"info","ts":"2026-09-08T15:48:36.883572Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:48:36.883861Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e9d471e5f5fb9faf","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.165:2379]}","request-path":"/0/members/e9d471e5f5fb9faf/attributes","cluster-id":"fb4c33c289aeb291","publish-timeout":"7s"} {"level":"info","ts":"2026-09-08T15:48:36.883932Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T15:48:36.883964Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T15:48:36.884253Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-08T15:48:36.884528Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-08T15:48:36.884968Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb4c33c289aeb291","local-member-id":"e9d471e5f5fb9faf","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:48:36.885114Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:48:36.88515Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:48:36.887332Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.165:2379"} {"level":"info","ts":"2026-09-08T15:48:36.889095Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-08T15:49:20.605167Z","caller":"traceutil/trace.go:171","msg":"trace[1947738034] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"246.22564ms","start":"2026-09-08T15:49:20.35891Z","end":"2026-09-08T15:49:20.605135Z","steps":["trace[1947738034] 'read index received' (duration: 245.163543ms)","trace[1947738034] 'applied index is now lower than readState.Index' (duration: 1.061217ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:20.605378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.450446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.165\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-08T15:49:20.605463Z","caller":"traceutil/trace.go:171","msg":"trace[340550428] range","detail":"{range_begin:/registry/masterleases/162.253.55.165; range_end:; response_count:1; response_revision:580; }","duration":"246.60373ms","start":"2026-09-08T15:49:20.358819Z","end":"2026-09-08T15:49:20.605423Z","steps":["trace[340550428] 'agreement among raft nodes before linearized reading' (duration: 246.443916ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:20.605473Z","caller":"traceutil/trace.go:171","msg":"trace[1675678698] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"296.145859ms","start":"2026-09-08T15:49:20.309279Z","end":"2026-09-08T15:49:20.605425Z","steps":["trace[1675678698] 'process raft request' (duration: 295.245465ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:20.867286Z","caller":"traceutil/trace.go:171","msg":"trace[1228882194] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:604; }","duration":"185.901337ms","start":"2026-09-08T15:49:20.68135Z","end":"2026-09-08T15:49:20.867251Z","steps":["trace[1228882194] 'read index received' (duration: 103.823904ms)","trace[1228882194] 'applied index is now lower than readState.Index' (duration: 82.076253ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:20.867627Z","caller":"traceutil/trace.go:171","msg":"trace[2122393072] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"497.595065ms","start":"2026-09-08T15:49:20.369796Z","end":"2026-09-08T15:49:20.867391Z","steps":["trace[2122393072] 'process raft request' (duration: 415.356527ms)","trace[2122393072] 'compare' (duration: 81.468378ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:20.867658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.292267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:49:20.867778Z","caller":"traceutil/trace.go:171","msg":"trace[1110017189] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:581; }","duration":"186.41116ms","start":"2026-09-08T15:49:20.681285Z","end":"2026-09-08T15:49:20.867696Z","steps":["trace[1110017189] 'agreement among raft nodes before linearized reading' (duration: 186.304267ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:20.868422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:20.369745Z","time spent":"497.959124ms","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:49:21.578629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.009597ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:49:21.579131Z","caller":"traceutil/trace.go:171","msg":"trace[1505818854] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:606; }","duration":"594.122696ms","start":"2026-09-08T15:49:20.984968Z","end":"2026-09-08T15:49:21.579091Z","steps":["trace[1505818854] 'read index received' (duration: 183.618737ms)","trace[1505818854] 'applied index is now lower than readState.Index' (duration: 410.503079ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:21.579147Z","caller":"traceutil/trace.go:171","msg":"trace[1963033804] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"709.481773ms","start":"2026-09-08T15:49:20.869621Z","end":"2026-09-08T15:49:21.579103Z","steps":["trace[1963033804] 'process raft request' (duration: 298.733848ms)","trace[1963033804] 'compare' (duration: 409.74284ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:21.579181Z","caller":"traceutil/trace.go:171","msg":"trace[1482297918] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"695.345132ms","start":"2026-09-08T15:49:20.883794Z","end":"2026-09-08T15:49:21.579139Z","steps":["trace[1482297918] 'process raft request' (duration: 694.921501ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:21.57932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:20.869598Z","time spent":"709.645257ms","remote":"127.0.0.1:58918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:49:21.579444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:20.88376Z","time spent":"695.510787ms","remote":"127.0.0.1:59166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:49:21.579801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.822375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-08T15:49:21.579962Z","caller":"traceutil/trace.go:171","msg":"trace[1129997848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:583; }","duration":"595.032781ms","start":"2026-09-08T15:49:20.984907Z","end":"2026-09-08T15:49:21.579939Z","steps":["trace[1129997848] 'agreement among raft nodes before linearized reading' (duration: 594.569608ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:21.580067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:20.984881Z","time spent":"595.169634ms","remote":"127.0.0.1:59166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-08T15:49:21.590884Z","caller":"traceutil/trace.go:171","msg":"trace[1037612889] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"306.373887ms","start":"2026-09-08T15:49:21.284474Z","end":"2026-09-08T15:49:21.590848Z","steps":["trace[1037612889] 'process raft request' (duration: 305.621297ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:21.591078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.626327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:49:21.591155Z","caller":"traceutil/trace.go:171","msg":"trace[926151419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"409.76039ms","start":"2026-09-08T15:49:21.181376Z","end":"2026-09-08T15:49:21.591136Z","steps":["trace[926151419] 'agreement among raft nodes before linearized reading' (duration: 409.583325ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:21.591087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:21.284445Z","time spent":"306.532361ms","remote":"127.0.0.1:58978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036101,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:49:21.591195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:21.181344Z","time spent":"409.845183ms","remote":"127.0.0.1:58886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-08T15:49:23.669797Z","caller":"traceutil/trace.go:171","msg":"trace[65087388] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"242.526753ms","start":"2026-09-08T15:49:23.427236Z","end":"2026-09-08T15:49:23.669763Z","steps":["trace[65087388] 'process raft request' (duration: 242.232815ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:23.710341Z","caller":"traceutil/trace.go:171","msg":"trace[869274716] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"110.609911ms","start":"2026-09-08T15:49:23.599693Z","end":"2026-09-08T15:49:23.710303Z","steps":["trace[869274716] 'process raft request' (duration: 110.477658ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:06.664217Z","caller":"traceutil/trace.go:171","msg":"trace[1059856816] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"169.669523ms","start":"2026-09-08T15:50:06.49451Z","end":"2026-09-08T15:50:06.664179Z","steps":["trace[1059856816] 'process raft request' (duration: 169.466927ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:54.893044Z","caller":"traceutil/trace.go:171","msg":"trace[1834837188] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"317.947328ms","start":"2026-09-08T15:50:54.575068Z","end":"2026-09-08T15:50:54.893015Z","steps":["trace[1834837188] 'process raft request' (duration: 317.764383ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:50:54.893229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:50:54.575039Z","time spent":"318.104172ms","remote":"127.0.0.1:59166","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-09-08T15:50:54.99125Z","caller":"traceutil/trace.go:171","msg":"trace[926913247] linearizableReadLoop","detail":"{readStateIndex:1903; appliedIndex:1901; }","duration":"297.701479ms","start":"2026-09-08T15:50:54.69345Z","end":"2026-09-08T15:50:54.991151Z","steps":["trace[926913247] 'read index received' (duration: 199.420279ms)","trace[926913247] 'applied index is now lower than readState.Index' (duration: 98.27984ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:50:54.991586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.82361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5cb6130f-2c00-4a2f-9a5c-8afc20a97c7d\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-08T15:50:54.991653Z","caller":"traceutil/trace.go:171","msg":"trace[371430207] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5cb6130f-2c00-4a2f-9a5c-8afc20a97c7d; range_end:; response_count:1; response_revision:1831; }","duration":"209.916073ms","start":"2026-09-08T15:50:54.7817Z","end":"2026-09-08T15:50:54.991616Z","steps":["trace[371430207] 'agreement among raft nodes before linearized reading' (duration: 209.686688ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:50:54.991582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.097319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-09-08T15:50:54.991681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.809708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5cb6130f-2c00-4a2f-9a5c-8afc20a97c7d\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-08T15:50:54.991773Z","caller":"traceutil/trace.go:171","msg":"trace[989835129] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5cb6130f-2c00-4a2f-9a5c-8afc20a97c7d; range_end:; response_count:1; response_revision:1831; }","duration":"172.990952ms","start":"2026-09-08T15:50:54.818754Z","end":"2026-09-08T15:50:54.991745Z","steps":["trace[989835129] 'agreement among raft nodes before linearized reading' (duration: 172.735005ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:54.991778Z","caller":"traceutil/trace.go:171","msg":"trace[2072007478] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1831; }","duration":"298.331686ms","start":"2026-09-08T15:50:54.6934Z","end":"2026-09-08T15:50:54.991732Z","steps":["trace[2072007478] 'agreement among raft nodes before linearized reading' (duration: 297.977137ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:54.991663Z","caller":"traceutil/trace.go:171","msg":"trace[170827580] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"354.6292ms","start":"2026-09-08T15:50:54.636941Z","end":"2026-09-08T15:50:54.99157Z","steps":["trace[170827580] 'process raft request' (duration: 312.49133ms)","trace[170827580] 'compare' (duration: 41.533364ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:50:54.991976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:50:54.636917Z","time spent":"354.941459ms","remote":"127.0.0.1:59166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:50:55.223344Z","caller":"traceutil/trace.go:171","msg":"trace[2134251896] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"223.916761ms","start":"2026-09-08T15:50:54.999394Z","end":"2026-09-08T15:50:55.223311Z","steps":["trace[2134251896] 'process raft request' (duration: 223.652954ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:51:09.0471Z","caller":"traceutil/trace.go:171","msg":"trace[1502215714] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"104.886351ms","start":"2026-09-08T15:51:08.942194Z","end":"2026-09-08T15:51:09.047081Z","steps":["trace[1502215714] 'process raft request' (duration: 104.769727ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:51:38.509798Z","caller":"traceutil/trace.go:171","msg":"trace[926778412] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"172.477858ms","start":"2026-09-08T15:51:38.337277Z","end":"2026-09-08T15:51:38.509755Z","steps":["trace[926778412] 'process raft request' (duration: 89.469155ms)","trace[926778412] 'compare' (duration: 82.477489ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:51:38.668678Z","caller":"traceutil/trace.go:171","msg":"trace[2112885230] linearizableReadLoop","detail":"{readStateIndex:2205; appliedIndex:2203; }","duration":"152.801737ms","start":"2026-09-08T15:51:38.515835Z","end":"2026-09-08T15:51:38.668637Z","steps":["trace[2112885230] 'read index received' (duration: 28.061014ms)","trace[2112885230] 'applied index is now lower than readState.Index' (duration: 124.738943ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:51:38.668924Z","caller":"traceutil/trace.go:171","msg":"trace[1411239002] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"153.358283ms","start":"2026-09-08T15:51:38.515524Z","end":"2026-09-08T15:51:38.668883Z","steps":["trace[1411239002] 'process raft request' (duration: 145.468521ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:51:38.669025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.150048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-09-08T15:51:38.669084Z","caller":"traceutil/trace.go:171","msg":"trace[1325964722] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2124; }","duration":"153.26149ms","start":"2026-09-08T15:51:38.515806Z","end":"2026-09-08T15:51:38.669068Z","steps":["trace[1325964722] 'agreement among raft nodes before linearized reading' (duration: 153.053444ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:51:38.669321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.11339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:123106"} {"level":"info","ts":"2026-09-08T15:51:38.66936Z","caller":"traceutil/trace.go:171","msg":"trace[553538955] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2124; }","duration":"152.197453ms","start":"2026-09-08T15:51:38.517152Z","end":"2026-09-08T15:51:38.66935Z","steps":["trace[553538955] 'agreement among raft nodes before linearized reading' (duration: 152.008418ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:51:38.669602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.745186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-09-08T15:51:38.669636Z","caller":"traceutil/trace.go:171","msg":"trace[920324301] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2124; }","duration":"104.773917ms","start":"2026-09-08T15:51:38.56485Z","end":"2026-09-08T15:51:38.669623Z","steps":["trace[920324301] 'agreement among raft nodes before linearized reading' (duration: 104.699475ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:51:38.669626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.174779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-08T15:51:38.669722Z","caller":"traceutil/trace.go:171","msg":"trace[910122784] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2124; }","duration":"124.239701ms","start":"2026-09-08T15:51:38.545436Z","end":"2026-09-08T15:51:38.669675Z","steps":["trace[910122784] 'agreement among raft nodes before linearized reading' (duration: 124.042895ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:51:44.491161Z","caller":"traceutil/trace.go:171","msg":"trace[1250530578] linearizableReadLoop","detail":"{readStateIndex:2236; appliedIndex:2235; }","duration":"150.182108ms","start":"2026-09-08T15:51:44.340958Z","end":"2026-09-08T15:51:44.49114Z","steps":["trace[1250530578] 'read index received' (duration: 150.078745ms)","trace[1250530578] 'applied index is now lower than readState.Index' (duration: 102.403µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:51:44.491238Z","caller":"traceutil/trace.go:171","msg":"trace[2082094986] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"232.064739ms","start":"2026-09-08T15:51:44.259136Z","end":"2026-09-08T15:51:44.491201Z","steps":["trace[2082094986] 'process raft request' (duration: 231.880634ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:51:44.491425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.431514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-08T15:51:44.491486Z","caller":"traceutil/trace.go:171","msg":"trace[1301836646] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2153; }","duration":"150.552467ms","start":"2026-09-08T15:51:44.340915Z","end":"2026-09-08T15:51:44.491467Z","steps":["trace[1301836646] 'agreement among raft nodes before linearized reading' (duration: 150.305421ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:51:44.733199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.318432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:51:44.733332Z","caller":"traceutil/trace.go:171","msg":"trace[1756394717] linearizableReadLoop","detail":"{readStateIndex:2237; appliedIndex:2236; }","duration":"165.768537ms","start":"2026-09-08T15:51:44.567542Z","end":"2026-09-08T15:51:44.733311Z","steps":["trace[1756394717] 'read index received' (duration: 56.111µs)","trace[1756394717] 'applied index is now lower than readState.Index' (duration: 165.709616ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:51:44.733727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.151458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-08T15:51:44.733794Z","caller":"traceutil/trace.go:171","msg":"trace[1099055553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2154; }","duration":"166.301012ms","start":"2026-09-08T15:51:44.567481Z","end":"2026-09-08T15:51:44.733782Z","steps":["trace[1099055553] 'agreement among raft nodes before linearized reading' (duration: 165.907551ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:51:44.733358Z","caller":"traceutil/trace.go:171","msg":"trace[1669395184] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"201.761305ms","start":"2026-09-08T15:51:44.531568Z","end":"2026-09-08T15:51:44.73333Z","steps":["trace[1669395184] 'compare' (duration: 195.217298ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:53:09.320232Z","caller":"traceutil/trace.go:171","msg":"trace[677052268] transaction","detail":"{read_only:false; response_revision:2804; number_of_response:1; }","duration":"177.560548ms","start":"2026-09-08T15:53:09.142647Z","end":"2026-09-08T15:53:09.320208Z","steps":["trace[677052268] 'process raft request' (duration: 138.316819ms)","trace[677052268] 'compare' (duration: 38.971462ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:54:17.487317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.585217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-08T15:54:17.487404Z","caller":"traceutil/trace.go:171","msg":"trace[712390279] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3173; }","duration":"120.72759ms","start":"2026-09-08T15:54:17.366659Z","end":"2026-09-08T15:54:17.487386Z","steps":["trace[712390279] 'range keys from in-memory index tree' (duration: 120.404261ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:54:17.888663Z","caller":"traceutil/trace.go:171","msg":"trace[1973016402] transaction","detail":"{read_only:false; response_revision:3178; number_of_response:1; }","duration":"106.761256ms","start":"2026-09-08T15:54:17.78187Z","end":"2026-09-08T15:54:17.888631Z","steps":["trace[1973016402] 'process raft request' (duration: 94.142157ms)","trace[1973016402] 'compare' (duration: 12.426514ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:55:50.665217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.845971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:55:50.665578Z","caller":"traceutil/trace.go:171","msg":"trace[325285940] linearizableReadLoop","detail":"{readStateIndex:3960; appliedIndex:3959; }","duration":"114.537143ms","start":"2026-09-08T15:55:50.550901Z","end":"2026-09-08T15:55:50.665438Z","steps":["trace[325285940] 'read index received' (duration: 2.398464ms)","trace[325285940] 'applied index is now lower than readState.Index' (duration: 112.135979ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:55:50.66538Z","caller":"traceutil/trace.go:171","msg":"trace[1222202990] transaction","detail":"{read_only:false; response_revision:3813; number_of_response:1; }","duration":"121.467798ms","start":"2026-09-08T15:55:50.543854Z","end":"2026-09-08T15:55:50.665321Z","steps":["trace[1222202990] 'compare' (duration: 111.692357ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:55:50.665691Z","caller":"traceutil/trace.go:171","msg":"trace[426864502] transaction","detail":"{read_only:false; response_revision:3815; number_of_response:1; }","duration":"113.894915ms","start":"2026-09-08T15:55:50.551777Z","end":"2026-09-08T15:55:50.665672Z","steps":["trace[426864502] 'process raft request' (duration: 113.659439ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:55:50.665808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.887592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/secretgen-controller/secretgen-controller-5cf976ccc7-zhtx9\" ","response":"range_response_count:1 size:2097"} {"level":"info","ts":"2026-09-08T15:55:50.665993Z","caller":"traceutil/trace.go:171","msg":"trace[1436878333] range","detail":"{range_begin:/registry/pods/secretgen-controller/secretgen-controller-5cf976ccc7-zhtx9; range_end:; response_count:1; response_revision:3815; }","duration":"114.988245ms","start":"2026-09-08T15:55:50.550867Z","end":"2026-09-08T15:55:50.665855Z","steps":["trace[1436878333] 'agreement among raft nodes before linearized reading' (duration: 114.772919ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:55:50.666066Z","caller":"traceutil/trace.go:171","msg":"trace[1297202072] transaction","detail":"{read_only:false; response_revision:3814; number_of_response:1; }","duration":"114.984205ms","start":"2026-09-08T15:55:50.551058Z","end":"2026-09-08T15:55:50.666042Z","steps":["trace[1297202072] 'process raft request' (duration: 114.262296ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:44.998141Z","caller":"traceutil/trace.go:171","msg":"trace[1287232586] linearizableReadLoop","detail":"{readStateIndex:5153; appliedIndex:5152; }","duration":"111.315133ms","start":"2026-09-08T15:57:44.886807Z","end":"2026-09-08T15:57:44.998122Z","steps":["trace[1287232586] 'read index received' (duration: 105.416887ms)","trace[1287232586] 'applied index is now lower than readState.Index' (duration: 5.897036ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:57:44.998375Z","caller":"traceutil/trace.go:171","msg":"trace[9475442] transaction","detail":"{read_only:false; response_revision:4965; number_of_response:1; }","duration":"114.96308ms","start":"2026-09-08T15:57:44.883403Z","end":"2026-09-08T15:57:44.998366Z","steps":["trace[9475442] 'process raft request' (duration: 108.88096ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:57:44.998629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.807396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-08T15:57:44.998657Z","caller":"traceutil/trace.go:171","msg":"trace[1996474053] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4965; }","duration":"111.870388ms","start":"2026-09-08T15:57:44.88678Z","end":"2026-09-08T15:57:44.99865Z","steps":["trace[1996474053] 'agreement among raft nodes before linearized reading' (duration: 111.760415ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:57:44.998817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.244444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-08T15:57:44.99884Z","caller":"traceutil/trace.go:171","msg":"trace[1128549883] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4965; }","duration":"104.295616ms","start":"2026-09-08T15:57:44.894539Z","end":"2026-09-08T15:57:44.998835Z","steps":["trace[1128549883] 'agreement among raft nodes before linearized reading' (duration: 104.236705ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:50.22209Z","caller":"traceutil/trace.go:171","msg":"trace[53615039] transaction","detail":"{read_only:false; response_revision:5092; number_of_response:1; }","duration":"110.884971ms","start":"2026-09-08T15:57:50.111167Z","end":"2026-09-08T15:57:50.222052Z","steps":["trace[53615039] 'process raft request' (duration: 65.290289ms)","trace[53615039] 'compare' (duration: 45.377787ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:57:50.22248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.557318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:9 size:75509"} {"level":"info","ts":"2026-09-08T15:57:50.222595Z","caller":"traceutil/trace.go:171","msg":"trace[626229534] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:9; response_revision:5092; }","duration":"138.745322ms","start":"2026-09-08T15:57:50.083829Z","end":"2026-09-08T15:57:50.222574Z","steps":["trace[626229534] 'agreement among raft nodes before linearized reading' (duration: 138.196418ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:50.260125Z","caller":"traceutil/trace.go:171","msg":"trace[1608104587] transaction","detail":"{read_only:false; response_revision:5093; number_of_response:1; }","duration":"130.264798ms","start":"2026-09-08T15:57:50.12983Z","end":"2026-09-08T15:57:50.260095Z","steps":["trace[1608104587] 'process raft request' (duration: 130.063382ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:57:50.26191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.619289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2210296"} {"level":"info","ts":"2026-09-08T15:57:50.262095Z","caller":"traceutil/trace.go:171","msg":"trace[2187284] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:5093; }","duration":"135.848925ms","start":"2026-09-08T15:57:50.126223Z","end":"2026-09-08T15:57:50.262072Z","steps":["trace[2187284] 'agreement among raft nodes before linearized reading' (duration: 133.918944ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:55.392847Z","caller":"traceutil/trace.go:171","msg":"trace[81073729] transaction","detail":"{read_only:false; response_revision:5250; number_of_response:1; }","duration":"279.168251ms","start":"2026-09-08T15:57:55.113651Z","end":"2026-09-08T15:57:55.39282Z","steps":["trace[81073729] 'process raft request' (duration: 278.972217ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:55.392841Z","caller":"traceutil/trace.go:171","msg":"trace[1009149139] linearizableReadLoop","detail":"{readStateIndex:5446; appliedIndex:5445; }","duration":"273.096469ms","start":"2026-09-08T15:57:55.119715Z","end":"2026-09-08T15:57:55.392811Z","steps":["trace[1009149139] 'read index received' (duration: 272.898715ms)","trace[1009149139] 'applied index is now lower than readState.Index' (duration: 195.794µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:57:55.393056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.318655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-af4a7e75-83c2-4934-b22b-ffeeeaf31658\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-09-08T15:57:55.393095Z","caller":"traceutil/trace.go:171","msg":"trace[268136815] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-af4a7e75-83c2-4934-b22b-ffeeeaf31658; range_end:; response_count:1; response_revision:5250; }","duration":"273.396057ms","start":"2026-09-08T15:57:55.119689Z","end":"2026-09-08T15:57:55.393085Z","steps":["trace[268136815] 'agreement among raft nodes before linearized reading' (duration: 273.218522ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:57:55.393199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.89226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T15:57:55.393245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.306284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-08T15:57:55.393264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.698042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-08T15:57:55.39327Z","caller":"traceutil/trace.go:171","msg":"trace[82687762] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5250; }","duration":"214.992483ms","start":"2026-09-08T15:57:55.178254Z","end":"2026-09-08T15:57:55.393247Z","steps":["trace[82687762] 'agreement among raft nodes before linearized reading' (duration: 214.88506ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:55.393316Z","caller":"traceutil/trace.go:171","msg":"trace[271006735] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5250; }","duration":"203.790134ms","start":"2026-09-08T15:57:55.189512Z","end":"2026-09-08T15:57:55.393302Z","steps":["trace[271006735] 'agreement among raft nodes before linearized reading' (duration: 203.64611ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:55.393285Z","caller":"traceutil/trace.go:171","msg":"trace[887630483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5250; }","duration":"202.364466ms","start":"2026-09-08T15:57:55.19091Z","end":"2026-09-08T15:57:55.393274Z","steps":["trace[887630483] 'agreement among raft nodes before linearized reading' (duration: 202.243933ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:57:55.393455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.275274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-09-08T15:57:55.393523Z","caller":"traceutil/trace.go:171","msg":"trace[606858739] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5250; }","duration":"205.360596ms","start":"2026-09-08T15:57:55.188144Z","end":"2026-09-08T15:57:55.393505Z","steps":["trace[606858739] 'agreement among raft nodes before linearized reading' (duration: 205.176351ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:55.821202Z","caller":"traceutil/trace.go:171","msg":"trace[605499276] transaction","detail":"{read_only:false; response_revision:5256; number_of_response:1; }","duration":"220.620462ms","start":"2026-09-08T15:57:55.60055Z","end":"2026-09-08T15:57:55.821171Z","steps":["trace[605499276] 'process raft request' (duration: 162.66806ms)","trace[605499276] 'compare' (duration: 57.695016ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:57:55.821231Z","caller":"traceutil/trace.go:171","msg":"trace[1423394041] linearizableReadLoop","detail":"{readStateIndex:5452; appliedIndex:5451; }","duration":"186.424682ms","start":"2026-09-08T15:57:55.634765Z","end":"2026-09-08T15:57:55.82119Z","steps":["trace[1423394041] 'read index received' (duration: 128.566793ms)","trace[1423394041] 'applied index is now lower than readState.Index' (duration: 57.854369ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:57:55.847568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.762984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T15:57:55.847683Z","caller":"traceutil/trace.go:171","msg":"trace[300033911] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:5256; }","duration":"212.927628ms","start":"2026-09-08T15:57:55.634728Z","end":"2026-09-08T15:57:55.847656Z","steps":["trace[300033911] 'agreement among raft nodes before linearized reading' (duration: 186.663289ms)","trace[300033911] 'count revisions from in-memory index tree' (duration: 26.082125ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:58:08.72165Z","caller":"traceutil/trace.go:171","msg":"trace[1099369980] linearizableReadLoop","detail":"{readStateIndex:5700; appliedIndex:5699; }","duration":"148.948934ms","start":"2026-09-08T15:58:08.572666Z","end":"2026-09-08T15:58:08.721615Z","steps":["trace[1099369980] 'read index received' (duration: 148.705239ms)","trace[1099369980] 'applied index is now lower than readState.Index' (duration: 242.675µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:58:08.721885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.18423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T15:58:08.721938Z","caller":"traceutil/trace.go:171","msg":"trace[1964652988] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5496; }","duration":"149.296783ms","start":"2026-09-08T15:58:08.572626Z","end":"2026-09-08T15:58:08.721922Z","steps":["trace[1964652988] 'agreement among raft nodes before linearized reading' (duration: 149.120509ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:08.721953Z","caller":"traceutil/trace.go:171","msg":"trace[668458568] transaction","detail":"{read_only:false; response_revision:5496; number_of_response:1; }","duration":"268.062065ms","start":"2026-09-08T15:58:08.453859Z","end":"2026-09-08T15:58:08.721908Z","steps":["trace[668458568] 'process raft request' (duration: 267.603163ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:17.411237Z","caller":"traceutil/trace.go:171","msg":"trace[325974261] transaction","detail":"{read_only:false; response_revision:5659; number_of_response:1; }","duration":"203.525708ms","start":"2026-09-08T15:58:17.207684Z","end":"2026-09-08T15:58:17.41121Z","steps":["trace[325974261] 'process raft request' (duration: 203.369004ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:17.659135Z","caller":"traceutil/trace.go:171","msg":"trace[1882801785] linearizableReadLoop","detail":"{readStateIndex:5868; appliedIndex:5867; }","duration":"159.804684ms","start":"2026-09-08T15:58:17.499306Z","end":"2026-09-08T15:58:17.659111Z","steps":["trace[1882801785] 'read index received' (duration: 96.611672ms)","trace[1882801785] 'applied index is now lower than readState.Index' (duration: 63.191962ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:58:17.659193Z","caller":"traceutil/trace.go:171","msg":"trace[1802258854] transaction","detail":"{read_only:false; response_revision:5660; number_of_response:1; }","duration":"160.003159ms","start":"2026-09-08T15:58:17.499143Z","end":"2026-09-08T15:58:17.659146Z","steps":["trace[1802258854] 'process raft request' (duration: 96.856148ms)","trace[1802258854] 'compare' (duration: 63.008187ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:58:17.659361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.03433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:4787"} {"level":"info","ts":"2026-09-08T15:58:17.659408Z","caller":"traceutil/trace.go:171","msg":"trace[2103841603] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:5660; }","duration":"160.119463ms","start":"2026-09-08T15:58:17.499275Z","end":"2026-09-08T15:58:17.659394Z","steps":["trace[2103841603] 'agreement among raft nodes before linearized reading' (duration: 159.980269ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:17.808089Z","caller":"traceutil/trace.go:171","msg":"trace[911500088] transaction","detail":"{read_only:false; response_revision:5661; number_of_response:1; }","duration":"143.842969ms","start":"2026-09-08T15:58:17.664213Z","end":"2026-09-08T15:58:17.808056Z","steps":["trace[911500088] 'process raft request' (duration: 131.398797ms)","trace[911500088] 'compare' (duration: 12.265957ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:58:17.812959Z","caller":"traceutil/trace.go:171","msg":"trace[809579271] transaction","detail":"{read_only:false; response_revision:5663; number_of_response:1; }","duration":"147.930348ms","start":"2026-09-08T15:58:17.665013Z","end":"2026-09-08T15:58:17.812944Z","steps":["trace[809579271] 'process raft request' (duration: 147.882356ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:17.813202Z","caller":"traceutil/trace.go:171","msg":"trace[28140938] transaction","detail":"{read_only:false; response_revision:5662; number_of_response:1; }","duration":"148.513303ms","start":"2026-09-08T15:58:17.66466Z","end":"2026-09-08T15:58:17.813173Z","steps":["trace[28140938] 'process raft request' (duration: 148.158443ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:17.813305Z","caller":"traceutil/trace.go:171","msg":"trace[1064979396] transaction","detail":"{read_only:false; response_revision:5664; number_of_response:1; }","duration":"148.138653ms","start":"2026-09-08T15:58:17.665136Z","end":"2026-09-08T15:58:17.813274Z","steps":["trace[1064979396] 'process raft request' (duration: 147.783023ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:17.813281Z","caller":"traceutil/trace.go:171","msg":"trace[1442361830] linearizableReadLoop","detail":"{readStateIndex:5870; appliedIndex:5868; }","duration":"148.507103ms","start":"2026-09-08T15:58:17.66474Z","end":"2026-09-08T15:58:17.813247Z","steps":["trace[1442361830] 'read index received' (duration: 130.922915ms)","trace[1442361830] 'applied index is now lower than readState.Index' (duration: 17.582108ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:58:17.81351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.746749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-09-08T15:58:17.813553Z","caller":"traceutil/trace.go:171","msg":"trace[1656471183] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5664; }","duration":"148.812741ms","start":"2026-09-08T15:58:17.664727Z","end":"2026-09-08T15:58:17.81354Z","steps":["trace[1656471183] 'agreement among raft nodes before linearized reading' (duration: 148.686207ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:58:17.813838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.925836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6c86c4a1-5cfe-4d62-a89d-5672b6a5ba2c\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-09-08T15:58:17.813898Z","caller":"traceutil/trace.go:171","msg":"trace[1165933086] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6c86c4a1-5cfe-4d62-a89d-5672b6a5ba2c; range_end:; response_count:1; response_revision:5664; }","duration":"122.044379ms","start":"2026-09-08T15:58:17.691837Z","end":"2026-09-08T15:58:17.813881Z","steps":["trace[1165933086] 'agreement among raft nodes before linearized reading' (duration: 121.833573ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:36.920518Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2956} {"level":"info","ts":"2026-09-08T15:58:36.99529Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2956,"took":"73.046085ms","hash":3169792250,"current-db-size-bytes":56123392,"current-db-size":"56 MB","current-db-size-in-use-bytes":32411648,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-08T15:58:36.995422Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3169792250,"revision":2956,"compact-revision":-1} {"level":"info","ts":"2026-09-08T16:03:36.927617Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5887} {"level":"info","ts":"2026-09-08T16:03:37.027236Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5887,"took":"96.224588ms","hash":1250137075,"current-db-size-bytes":56123392,"current-db-size":"56 MB","current-db-size-in-use-bytes":26574848,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-08T16:03:37.027417Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1250137075,"revision":5887,"compact-revision":2956} {"level":"info","ts":"2026-09-08T16:04:27.141172Z","caller":"traceutil/trace.go:171","msg":"trace[750647149] transaction","detail":"{read_only:false; response_revision:8515; number_of_response:1; }","duration":"143.143053ms","start":"2026-09-08T16:04:26.997977Z","end":"2026-09-08T16:04:27.14112Z","steps":["trace[750647149] 'process raft request' (duration: 142.916656ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:04:27.379592Z","caller":"traceutil/trace.go:171","msg":"trace[290662306] transaction","detail":"{read_only:false; response_revision:8516; number_of_response:1; }","duration":"231.64918ms","start":"2026-09-08T16:04:27.147925Z","end":"2026-09-08T16:04:27.379574Z","steps":["trace[290662306] 'process raft request' (duration: 231.517437ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:04:39.974109Z","caller":"traceutil/trace.go:171","msg":"trace[960442573] linearizableReadLoop","detail":"{readStateIndex:8883; appliedIndex:8882; }","duration":"108.069268ms","start":"2026-09-08T16:04:39.866022Z","end":"2026-09-08T16:04:39.974091Z","steps":["trace[960442573] 'read index received' (duration: 107.890584ms)","trace[960442573] 'applied index is now lower than readState.Index' (duration: 177.484µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:04:39.974459Z","caller":"traceutil/trace.go:171","msg":"trace[1686716452] transaction","detail":"{read_only:false; response_revision:8581; number_of_response:1; }","duration":"204.125347ms","start":"2026-09-08T16:04:39.770323Z","end":"2026-09-08T16:04:39.974448Z","steps":["trace[1686716452] 'process raft request' (duration: 203.595293ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:39.97468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.643434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-08T16:04:39.974703Z","caller":"traceutil/trace.go:171","msg":"trace[207380408] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8581; }","duration":"108.703546ms","start":"2026-09-08T16:04:39.865992Z","end":"2026-09-08T16:04:39.974696Z","steps":["trace[207380408] 'agreement among raft nodes before linearized reading' (duration: 108.603103ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:04:40.499967Z","caller":"traceutil/trace.go:171","msg":"trace[1362670703] transaction","detail":"{read_only:false; response_revision:8587; number_of_response:1; }","duration":"107.280147ms","start":"2026-09-08T16:04:40.39266Z","end":"2026-09-08T16:04:40.49994Z","steps":["trace[1362670703] 'process raft request' (duration: 107.071212ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:04:40.646116Z","caller":"traceutil/trace.go:171","msg":"trace[913007844] linearizableReadLoop","detail":"{readStateIndex:8892; appliedIndex:8891; }","duration":"100.25484ms","start":"2026-09-08T16:04:40.545797Z","end":"2026-09-08T16:04:40.646052Z","steps":["trace[913007844] 'read index received' (duration: 19.813338ms)","trace[913007844] 'applied index is now lower than readState.Index' (duration: 80.440482ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:04:40.646399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.588529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-09-08T16:04:40.646464Z","caller":"traceutil/trace.go:171","msg":"trace[970422801] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:8589; }","duration":"100.699362ms","start":"2026-09-08T16:04:40.545754Z","end":"2026-09-08T16:04:40.646453Z","steps":["trace[970422801] 'agreement among raft nodes before linearized reading' (duration: 100.486976ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:04:40.646481Z","caller":"traceutil/trace.go:171","msg":"trace[544211741] transaction","detail":"{read_only:false; response_revision:8589; number_of_response:1; }","duration":"115.178938ms","start":"2026-09-08T16:04:40.531211Z","end":"2026-09-08T16:04:40.64639Z","steps":["trace[544211741] 'process raft request' (duration: 34.461678ms)","trace[544211741] 'compare' (duration: 80.087223ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:05:24.624054Z","caller":"traceutil/trace.go:171","msg":"trace[1385105251] transaction","detail":"{read_only:false; response_revision:8919; number_of_response:1; }","duration":"236.351055ms","start":"2026-09-08T16:05:24.387676Z","end":"2026-09-08T16:05:24.624027Z","steps":["trace[1385105251] 'process raft request' (duration: 236.195061ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:24.73511Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002414.wal"} {"level":"info","ts":"2026-09-08T16:05:24.735441Z","caller":"traceutil/trace.go:171","msg":"trace[672283557] linearizableReadLoop","detail":"{readStateIndex:9235; appliedIndex:9233; }","duration":"160.959248ms","start":"2026-09-08T16:05:24.57446Z","end":"2026-09-08T16:05:24.735419Z","steps":["trace[672283557] 'read index received' (duration: 49.433917ms)","trace[672283557] 'applied index is now lower than readState.Index' (duration: 111.524091ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:05:24.735521Z","caller":"traceutil/trace.go:171","msg":"trace[1506628835] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8920; }","duration":"346.710126ms","start":"2026-09-08T16:05:24.388802Z","end":"2026-09-08T16:05:24.735512Z","steps":["trace[1506628835] 'process raft request' (duration: 346.458819ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:24.73569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:05:24.388782Z","time spent":"346.774148ms","remote":"127.0.0.1:50140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80,"response count":0,"response size":42,"request content":"compare: success: > failure: >"} {"level":"warn","ts":"2026-09-08T16:05:24.735845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.698394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:05:24.735968Z","caller":"traceutil/trace.go:171","msg":"trace[155639779] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:8920; }","duration":"144.867199ms","start":"2026-09-08T16:05:24.591053Z","end":"2026-09-08T16:05:24.73592Z","steps":["trace[155639779] 'agreement among raft nodes before linearized reading' (duration: 144.711215ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:24.736143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.196122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-rk7d7\" ","response":"range_response_count:1 size:4653"} {"level":"info","ts":"2026-09-08T16:05:24.736186Z","caller":"traceutil/trace.go:171","msg":"trace[1133882822] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-rk7d7; range_end:; response_count:1; response_revision:8920; }","duration":"108.285414ms","start":"2026-09-08T16:05:24.627889Z","end":"2026-09-08T16:05:24.736175Z","steps":["trace[1133882822] 'agreement among raft nodes before linearized reading' (duration: 108.126339ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:24.736382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.924023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-08T16:05:24.736416Z","caller":"traceutil/trace.go:171","msg":"trace[965214527] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8920; }","duration":"162.010865ms","start":"2026-09-08T16:05:24.574395Z","end":"2026-09-08T16:05:24.736406Z","steps":["trace[965214527] 'agreement among raft nodes before linearized reading' (duration: 161.924053ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:24.736753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.152034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-kjg4b\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-08T16:05:24.736813Z","caller":"traceutil/trace.go:171","msg":"trace[2073137418] range","detail":"{range_begin:/registry/pods/openstack/keepalived-kjg4b; range_end:; response_count:1; response_revision:8920; }","duration":"140.235925ms","start":"2026-09-08T16:05:24.596562Z","end":"2026-09-08T16:05:24.736798Z","steps":["trace[2073137418] 'agreement among raft nodes before linearized reading' (duration: 139.694331ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:25.352536Z","caller":"traceutil/trace.go:171","msg":"trace[144397944] linearizableReadLoop","detail":"{readStateIndex:9241; appliedIndex:9239; }","duration":"198.189748ms","start":"2026-09-08T16:05:25.154307Z","end":"2026-09-08T16:05:25.352497Z","steps":["trace[144397944] 'read index received' (duration: 197.832289ms)","trace[144397944] 'applied index is now lower than readState.Index' (duration: 356.169µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:05:25.352762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.515704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T16:05:25.352793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.445325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:05:25.352877Z","caller":"traceutil/trace.go:171","msg":"trace[1378354263] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:8926; }","duration":"198.6005ms","start":"2026-09-08T16:05:25.154253Z","end":"2026-09-08T16:05:25.352854Z","steps":["trace[1378354263] 'agreement among raft nodes before linearized reading' (duration: 198.421905ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:25.352691Z","caller":"traceutil/trace.go:171","msg":"trace[472743340] transaction","detail":"{read_only:false; response_revision:8925; number_of_response:1; }","duration":"310.377858ms","start":"2026-09-08T16:05:25.042275Z","end":"2026-09-08T16:05:25.352653Z","steps":["trace[472743340] 'process raft request' (duration: 309.937686ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:25.352824Z","caller":"traceutil/trace.go:171","msg":"trace[766487787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8926; }","duration":"172.636278ms","start":"2026-09-08T16:05:25.180175Z","end":"2026-09-08T16:05:25.352812Z","steps":["trace[766487787] 'agreement among raft nodes before linearized reading' (duration: 172.511085ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:25.353023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:05:25.042255Z","time spent":"310.703416ms","remote":"127.0.0.1:58966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-08T16:05:25.354724Z","caller":"traceutil/trace.go:171","msg":"trace[303185792] transaction","detail":"{read_only:false; response_revision:8926; number_of_response:1; }","duration":"298.024968ms","start":"2026-09-08T16:05:25.054641Z","end":"2026-09-08T16:05:25.352666Z","steps":["trace[303185792] 'process raft request' (duration: 297.779621ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:25.354905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:05:25.054608Z","time spent":"300.198396ms","remote":"127.0.0.1:59132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3037,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T16:06:40.422713Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e9d471e5f5fb9faf","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-08T16:06:40.430965Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-08T16:06:40.431164Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-09-08T16:06:46.865964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.401314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T16:06:46.866114Z","caller":"traceutil/trace.go:171","msg":"trace[1469246270] linearizableReadLoop","detail":"{readStateIndex:10040; appliedIndex:10039; }","duration":"185.459428ms","start":"2026-09-08T16:06:46.68063Z","end":"2026-09-08T16:06:46.866089Z","steps":["trace[1469246270] 'read index received' (duration: 16.840199ms)","trace[1469246270] 'applied index is now lower than readState.Index' (duration: 168.598729ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:06:46.86631Z","caller":"traceutil/trace.go:171","msg":"trace[160029546] transaction","detail":"{read_only:false; response_revision:9699; number_of_response:1; }","duration":"188.363775ms","start":"2026-09-08T16:06:46.677892Z","end":"2026-09-08T16:06:46.866256Z","steps":["trace[160029546] 'process raft request' (duration: 19.56634ms)","trace[160029546] 'compare' (duration: 168.221179ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:06:46.866388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.765955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-08T16:06:46.866462Z","caller":"traceutil/trace.go:171","msg":"trace[1536971713] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9699; }","duration":"185.891159ms","start":"2026-09-08T16:06:46.680555Z","end":"2026-09-08T16:06:46.866446Z","steps":["trace[1536971713] 'agreement among raft nodes before linearized reading' (duration: 185.695755ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:06:46.866522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.241312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-09-08T16:06:46.866531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.220668ms","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-09-08T16:06:46.866598Z","caller":"traceutil/trace.go:171","msg":"trace[235108067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9699; }","duration":"180.319221ms","start":"2026-09-08T16:06:46.686263Z","end":"2026-09-08T16:06:46.866582Z","steps":["trace[235108067] 'agreement among raft nodes before linearized reading' (duration: 180.047154ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:06:46.866592Z","caller":"traceutil/trace.go:171","msg":"trace[383419050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9699; }","duration":"179.372465ms","start":"2026-09-08T16:06:46.687202Z","end":"2026-09-08T16:06:46.866574Z","steps":["trace[383419050] 'agreement among raft nodes before linearized reading' (duration: 179.11883ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:07:29.780683Z","caller":"traceutil/trace.go:171","msg":"trace[572383801] transaction","detail":"{read_only:false; response_revision:10013; number_of_response:1; }","duration":"144.800346ms","start":"2026-09-08T16:07:29.635836Z","end":"2026-09-08T16:07:29.780636Z","steps":["trace[572383801] 'process raft request' (duration: 144.564579ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:07:40.702385Z","caller":"traceutil/trace.go:171","msg":"trace[328585820] transaction","detail":"{read_only:false; response_revision:10090; number_of_response:1; }","duration":"101.867112ms","start":"2026-09-08T16:07:40.600493Z","end":"2026-09-08T16:07:40.70236Z","steps":["trace[328585820] 'process raft request' (duration: 101.725578ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:08:36.97925Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8050} {"level":"info","ts":"2026-09-08T16:08:37.053668Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8050,"took":"71.789971ms","hash":3888059888,"current-db-size-bytes":56123392,"current-db-size":"56 MB","current-db-size-in-use-bytes":28667904,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-08T16:08:37.05374Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3888059888,"revision":8050,"compact-revision":5887} {"level":"info","ts":"2026-09-08T16:13:36.987019Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10495} {"level":"info","ts":"2026-09-08T16:13:37.08566Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10495,"took":"95.183462ms","hash":1958233310,"current-db-size-bytes":56123392,"current-db-size":"56 MB","current-db-size-in-use-bytes":27484160,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-08T16:13:37.085722Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1958233310,"revision":10495,"compact-revision":8050} {"level":"info","ts":"2026-09-08T16:16:49.191334Z","caller":"traceutil/trace.go:171","msg":"trace[847487738] transaction","detail":"{read_only:false; response_revision:14084; number_of_response:1; }","duration":"102.769024ms","start":"2026-09-08T16:16:49.088537Z","end":"2026-09-08T16:16:49.191306Z","steps":["trace[847487738] 'process raft request' (duration: 102.59608ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:16:51.354592Z","caller":"traceutil/trace.go:171","msg":"trace[982832420] transaction","detail":"{read_only:false; response_revision:14095; number_of_response:1; }","duration":"158.660922ms","start":"2026-09-08T16:16:51.195895Z","end":"2026-09-08T16:16:51.354556Z","steps":["trace[982832420] 'process raft request' (duration: 158.366384ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:17:09.735619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.270248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-09-08T16:17:09.735717Z","caller":"traceutil/trace.go:171","msg":"trace[1216445637] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:14208; }","duration":"143.380661ms","start":"2026-09-08T16:17:09.592315Z","end":"2026-09-08T16:17:09.735696Z","steps":["trace[1216445637] 'range keys from in-memory index tree' (duration: 143.084443ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:17:10.065217Z","caller":"traceutil/trace.go:171","msg":"trace[388131595] transaction","detail":"{read_only:false; response_revision:14210; number_of_response:1; }","duration":"107.950438ms","start":"2026-09-08T16:17:09.957237Z","end":"2026-09-08T16:17:10.065187Z","steps":["trace[388131595] 'process raft request' (duration: 107.65137ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:17:10.666793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.367648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:17:10.666877Z","caller":"traceutil/trace.go:171","msg":"trace[1272114057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14210; }","duration":"485.4831ms","start":"2026-09-08T16:17:10.181373Z","end":"2026-09-08T16:17:10.666856Z","steps":["trace[1272114057] 'range keys from in-memory index tree' (duration: 485.285846ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:17:10.666916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:17:10.181353Z","time spent":"485.552693ms","remote":"127.0.0.1:58886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-08T16:17:10.667297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.008747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-08T16:17:10.667373Z","caller":"traceutil/trace.go:171","msg":"trace[376674337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14210; }","duration":"214.162311ms","start":"2026-09-08T16:17:10.453192Z","end":"2026-09-08T16:17:10.667354Z","steps":["trace[376674337] 'range keys from in-memory index tree' (duration: 213.795851ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:17:10.667378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.970006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.165\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-09-08T16:17:10.66754Z","caller":"traceutil/trace.go:171","msg":"trace[1668890698] range","detail":"{range_begin:/registry/masterleases/162.253.55.165; range_end:; response_count:1; response_revision:14210; }","duration":"214.076449ms","start":"2026-09-08T16:17:10.453345Z","end":"2026-09-08T16:17:10.667422Z","steps":["trace[1668890698] 'range keys from in-memory index tree' (duration: 213.729209ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:17:10.66734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.067973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-08T16:17:10.667969Z","caller":"traceutil/trace.go:171","msg":"trace[1646094458] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14210; }","duration":"525.736201ms","start":"2026-09-08T16:17:10.142205Z","end":"2026-09-08T16:17:10.667942Z","steps":["trace[1646094458] 'range keys from in-memory index tree' (duration: 524.871198ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:17:10.668052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:17:10.142184Z","time spent":"525.830044ms","remote":"127.0.0.1:59166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-08T16:17:11.135318Z","caller":"traceutil/trace.go:171","msg":"trace[1073247549] linearizableReadLoop","detail":"{readStateIndex:14703; appliedIndex:14702; }","duration":"416.20552ms","start":"2026-09-08T16:17:10.719083Z","end":"2026-09-08T16:17:11.135288Z","steps":["trace[1073247549] 'read index received' (duration: 416.039556ms)","trace[1073247549] 'applied index is now lower than readState.Index' (duration: 164.814µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:17:11.135344Z","caller":"traceutil/trace.go:171","msg":"trace[1450459897] transaction","detail":"{read_only:false; response_revision:14213; number_of_response:1; }","duration":"429.037742ms","start":"2026-09-08T16:17:10.706277Z","end":"2026-09-08T16:17:11.135315Z","steps":["trace[1450459897] 'process raft request' (duration: 428.831706ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:17:11.135542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:17:10.706253Z","time spent":"429.202767ms","remote":"127.0.0.1:58918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T16:17:11.135553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.208819ms","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-09-08T16:17:11.135597Z","caller":"traceutil/trace.go:171","msg":"trace[700650504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14213; }","duration":"419.306082ms","start":"2026-09-08T16:17:10.71628Z","end":"2026-09-08T16:17:11.135587Z","steps":["trace[700650504] 'agreement among raft nodes before linearized reading' (duration: 419.120497ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:17:11.135628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:17:10.716255Z","time spent":"419.367244ms","remote":"127.0.0.1:59166","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-09-08T16:17:11.135935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.810473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-08T16:17:11.135969Z","caller":"traceutil/trace.go:171","msg":"trace[1112821750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14213; }","duration":"417.891015ms","start":"2026-09-08T16:17:10.71807Z","end":"2026-09-08T16:17:11.135961Z","steps":["trace[1112821750] 'agreement among raft nodes before linearized reading' (duration: 417.277129ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:17:11.135993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:17:10.718022Z","time spent":"417.964878ms","remote":"127.0.0.1:59166","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-09-08T16:17:11.136942Z","caller":"traceutil/trace.go:171","msg":"trace[1883717321] transaction","detail":"{read_only:false; response_revision:14214; number_of_response:1; }","duration":"412.535713ms","start":"2026-09-08T16:17:10.724397Z","end":"2026-09-08T16:17:11.136933Z","steps":["trace[1883717321] 'process raft request' (duration: 412.296207ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:17:11.137016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:17:10.724371Z","time spent":"412.596754ms","remote":"127.0.0.1:59166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T16:18:36.994175Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12768} {"level":"info","ts":"2026-09-08T16:18:37.119316Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12768,"took":"122.779813ms","hash":2415773597,"current-db-size-bytes":56123392,"current-db-size":"56 MB","current-db-size-in-use-bytes":30195712,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-08T16:18:37.119551Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2415773597,"revision":12768,"compact-revision":10495} {"level":"info","ts":"2026-09-08T16:18:49.917258Z","caller":"traceutil/trace.go:171","msg":"trace[525146138] linearizableReadLoop","detail":"{readStateIndex:15932; appliedIndex:15931; }","duration":"215.99593ms","start":"2026-09-08T16:18:49.701238Z","end":"2026-09-08T16:18:49.917234Z","steps":["trace[525146138] 'read index received' (duration: 215.713943ms)","trace[525146138] 'applied index is now lower than readState.Index' (duration: 280.727µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:18:49.917376Z","caller":"traceutil/trace.go:171","msg":"trace[2015673134] transaction","detail":"{read_only:false; response_revision:15404; number_of_response:1; }","duration":"241.616921ms","start":"2026-09-08T16:18:49.6757Z","end":"2026-09-08T16:18:49.917317Z","steps":["trace[2015673134] 'process raft request' (duration: 241.345163ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:18:49.917734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.792588ms","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-09-08T16:18:49.917796Z","caller":"traceutil/trace.go:171","msg":"trace[991983915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15404; }","duration":"202.87779ms","start":"2026-09-08T16:18:49.714908Z","end":"2026-09-08T16:18:49.917786Z","steps":["trace[991983915] 'agreement among raft nodes before linearized reading' (duration: 202.49559ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:18:49.91777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.502444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-08T16:18:49.917852Z","caller":"traceutil/trace.go:171","msg":"trace[64145302] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15404; }","duration":"216.632158ms","start":"2026-09-08T16:18:49.701202Z","end":"2026-09-08T16:18:49.917834Z","steps":["trace[64145302] 'agreement among raft nodes before linearized reading' (duration: 216.188126ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:18:49.917986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.805355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-08T16:18:49.918108Z","caller":"traceutil/trace.go:171","msg":"trace[1973844280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15404; }","duration":"183.940868ms","start":"2026-09-08T16:18:49.734143Z","end":"2026-09-08T16:18:49.918083Z","steps":["trace[1973844280] 'agreement among raft nodes before linearized reading' (duration: 183.652921ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:18:50.182955Z","caller":"traceutil/trace.go:171","msg":"trace[1321642535] transaction","detail":"{read_only:false; response_revision:15406; number_of_response:1; }","duration":"259.64586ms","start":"2026-09-08T16:18:49.923276Z","end":"2026-09-08T16:18:50.182922Z","steps":["trace[1321642535] 'process raft request' (duration: 170.229283ms)","trace[1321642535] 'compare' (duration: 89.078608ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:18:50.183066Z","caller":"traceutil/trace.go:171","msg":"trace[753401512] transaction","detail":"{read_only:false; response_revision:15408; number_of_response:1; }","duration":"254.844303ms","start":"2026-09-08T16:18:49.928196Z","end":"2026-09-08T16:18:50.18304Z","steps":["trace[753401512] 'process raft request' (duration: 254.783721ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:18:50.183125Z","caller":"traceutil/trace.go:171","msg":"trace[1549764191] linearizableReadLoop","detail":"{readStateIndex:15935; appliedIndex:15933; }","duration":"255.11396ms","start":"2026-09-08T16:18:49.928Z","end":"2026-09-08T16:18:50.183114Z","steps":["trace[1549764191] 'read index received' (duration: 165.604491ms)","trace[1549764191] 'applied index is now lower than readState.Index' (duration: 89.508359ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:18:50.183095Z","caller":"traceutil/trace.go:171","msg":"trace[19623297] transaction","detail":"{read_only:false; response_revision:15407; number_of_response:1; }","duration":"259.27125ms","start":"2026-09-08T16:18:49.9238Z","end":"2026-09-08T16:18:50.183071Z","steps":["trace[19623297] 'process raft request' (duration: 259.052564ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:18:50.183807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.825591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:18:50.183864Z","caller":"traceutil/trace.go:171","msg":"trace[1828946450] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15408; }","duration":"222.913804ms","start":"2026-09-08T16:18:49.960929Z","end":"2026-09-08T16:18:50.183843Z","steps":["trace[1828946450] 'agreement among raft nodes before linearized reading' (duration: 222.78972ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:18:50.183879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.013236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"warn","ts":"2026-09-08T16:18:50.183918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.129274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-09-08T16:18:50.183987Z","caller":"traceutil/trace.go:171","msg":"trace[2043110554] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15408; }","duration":"223.200432ms","start":"2026-09-08T16:18:49.960755Z","end":"2026-09-08T16:18:50.183955Z","steps":["trace[2043110554] 'agreement among raft nodes before linearized reading' (duration: 222.922844ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:18:50.184041Z","caller":"traceutil/trace.go:171","msg":"trace[69349235] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15408; }","duration":"219.251677ms","start":"2026-09-08T16:18:49.964741Z","end":"2026-09-08T16:18:50.183992Z","steps":["trace[69349235] 'agreement among raft nodes before linearized reading' (duration: 218.955399ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:18:50.183974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.101778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:18:50.184169Z","caller":"traceutil/trace.go:171","msg":"trace[1772399051] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15408; }","duration":"220.357455ms","start":"2026-09-08T16:18:49.963773Z","end":"2026-09-08T16:18:50.184131Z","steps":["trace[1772399051] 'agreement among raft nodes before linearized reading' (duration: 219.957535ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:18:50.184195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.13944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-08T16:18:50.184394Z","caller":"traceutil/trace.go:171","msg":"trace[1881230737] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15408; }","duration":"220.51708ms","start":"2026-09-08T16:18:49.963859Z","end":"2026-09-08T16:18:50.184376Z","steps":["trace[1881230737] 'agreement among raft nodes before linearized reading' (duration: 219.851192ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:18:50.184283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.259101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769603"} {"level":"info","ts":"2026-09-08T16:18:50.184566Z","caller":"traceutil/trace.go:171","msg":"trace[589746130] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15408; }","duration":"256.580459ms","start":"2026-09-08T16:18:49.927968Z","end":"2026-09-08T16:18:50.184549Z","steps":["trace[589746130] 'agreement among raft nodes before linearized reading' (duration: 255.173342ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:23:37.002953Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15339} {"level":"info","ts":"2026-09-08T16:23:37.113757Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15339,"took":"107.690716ms","hash":3705747896,"current-db-size-bytes":56123392,"current-db-size":"56 MB","current-db-size-in-use-bytes":31748096,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-08T16:23:37.113822Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3705747896,"revision":15339,"compact-revision":12768} {"level":"info","ts":"2026-09-08T16:24:39.275009Z","caller":"traceutil/trace.go:171","msg":"trace[798081976] transaction","detail":"{read_only:false; response_revision:18023; number_of_response:1; }","duration":"265.629303ms","start":"2026-09-08T16:24:39.009339Z","end":"2026-09-08T16:24:39.274968Z","steps":["trace[798081976] 'process raft request' (duration: 265.415317ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:24:39.615917Z","caller":"traceutil/trace.go:171","msg":"trace[1619737183] transaction","detail":"{read_only:false; response_revision:18025; number_of_response:1; }","duration":"332.70087ms","start":"2026-09-08T16:24:39.283191Z","end":"2026-09-08T16:24:39.615892Z","steps":["trace[1619737183] 'process raft request' (duration: 251.922098ms)","trace[1619737183] 'compare' (duration: 80.583237ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:24:39.616058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:24:39.283167Z","time spent":"332.817713ms","remote":"127.0.0.1:59060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":931,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T16:24:39.616178Z","caller":"traceutil/trace.go:171","msg":"trace[1313822290] transaction","detail":"{read_only:false; response_revision:18026; number_of_response:1; }","duration":"332.662879ms","start":"2026-09-08T16:24:39.283492Z","end":"2026-09-08T16:24:39.616155Z","steps":["trace[1313822290] 'process raft request' (duration: 332.33572ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:24:39.616313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:24:39.283479Z","time spent":"332.760562ms","remote":"127.0.0.1:59182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1401,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T16:24:39.616642Z","caller":"traceutil/trace.go:171","msg":"trace[221257260] transaction","detail":"{read_only:false; response_revision:18027; number_of_response:1; }","duration":"332.751142ms","start":"2026-09-08T16:24:39.283851Z","end":"2026-09-08T16:24:39.616602Z","steps":["trace[221257260] 'process raft request' (duration: 332.190387ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:24:39.616656Z","caller":"traceutil/trace.go:171","msg":"trace[1538819494] linearizableReadLoop","detail":"{readStateIndex:18643; appliedIndex:18639; }","duration":"160.594738ms","start":"2026-09-08T16:24:39.45604Z","end":"2026-09-08T16:24:39.616634Z","steps":["trace[1538819494] 'read index received' (duration: 79.083757ms)","trace[1538819494] 'applied index is now lower than readState.Index' (duration: 81.49093ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:24:39.616718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:24:39.283842Z","time spent":"332.837384ms","remote":"127.0.0.1:59182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1474,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T16:24:39.61669Z","caller":"traceutil/trace.go:171","msg":"trace[1523862132] transaction","detail":"{read_only:false; response_revision:18028; number_of_response:1; }","duration":"239.547432ms","start":"2026-09-08T16:24:39.377093Z","end":"2026-09-08T16:24:39.616641Z","steps":["trace[1523862132] 'process raft request' (duration: 239.415948ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:24:39.61688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.479082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-08T16:24:39.61692Z","caller":"traceutil/trace.go:171","msg":"trace[1611482288] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18028; }","duration":"155.556184ms","start":"2026-09-08T16:24:39.461352Z","end":"2026-09-08T16:24:39.616908Z","steps":["trace[1611482288] 'agreement among raft nodes before linearized reading' (duration: 155.429981ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:24:39.616977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.890826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-08T16:24:39.617097Z","caller":"traceutil/trace.go:171","msg":"trace[415756720] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18028; }","duration":"161.082301ms","start":"2026-09-08T16:24:39.455983Z","end":"2026-09-08T16:24:39.617066Z","steps":["trace[415756720] 'agreement among raft nodes before linearized reading' (duration: 160.724802ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:27:15.86433Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e9d471e5f5fb9faf","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-08T16:27:15.871152Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-08T16:27:15.87126Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-08T16:28:37.013175Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17528} {"level":"info","ts":"2026-09-08T16:28:37.122036Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17528,"took":"105.110607ms","hash":2649071210,"current-db-size-bytes":56123392,"current-db-size":"56 MB","current-db-size-in-use-bytes":34156544,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-08T16:28:37.122111Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2649071210,"revision":17528,"compact-revision":15339} {"level":"info","ts":"2026-09-08T16:29:47.213024Z","caller":"traceutil/trace.go:171","msg":"trace[1006519900] transaction","detail":"{read_only:false; response_revision:20390; number_of_response:1; }","duration":"191.090416ms","start":"2026-09-08T16:29:47.021895Z","end":"2026-09-08T16:29:47.212985Z","steps":["trace[1006519900] 'process raft request' (duration: 136.623712ms)","trace[1006519900] 'compare' (duration: 54.265979ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:30:03.775219Z","caller":"traceutil/trace.go:171","msg":"trace[2141996502] linearizableReadLoop","detail":"{readStateIndex:21189; appliedIndex:21188; }","duration":"103.018194ms","start":"2026-09-08T16:30:03.672174Z","end":"2026-09-08T16:30:03.775192Z","steps":["trace[2141996502] 'read index received' (duration: 74.068485ms)","trace[2141996502] 'applied index is now lower than readState.Index' (duration: 28.948769ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:30:03.775486Z","caller":"traceutil/trace.go:171","msg":"trace[35561699] transaction","detail":"{read_only:false; response_revision:20490; number_of_response:1; }","duration":"279.874736ms","start":"2026-09-08T16:30:03.495591Z","end":"2026-09-08T16:30:03.775466Z","steps":["trace[35561699] 'process raft request' (duration: 250.705422ms)","trace[35561699] 'compare' (duration: 28.415404ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:30:03.776186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.770654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-08T16:30:03.776252Z","caller":"traceutil/trace.go:171","msg":"trace[1652857140] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20490; }","duration":"104.288676ms","start":"2026-09-08T16:30:03.671948Z","end":"2026-09-08T16:30:03.776237Z","steps":["trace[1652857140] 'agreement among raft nodes before linearized reading' (duration: 103.827753ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:30:04.314807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:30:03.897624Z","time spent":"417.17328ms","remote":"127.0.0.1:55722","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-09-08T16:30:04.315227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.396435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-08T16:30:04.315393Z","caller":"traceutil/trace.go:171","msg":"trace[1556713797] linearizableReadLoop","detail":"{readStateIndex:21191; appliedIndex:21189; }","duration":"449.706664ms","start":"2026-09-08T16:30:03.865653Z","end":"2026-09-08T16:30:04.31536Z","steps":["trace[1556713797] 'read index received' (duration: 98.084643ms)","trace[1556713797] 'applied index is now lower than readState.Index' (duration: 351.620821ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:30:04.315402Z","caller":"traceutil/trace.go:171","msg":"trace[281225038] transaction","detail":"{read_only:false; response_revision:20491; number_of_response:1; }","duration":"534.049121ms","start":"2026-09-08T16:30:03.781311Z","end":"2026-09-08T16:30:04.31536Z","steps":["trace[281225038] 'process raft request' (duration: 182.440041ms)","trace[281225038] 'compare' (duration: 351.23646ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:30:04.315516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.865118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T16:30:04.315477Z","caller":"traceutil/trace.go:171","msg":"trace[961345419] transaction","detail":"{read_only:false; response_revision:20492; number_of_response:1; }","duration":"533.769475ms","start":"2026-09-08T16:30:03.78164Z","end":"2026-09-08T16:30:04.315409Z","steps":["trace[961345419] 'process raft request' (duration: 533.658122ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:30:04.3156Z","caller":"traceutil/trace.go:171","msg":"trace[368417840] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:20492; }","duration":"449.94953ms","start":"2026-09-08T16:30:03.865635Z","end":"2026-09-08T16:30:04.315584Z","steps":["trace[368417840] 'agreement among raft nodes before linearized reading' (duration: 449.835957ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:30:04.315646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:30:03.865533Z","time spent":"450.102895ms","remote":"127.0.0.1:59122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":30,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-09-08T16:30:04.315765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:30:03.781626Z","time spent":"534.00187ms","remote":"127.0.0.1:59166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T16:30:04.315823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:30:03.78128Z","time spent":"534.202596ms","remote":"127.0.0.1:59166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T16:30:04.31591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.799692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-09-08T16:30:04.315947Z","caller":"traceutil/trace.go:171","msg":"trace[1995287756] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:20492; }","duration":"153.885725ms","start":"2026-09-08T16:30:04.16205Z","end":"2026-09-08T16:30:04.315935Z","steps":["trace[1995287756] 'agreement among raft nodes before linearized reading' (duration: 153.768772ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:30:04.316097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.493095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T16:30:04.316132Z","caller":"traceutil/trace.go:171","msg":"trace[2116750683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20492; }","duration":"135.630309ms","start":"2026-09-08T16:30:04.180493Z","end":"2026-09-08T16:30:04.316124Z","steps":["trace[2116750683] 'agreement among raft nodes before linearized reading' (duration: 135.578937ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:30:04.316115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.87948ms","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-09-08T16:30:04.316183Z","caller":"traceutil/trace.go:171","msg":"trace[849029829] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20492; }","duration":"102.991593ms","start":"2026-09-08T16:30:04.213173Z","end":"2026-09-08T16:30:04.316164Z","steps":["trace[849029829] 'agreement among raft nodes before linearized reading' (duration: 102.748307ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:30:04.520536Z","caller":"traceutil/trace.go:171","msg":"trace[323208614] transaction","detail":"{read_only:false; response_revision:20494; number_of_response:1; }","duration":"199.077391ms","start":"2026-09-08T16:30:04.32141Z","end":"2026-09-08T16:30:04.520487Z","steps":["trace[323208614] 'process raft request' (duration: 198.684471ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:30:04.520518Z","caller":"traceutil/trace.go:171","msg":"trace[765671790] transaction","detail":"{read_only:false; response_revision:20496; number_of_response:1; }","duration":"197.089189ms","start":"2026-09-08T16:30:04.323392Z","end":"2026-09-08T16:30:04.520481Z","steps":["trace[765671790] 'process raft request' (duration: 197.019397ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:30:04.520615Z","caller":"traceutil/trace.go:171","msg":"trace[2045328276] transaction","detail":"{read_only:false; response_revision:20495; number_of_response:1; }","duration":"197.924141ms","start":"2026-09-08T16:30:04.322648Z","end":"2026-09-08T16:30:04.520572Z","steps":["trace[2045328276] 'process raft request' (duration: 197.706125ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:30:05.222876Z","caller":"traceutil/trace.go:171","msg":"trace[1620169765] transaction","detail":"{read_only:false; response_revision:20497; number_of_response:1; }","duration":"100.015145ms","start":"2026-09-08T16:30:05.122831Z","end":"2026-09-08T16:30:05.222846Z","steps":["trace[1620169765] 'process raft request' (duration: 99.82375ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:33:37.02132Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19998} {"level":"info","ts":"2026-09-08T16:33:37.144223Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19998,"took":"119.397298ms","hash":3773374704,"current-db-size-bytes":56123392,"current-db-size":"56 MB","current-db-size-in-use-bytes":30273536,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-08T16:33:37.1443Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3773374704,"revision":19998,"compact-revision":17528} {"level":"info","ts":"2026-09-08T16:34:30.205902Z","caller":"traceutil/trace.go:171","msg":"trace[165529758] transaction","detail":"{read_only:false; response_revision:23101; number_of_response:1; }","duration":"104.456772ms","start":"2026-09-08T16:34:30.101406Z","end":"2026-09-08T16:34:30.205862Z","steps":["trace[165529758] 'process raft request' (duration: 104.133033ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:34:40.507406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.877502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-08T16:34:40.507841Z","caller":"traceutil/trace.go:171","msg":"trace[607834484] transaction","detail":"{read_only:false; response_revision:23194; number_of_response:1; }","duration":"140.372315ms","start":"2026-09-08T16:34:40.367454Z","end":"2026-09-08T16:34:40.507827Z","steps":["trace[607834484] 'process raft request' (duration: 140.048287ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:34:40.50787Z","caller":"traceutil/trace.go:171","msg":"trace[695341931] transaction","detail":"{read_only:false; response_revision:23193; number_of_response:1; }","duration":"194.371608ms","start":"2026-09-08T16:34:40.313451Z","end":"2026-09-08T16:34:40.507822Z","steps":["trace[695341931] 'process raft request' (duration: 91.979822ms)","trace[695341931] 'compare' (duration: 101.754009ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:36:12.486644Z","caller":"traceutil/trace.go:171","msg":"trace[1730922853] transaction","detail":"{read_only:false; response_revision:24198; number_of_response:1; }","duration":"158.393633ms","start":"2026-09-08T16:36:12.328214Z","end":"2026-09-08T16:36:12.486608Z","steps":["trace[1730922853] 'process raft request' (duration: 152.099065ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:38:37.032972Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22179} {"level":"info","ts":"2026-09-08T16:38:37.151479Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22179,"took":"114.037738ms","hash":355320797,"current-db-size-bytes":56123392,"current-db-size":"56 MB","current-db-size-in-use-bytes":42356736,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-08T16:38:37.151557Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":355320797,"revision":22179,"compact-revision":19998} {"level":"info","ts":"2026-09-08T16:43:37.050271Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25278} {"level":"info","ts":"2026-09-08T16:43:37.212656Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25278,"took":"158.164726ms","hash":1044266816,"current-db-size-bytes":56123392,"current-db-size":"56 MB","current-db-size-in-use-bytes":29859840,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-08T16:43:37.213595Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1044266816,"revision":25278,"compact-revision":22179}