{"level":"warn","ts":"2026-06-17T15:21:13.943452Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-17T15:21:13.943533Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162: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-06-17T15:21:13.943598Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-17T15:21:13.943608Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-06-17T15:21:13.943646Z","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-06-17T15:21:13.944269Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-06-17T15:21:13.94436Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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-06-17T15:21:13.948916Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.317813ms"} {"level":"info","ts":"2026-06-17T15:21:13.955154Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-06-17T15:21:13.955241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-06-17T15:21:13.955268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-06-17T15:21:13.955288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-17T15:21:13.955299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-06-17T15:21:13.955372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-06-17T15:21:13.966779Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-17T15:21:13.968324Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-17T15:21:13.969422Z","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-06-17T15:21:13.972074Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-17T15:21:13.972477Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-17T15:21:13.972477Z","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-06-17T15:21:13.972739Z","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-06-17T15:21:13.972845Z","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-06-17T15:21:13.973087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-06-17T15:21:13.975878Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-06-17T15:21:13.983456Z","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-06-17T15:21:13.983541Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-06-17T15:21:13.983563Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-06-17T15:21:13.98386Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-17T15:21:13.983913Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-17T15:21:14.156142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-06-17T15:21:14.156221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-06-17T15:21:14.156252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-06-17T15:21:14.156273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-06-17T15:21:14.156303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-06-17T15:21:14.15632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-06-17T15:21:14.156339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-06-17T15:21:14.157492Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-17T15:21:14.158464Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-17T15:21:14.158465Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-06-17T15:21:14.158942Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-06-17T15:21:14.159045Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-17T15:21:14.159056Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-17T15:21:14.15908Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-17T15:21:14.160736Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-17T15:21:14.161984Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-06-17T15:21:14.168247Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-17T15:21:14.168263Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-17T15:21:26.815252Z","caller":"traceutil/trace.go:171","msg":"trace[1448630475] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"175.38451ms","start":"2026-06-17T15:21:26.639833Z","end":"2026-06-17T15:21:26.815218Z","steps":["trace[1448630475] 'process raft request' (duration: 175.188425ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:21:32.374706Z","caller":"traceutil/trace.go:171","msg":"trace[1492240682] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"106.782058ms","start":"2026-06-17T15:21:32.267898Z","end":"2026-06-17T15:21:32.374681Z","steps":["trace[1492240682] 'process raft request' (duration: 106.637003ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:21:38.054153Z","caller":"traceutil/trace.go:171","msg":"trace[993956493] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:404; }","duration":"498.657449ms","start":"2026-06-17T15:21:37.555465Z","end":"2026-06-17T15:21:38.054122Z","steps":["trace[993956493] 'read index received' (duration: 492.146259ms)","trace[993956493] 'applied index is now lower than readState.Index' (duration: 6.50562ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:21:38.054283Z","caller":"traceutil/trace.go:171","msg":"trace[76386704] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"632.486198ms","start":"2026-06-17T15:21:37.421763Z","end":"2026-06-17T15:21:38.05425Z","steps":["trace[76386704] 'process raft request' (duration: 625.792403ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:21:38.054381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.282897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-17T15:21:38.054423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.927777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-17T15:21:38.054457Z","caller":"traceutil/trace.go:171","msg":"trace[1494650728] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:392; }","duration":"232.435891ms","start":"2026-06-17T15:21:37.82201Z","end":"2026-06-17T15:21:38.054445Z","steps":["trace[1494650728] 'agreement among raft nodes before linearized reading' (duration: 232.280107ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:21:38.054506Z","caller":"traceutil/trace.go:171","msg":"trace[820736739] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:392; }","duration":"499.115761ms","start":"2026-06-17T15:21:37.555371Z","end":"2026-06-17T15:21:38.054487Z","steps":["trace[820736739] 'agreement among raft nodes before linearized reading' (duration: 498.903166ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:21:38.054572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:21:37.555354Z","time spent":"499.192103ms","remote":"127.0.0.1:42642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.162\" "} {"level":"warn","ts":"2026-06-17T15:21:38.054939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:21:37.421735Z","time spent":"632.645833ms","remote":"127.0.0.1:43082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-17T15:21:38.055283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.402975ms","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-06-17T15:21:38.055448Z","caller":"traceutil/trace.go:171","msg":"trace[250046232] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:392; }","duration":"193.635491ms","start":"2026-06-17T15:21:37.86178Z","end":"2026-06-17T15:21:38.055415Z","steps":["trace[250046232] 'agreement among raft nodes before linearized reading' (duration: 192.573803ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:21:51.959747Z","caller":"traceutil/trace.go:171","msg":"trace[1242486148] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"198.008996ms","start":"2026-06-17T15:21:51.761649Z","end":"2026-06-17T15:21:51.959658Z","steps":["trace[1242486148] 'process raft request' (duration: 194.719049ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:21:51.963033Z","caller":"traceutil/trace.go:171","msg":"trace[2079395091] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"197.502353ms","start":"2026-06-17T15:21:51.765505Z","end":"2026-06-17T15:21:51.963008Z","steps":["trace[2079395091] 'process raft request' (duration: 197.278947ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:21:51.963128Z","caller":"traceutil/trace.go:171","msg":"trace[1109021688] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"197.232575ms","start":"2026-06-17T15:21:51.765869Z","end":"2026-06-17T15:21:51.963102Z","steps":["trace[1109021688] 'process raft request' (duration: 197.04826ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:21:51.963179Z","caller":"traceutil/trace.go:171","msg":"trace[1011378119] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"193.57891ms","start":"2026-06-17T15:21:51.769593Z","end":"2026-06-17T15:21:51.963172Z","steps":["trace[1011378119] 'process raft request' (duration: 193.381755ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:21:51.963226Z","caller":"traceutil/trace.go:171","msg":"trace[29609306] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"182.329134ms","start":"2026-06-17T15:21:51.780892Z","end":"2026-06-17T15:21:51.963221Z","steps":["trace[29609306] 'process raft request' (duration: 182.18408ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:21:51.963246Z","caller":"traceutil/trace.go:171","msg":"trace[1015349140] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:525; }","duration":"143.501003ms","start":"2026-06-17T15:21:51.819701Z","end":"2026-06-17T15:21:51.963202Z","steps":["trace[1015349140] 'read index received' (duration: 136.738545ms)","trace[1015349140] 'applied index is now lower than readState.Index' (duration: 6.761518ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:21:51.963365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.651377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:21:51.963422Z","caller":"traceutil/trace.go:171","msg":"trace[1926752723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"143.736059ms","start":"2026-06-17T15:21:51.819674Z","end":"2026-06-17T15:21:51.96341Z","steps":["trace[1926752723] 'agreement among raft nodes before linearized reading' (duration: 143.634366ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:21:57.903406Z","caller":"traceutil/trace.go:171","msg":"trace[28069438] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"279.72847ms","start":"2026-06-17T15:21:57.62357Z","end":"2026-06-17T15:21:57.903298Z","steps":["trace[28069438] 'process raft request' (duration: 279.274338ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:21:58.300415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.774848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:21:58.300613Z","caller":"traceutil/trace.go:171","msg":"trace[589434340] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"341.808353ms","start":"2026-06-17T15:21:57.958715Z","end":"2026-06-17T15:21:58.300523Z","steps":["trace[589434340] 'process raft request' (duration: 231.763729ms)","trace[589434340] 'compare' (duration: 109.637553ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:21:58.300872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:21:57.95869Z","time spent":"342.066099ms","remote":"127.0.0.1:43112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-17T15:21:59.323145Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085745427254470727,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-17T15:21:59.340043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.272356ms","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-06-17T15:21:59.340103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.043277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-17T15:21:59.340162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.957313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fcm76\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-17T15:21:59.340312Z","caller":"traceutil/trace.go:171","msg":"trace[395747811] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fcm76; range_end:; response_count:1; response_revision:595; }","duration":"933.168819ms","start":"2026-06-17T15:21:58.407069Z","end":"2026-06-17T15:21:59.340238Z","steps":["trace[395747811] 'range keys from in-memory index tree' (duration: 932.808359ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:21:59.340373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:21:58.407042Z","time spent":"933.321314ms","remote":"127.0.0.1:42792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-fcm76\" "} {"level":"info","ts":"2026-06-17T15:21:59.340501Z","caller":"traceutil/trace.go:171","msg":"trace[1615917145] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"830.168371ms","start":"2026-06-17T15:21:58.509977Z","end":"2026-06-17T15:21:59.340145Z","steps":["trace[1615917145] 'range keys from in-memory index tree' (duration: 829.895403ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:21:59.340923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:21:58.509944Z","time spent":"830.92753ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-17T15:21:59.341053Z","caller":"traceutil/trace.go:171","msg":"trace[1335493624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"721.403549ms","start":"2026-06-17T15:21:58.618702Z","end":"2026-06-17T15:21:59.340106Z","steps":["trace[1335493624] 'range keys from in-memory index tree' (duration: 721.100582ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:21:59.341117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:21:58.61868Z","time spent":"722.424667ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-17T15:21:59.341782Z","caller":"traceutil/trace.go:171","msg":"trace[1613998722] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"519.628054ms","start":"2026-06-17T15:21:58.822141Z","end":"2026-06-17T15:21:59.341769Z","steps":["trace[1613998722] 'read index received' (duration: 518.651448ms)","trace[1613998722] 'applied index is now lower than readState.Index' (duration: 975.986µs)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:21:59.341927Z","caller":"traceutil/trace.go:171","msg":"trace[2002520785] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"593.638385ms","start":"2026-06-17T15:21:58.748266Z","end":"2026-06-17T15:21:59.341904Z","steps":["trace[2002520785] 'process raft request' (duration: 592.776003ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:21:59.343435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.297687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-17T15:21:59.342035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.892582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-17T15:21:59.343521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:21:58.748241Z","time spent":"594.872318ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:21:59.343503Z","caller":"traceutil/trace.go:171","msg":"trace[1985741972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:596; }","duration":"376.395221ms","start":"2026-06-17T15:21:58.96709Z","end":"2026-06-17T15:21:59.343486Z","steps":["trace[1985741972] 'agreement among raft nodes before linearized reading' (duration: 376.243256ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:21:59.3436Z","caller":"traceutil/trace.go:171","msg":"trace[380704403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"521.481593ms","start":"2026-06-17T15:21:58.822087Z","end":"2026-06-17T15:21:59.343568Z","steps":["trace[380704403] 'agreement among raft nodes before linearized reading' (duration: 519.87069ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:21:59.343667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:21:58.822063Z","time spent":"521.587136ms","remote":"127.0.0.1:42614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-17T15:21:59.34374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:21:58.967068Z","time spent":"376.571405ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-17T15:22:36.736776Z","caller":"traceutil/trace.go:171","msg":"trace[539417962] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"232.819427ms","start":"2026-06-17T15:22:36.503929Z","end":"2026-06-17T15:22:36.736748Z","steps":["trace[539417962] 'process raft request' (duration: 232.657223ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:22:36.740647Z","caller":"traceutil/trace.go:171","msg":"trace[1152804720] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"101.385095ms","start":"2026-06-17T15:22:36.639243Z","end":"2026-06-17T15:22:36.740628Z","steps":["trace[1152804720] 'process raft request' (duration: 100.81062ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:22:37.794401Z","caller":"traceutil/trace.go:171","msg":"trace[1412498369] linearizableReadLoop","detail":"{readStateIndex:1159; appliedIndex:1158; }","duration":"268.065473ms","start":"2026-06-17T15:22:37.526316Z","end":"2026-06-17T15:22:37.794382Z","steps":["trace[1412498369] 'read index received' (duration: 268.029911ms)","trace[1412498369] 'applied index is now lower than readState.Index' (duration: 34.032µs)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:22:37.794497Z","caller":"traceutil/trace.go:171","msg":"trace[106490806] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"268.304889ms","start":"2026-06-17T15:22:37.526184Z","end":"2026-06-17T15:22:37.794489Z","steps":["trace[106490806] 'process raft request' (duration: 268.077192ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:22:37.794855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.509095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-17T15:22:37.794941Z","caller":"traceutil/trace.go:171","msg":"trace[348638366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1130; }","duration":"268.655018ms","start":"2026-06-17T15:22:37.526268Z","end":"2026-06-17T15:22:37.794923Z","steps":["trace[348638366] 'agreement among raft nodes before linearized reading' (duration: 268.245777ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:22:38.295287Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085745427254471715,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-17T15:22:38.795748Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085745427254471715,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-17T15:22:38.887418Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.092448857s","expected-duration":"1s"} {"level":"info","ts":"2026-06-17T15:22:38.889101Z","caller":"traceutil/trace.go:171","msg":"trace[815204619] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"1.275085576s","start":"2026-06-17T15:22:37.613964Z","end":"2026-06-17T15:22:38.889049Z","steps":["trace[815204619] 'process raft request' (duration: 1.274443169s)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:22:38.888536Z","caller":"traceutil/trace.go:171","msg":"trace[126178493] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"1.360108968s","start":"2026-06-17T15:22:37.528386Z","end":"2026-06-17T15:22:38.888495Z","steps":["trace[126178493] 'process raft request' (duration: 1.359753469s)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:22:38.889083Z","caller":"traceutil/trace.go:171","msg":"trace[340544509] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"1.188173954s","start":"2026-06-17T15:22:37.700875Z","end":"2026-06-17T15:22:38.889049Z","steps":["trace[340544509] 'process raft request' (duration: 1.188052421s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:22:38.889379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:22:37.613928Z","time spent":"1.275296032s","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:22:38.889415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:22:37.528366Z","time spent":"1.360894629s","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:22:38.889457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:22:37.700794Z","time spent":"1.188483241s","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:22:38.892622Z","caller":"traceutil/trace.go:171","msg":"trace[181623186] linearizableReadLoop","detail":"{readStateIndex:1163; appliedIndex:1159; }","duration":"1.098139949s","start":"2026-06-17T15:22:37.794465Z","end":"2026-06-17T15:22:38.892604Z","steps":["trace[181623186] 'read index received' (duration: 1.093611089s)","trace[181623186] 'applied index is now lower than readState.Index' (duration: 4.52763ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:22:38.892679Z","caller":"traceutil/trace.go:171","msg":"trace[143529245] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"1.092168141s","start":"2026-06-17T15:22:37.800489Z","end":"2026-06-17T15:22:38.892657Z","steps":["trace[143529245] 'process raft request' (duration: 1.092032906s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:22:38.892774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:22:37.800477Z","time spent":"1.092234441s","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:22:38.892887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.784551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-17T15:22:38.892886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.663753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-17T15:22:38.892939Z","caller":"traceutil/trace.go:171","msg":"trace[1287891084] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1134; }","duration":"151.856772ms","start":"2026-06-17T15:22:38.741065Z","end":"2026-06-17T15:22:38.892921Z","steps":["trace[1287891084] 'agreement among raft nodes before linearized reading' (duration: 151.702268ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:22:38.892959Z","caller":"traceutil/trace.go:171","msg":"trace[1844394278] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1134; }","duration":"824.789776ms","start":"2026-06-17T15:22:38.068149Z","end":"2026-06-17T15:22:38.892939Z","steps":["trace[1844394278] 'agreement among raft nodes before linearized reading' (duration: 824.640423ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:22:38.892935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.334359816s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-06-17T15:22:38.893048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033264029s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:22:38.893164Z","caller":"traceutil/trace.go:171","msg":"trace[89278117] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1134; }","duration":"1.033735182s","start":"2026-06-17T15:22:37.859415Z","end":"2026-06-17T15:22:38.89315Z","steps":["trace[89278117] 'agreement among raft nodes before linearized reading' (duration: 1.03329228s)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:22:38.893075Z","caller":"traceutil/trace.go:171","msg":"trace[1833600666] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:1134; }","duration":"1.334525791s","start":"2026-06-17T15:22:37.558503Z","end":"2026-06-17T15:22:38.893029Z","steps":["trace[1833600666] 'agreement among raft nodes before linearized reading' (duration: 1.334194842s)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:22:38.893302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:22:37.558489Z","time spent":"1.334787997s","remote":"127.0.0.1:42642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.162\" "} {"level":"warn","ts":"2026-06-17T15:22:38.892974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.375527ms","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-06-17T15:22:38.893408Z","caller":"traceutil/trace.go:171","msg":"trace[120103787] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1134; }","duration":"109.900761ms","start":"2026-06-17T15:22:38.783491Z","end":"2026-06-17T15:22:38.893391Z","steps":["trace[120103787] 'agreement among raft nodes before linearized reading' (duration: 109.263485ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:22:38.892996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:22:38.068121Z","time spent":"824.867889ms","remote":"127.0.0.1:42712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":7,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-06-17T15:22:38.893713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:22:37.859366Z","time spent":"1.034334357s","remote":"127.0.0.1:42606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-17T15:23:04.041882Z","caller":"traceutil/trace.go:171","msg":"trace[1348370145] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"121.945955ms","start":"2026-06-17T15:23:03.919878Z","end":"2026-06-17T15:23:04.041824Z","steps":["trace[1348370145] 'process raft request' (duration: 121.759871ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:23:16.851338Z","caller":"traceutil/trace.go:171","msg":"trace[134017599] transaction","detail":"{read_only:false; response_revision:1654; number_of_response:1; }","duration":"148.897218ms","start":"2026-06-17T15:23:16.7024Z","end":"2026-06-17T15:23:16.851297Z","steps":["trace[134017599] 'process raft request' (duration: 118.087754ms)","trace[134017599] 'compare' (duration: 30.610629ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:23:16.851316Z","caller":"traceutil/trace.go:171","msg":"trace[1391721611] linearizableReadLoop","detail":"{readStateIndex:1712; appliedIndex:1710; }","duration":"117.958781ms","start":"2026-06-17T15:23:16.733329Z","end":"2026-06-17T15:23:16.851288Z","steps":["trace[1391721611] 'read index received' (duration: 22.784814ms)","trace[1391721611] 'applied index is now lower than readState.Index' (duration: 95.168467ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:23:16.851559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.178807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:23:16.851701Z","caller":"traceutil/trace.go:171","msg":"trace[26472511] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:1654; }","duration":"118.384451ms","start":"2026-06-17T15:23:16.733292Z","end":"2026-06-17T15:23:16.851677Z","steps":["trace[26472511] 'agreement among raft nodes before linearized reading' (duration: 118.046172ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:23:16.851736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.259808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:23:16.851802Z","caller":"traceutil/trace.go:171","msg":"trace[1016772483] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:1654; }","duration":"118.387261ms","start":"2026-06-17T15:23:16.733395Z","end":"2026-06-17T15:23:16.851782Z","steps":["trace[1016772483] 'agreement among raft nodes before linearized reading' (duration: 118.256468ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:23:16.851925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.676802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:23:16.852058Z","caller":"traceutil/trace.go:171","msg":"trace[323321832] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:1654; }","duration":"117.861988ms","start":"2026-06-17T15:23:16.734169Z","end":"2026-06-17T15:23:16.852031Z","steps":["trace[323321832] 'agreement among raft nodes before linearized reading' (duration: 117.644692ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:23:20.817482Z","caller":"traceutil/trace.go:171","msg":"trace[1699933024] transaction","detail":"{read_only:false; response_revision:1704; number_of_response:1; }","duration":"153.535582ms","start":"2026-06-17T15:23:20.663912Z","end":"2026-06-17T15:23:20.817448Z","steps":["trace[1699933024] 'process raft request' (duration: 153.317345ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:23:20.917832Z","caller":"traceutil/trace.go:171","msg":"trace[865659816] transaction","detail":"{read_only:false; response_revision:1705; number_of_response:1; }","duration":"144.924603ms","start":"2026-06-17T15:23:20.772858Z","end":"2026-06-17T15:23:20.917783Z","steps":["trace[865659816] 'process raft request' (duration: 140.148167ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:23:29.021997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.392654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:23:29.022108Z","caller":"traceutil/trace.go:171","msg":"trace[314556506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1826; }","duration":"199.548148ms","start":"2026-06-17T15:23:28.82254Z","end":"2026-06-17T15:23:29.022088Z","steps":["trace[314556506] 'range keys from in-memory index tree' (duration: 199.29388ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:23:29.022218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.787541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-17T15:23:29.022358Z","caller":"traceutil/trace.go:171","msg":"trace[1939805770] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1826; }","duration":"369.974716ms","start":"2026-06-17T15:23:28.652351Z","end":"2026-06-17T15:23:29.022325Z","steps":["trace[1939805770] 'count revisions from in-memory index tree' (duration: 369.605386ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:23:29.022419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:23:28.652326Z","time spent":"370.075979ms","remote":"127.0.0.1:42678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":228,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-06-17T15:23:29.02265Z","caller":"traceutil/trace.go:171","msg":"trace[2051198499] linearizableReadLoop","detail":"{readStateIndex:1893; appliedIndex:1892; }","duration":"110.74637ms","start":"2026-06-17T15:23:28.911874Z","end":"2026-06-17T15:23:29.02262Z","steps":["trace[2051198499] 'read index received' (duration: 40.645425ms)","trace[2051198499] 'applied index is now lower than readState.Index' (duration: 70.099625ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:23:29.022727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.704342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-17T15:23:29.022727Z","caller":"traceutil/trace.go:171","msg":"trace[386689058] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"175.399559ms","start":"2026-06-17T15:23:28.84728Z","end":"2026-06-17T15:23:29.022679Z","steps":["trace[386689058] 'process raft request' (duration: 105.259064ms)","trace[386689058] 'compare' (duration: 69.644392ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:23:29.022777Z","caller":"traceutil/trace.go:171","msg":"trace[929090417] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1826; }","duration":"633.785783ms","start":"2026-06-17T15:23:28.388979Z","end":"2026-06-17T15:23:29.022765Z","steps":["trace[929090417] 'range keys from in-memory index tree' (duration: 633.494826ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:23:29.022751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.189633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-554df3bb-db28-4fc8-b4a6-622904ab15d4\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-06-17T15:23:29.02281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:23:28.388941Z","time spent":"633.861325ms","remote":"127.0.0.1:49428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-06-17T15:23:29.022833Z","caller":"traceutil/trace.go:171","msg":"trace[1969759662] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-554df3bb-db28-4fc8-b4a6-622904ab15d4; range_end:; response_count:1; response_revision:1826; }","duration":"223.346626ms","start":"2026-06-17T15:23:28.799468Z","end":"2026-06-17T15:23:29.022814Z","steps":["trace[1969759662] 'range keys from in-memory index tree' (duration: 222.741051ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:23:29.022926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.040827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-554df3bb-db28-4fc8-b4a6-622904ab15d4\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-17T15:23:29.023007Z","caller":"traceutil/trace.go:171","msg":"trace[1043616013] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-554df3bb-db28-4fc8-b4a6-622904ab15d4; range_end:; response_count:1; response_revision:1827; }","duration":"111.15117ms","start":"2026-06-17T15:23:28.911812Z","end":"2026-06-17T15:23:29.022963Z","steps":["trace[1043616013] 'agreement among raft nodes before linearized reading' (duration: 110.932764ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:23:29.840383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.032169ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:23:29.84054Z","caller":"traceutil/trace.go:171","msg":"trace[1458262788] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1832; }","duration":"274.198853ms","start":"2026-06-17T15:23:29.566311Z","end":"2026-06-17T15:23:29.84051Z","steps":["trace[1458262788] 'range keys from in-memory index tree' (duration: 274.007188ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:23:29.851832Z","caller":"traceutil/trace.go:171","msg":"trace[1413786760] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"575.761578ms","start":"2026-06-17T15:23:29.276027Z","end":"2026-06-17T15:23:29.851788Z","steps":["trace[1413786760] 'process raft request' (duration: 575.574603ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:23:29.851988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:23:29.276011Z","time spent":"575.902112ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:23:29.852128Z","caller":"traceutil/trace.go:171","msg":"trace[911993791] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"575.622295ms","start":"2026-06-17T15:23:29.276484Z","end":"2026-06-17T15:23:29.852106Z","steps":["trace[911993791] 'process raft request' (duration: 575.264896ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:23:29.852269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:23:29.276455Z","time spent":"575.737168ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:23:29.852259Z","caller":"traceutil/trace.go:171","msg":"trace[1899674841] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"572.351009ms","start":"2026-06-17T15:23:29.27988Z","end":"2026-06-17T15:23:29.852231Z","steps":["trace[1899674841] 'process raft request' (duration: 572.137453ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:23:29.852418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:23:29.279785Z","time spent":"572.544624ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:23:29.853233Z","caller":"traceutil/trace.go:171","msg":"trace[252831240] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"104.328089ms","start":"2026-06-17T15:23:29.74887Z","end":"2026-06-17T15:23:29.853198Z","steps":["trace[252831240] 'process raft request' (duration: 104.168706ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:23:34.378282Z","caller":"traceutil/trace.go:171","msg":"trace[1083208781] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"198.253844ms","start":"2026-06-17T15:23:34.179951Z","end":"2026-06-17T15:23:34.378205Z","steps":["trace[1083208781] 'process raft request' (duration: 197.900875ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:23:41.434144Z","caller":"traceutil/trace.go:171","msg":"trace[1947159919] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"115.583578ms","start":"2026-06-17T15:23:41.318524Z","end":"2026-06-17T15:23:41.434108Z","steps":["trace[1947159919] 'process raft request' (duration: 115.384362ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:23:41.604334Z","caller":"traceutil/trace.go:171","msg":"trace[1516457622] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"165.498768ms","start":"2026-06-17T15:23:41.438797Z","end":"2026-06-17T15:23:41.604296Z","steps":["trace[1516457622] 'process raft request' (duration: 86.792106ms)","trace[1516457622] 'compare' (duration: 78.552177ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:23:51.385727Z","caller":"traceutil/trace.go:171","msg":"trace[1934061887] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"209.975364ms","start":"2026-06-17T15:23:51.175728Z","end":"2026-06-17T15:23:51.385703Z","steps":["trace[1934061887] 'process raft request' (duration: 209.8228ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:23:51.387361Z","caller":"traceutil/trace.go:171","msg":"trace[1895678626] linearizableReadLoop","detail":"{readStateIndex:2064; appliedIndex:2062; }","duration":"141.617016ms","start":"2026-06-17T15:23:51.245723Z","end":"2026-06-17T15:23:51.38734Z","steps":["trace[1895678626] 'read index received' (duration: 139.834839ms)","trace[1895678626] 'applied index is now lower than readState.Index' (duration: 1.781237ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:23:51.387592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.809801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-17T15:23:51.387631Z","caller":"traceutil/trace.go:171","msg":"trace[917611581] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1994; }","duration":"141.931445ms","start":"2026-06-17T15:23:51.245686Z","end":"2026-06-17T15:23:51.387617Z","steps":["trace[917611581] 'agreement among raft nodes before linearized reading' (duration: 141.75219ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:23:51.387492Z","caller":"traceutil/trace.go:171","msg":"trace[600669073] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"187.018436ms","start":"2026-06-17T15:23:51.20043Z","end":"2026-06-17T15:23:51.387448Z","steps":["trace[600669073] 'process raft request' (duration: 186.79718ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:18.020492Z","caller":"traceutil/trace.go:171","msg":"trace[455509454] linearizableReadLoop","detail":"{readStateIndex:2221; appliedIndex:2220; }","duration":"199.65358ms","start":"2026-06-17T15:24:17.820812Z","end":"2026-06-17T15:24:18.020465Z","steps":["trace[455509454] 'read index received' (duration: 199.541627ms)","trace[455509454] 'applied index is now lower than readState.Index' (duration: 110.703µs)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:24:18.020553Z","caller":"traceutil/trace.go:171","msg":"trace[1457354121] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"207.573882ms","start":"2026-06-17T15:24:17.812936Z","end":"2026-06-17T15:24:18.02051Z","steps":["trace[1457354121] 'process raft request' (duration: 207.372726ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:18.020635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.787165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:24:18.02068Z","caller":"traceutil/trace.go:171","msg":"trace[771276716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2145; }","duration":"199.885267ms","start":"2026-06-17T15:24:17.820781Z","end":"2026-06-17T15:24:18.020666Z","steps":["trace[771276716] 'agreement among raft nodes before linearized reading' (duration: 199.789915ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:24.025932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.256751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:24:24.02605Z","caller":"traceutil/trace.go:171","msg":"trace[1644490463] linearizableReadLoop","detail":"{readStateIndex:2253; appliedIndex:2252; }","duration":"202.665791ms","start":"2026-06-17T15:24:23.823368Z","end":"2026-06-17T15:24:24.026034Z","steps":["trace[1644490463] 'read index received' (duration: 20.139983ms)","trace[1644490463] 'applied index is now lower than readState.Index' (duration: 182.523948ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:24:24.026125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.767294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:24:24.026158Z","caller":"traceutil/trace.go:171","msg":"trace[339935707] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2176; }","duration":"202.847725ms","start":"2026-06-17T15:24:23.8233Z","end":"2026-06-17T15:24:24.026148Z","steps":["trace[339935707] 'agreement among raft nodes before linearized reading' (duration: 202.782124ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:24.026348Z","caller":"traceutil/trace.go:171","msg":"trace[2027557916] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"307.70526ms","start":"2026-06-17T15:24:23.718592Z","end":"2026-06-17T15:24:24.026297Z","steps":["trace[2027557916] 'process raft request' (duration: 124.992116ms)","trace[2027557916] 'compare' (duration: 182.039935ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:24:24.026576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:23.718549Z","time spent":"307.902805ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:24:30.066524Z","caller":"traceutil/trace.go:171","msg":"trace[1344750730] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"470.797902ms","start":"2026-06-17T15:24:29.595705Z","end":"2026-06-17T15:24:30.066503Z","steps":["trace[1344750730] 'process raft request' (duration: 470.071723ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:30.066663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:29.59567Z","time spent":"470.920656ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:24:30.307368Z","caller":"traceutil/trace.go:171","msg":"trace[1370774272] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"662.296297ms","start":"2026-06-17T15:24:29.645051Z","end":"2026-06-17T15:24:30.307347Z","steps":["trace[1370774272] 'process raft request' (duration: 662.217605ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:30.307559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:29.645025Z","time spent":"662.443833ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:24:30.307542Z","caller":"traceutil/trace.go:171","msg":"trace[2090947739] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"702.224534ms","start":"2026-06-17T15:24:29.605264Z","end":"2026-06-17T15:24:30.307489Z","steps":["trace[2090947739] 'process raft request' (duration: 701.861084ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:30.307599Z","caller":"traceutil/trace.go:171","msg":"trace[2104148851] linearizableReadLoop","detail":"{readStateIndex:2284; appliedIndex:2280; }","duration":"636.754131ms","start":"2026-06-17T15:24:29.670811Z","end":"2026-06-17T15:24:30.307565Z","steps":["trace[2104148851] 'read index received' (duration: 395.038888ms)","trace[2104148851] 'applied index is now lower than readState.Index' (duration: 241.712693ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:24:30.307706Z","caller":"traceutil/trace.go:171","msg":"trace[494335311] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"661.961718ms","start":"2026-06-17T15:24:29.645705Z","end":"2026-06-17T15:24:30.307666Z","steps":["trace[494335311] 'process raft request' (duration: 661.607129ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:30.307773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:29.605244Z","time spent":"702.397919ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:24:30.307911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:29.645699Z","time spent":"662.100682ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:24:30.30789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.051289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-17T15:24:30.308005Z","caller":"traceutil/trace.go:171","msg":"trace[1372183630] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2205; }","duration":"637.210874ms","start":"2026-06-17T15:24:29.670766Z","end":"2026-06-17T15:24:30.307977Z","steps":["trace[1372183630] 'agreement among raft nodes before linearized reading' (duration: 636.934498ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:30.308068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:29.670745Z","time spent":"637.309607ms","remote":"127.0.0.1:49428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-17T15:24:30.335583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.64341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-17T15:24:30.335671Z","caller":"traceutil/trace.go:171","msg":"trace[501725390] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:2206; }","duration":"529.787272ms","start":"2026-06-17T15:24:29.805862Z","end":"2026-06-17T15:24:30.33565Z","steps":["trace[501725390] 'agreement among raft nodes before linearized reading' (duration: 529.617809ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:30.335718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:29.805833Z","time spent":"529.876115ms","remote":"127.0.0.1:42876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-06-17T15:24:30.335934Z","caller":"traceutil/trace.go:171","msg":"trace[1250139059] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"138.526035ms","start":"2026-06-17T15:24:30.197373Z","end":"2026-06-17T15:24:30.335899Z","steps":["trace[1250139059] 'process raft request' (duration: 137.958629ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:30.336034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.706105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:24:30.336068Z","caller":"traceutil/trace.go:171","msg":"trace[600094942] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2206; }","duration":"514.768255ms","start":"2026-06-17T15:24:29.82129Z","end":"2026-06-17T15:24:30.336058Z","steps":["trace[600094942] 'agreement among raft nodes before linearized reading' (duration: 514.705653ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:30.336103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:29.821268Z","time spent":"514.828967ms","remote":"127.0.0.1:42606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-17T15:24:30.336162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.028804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-17T15:24:30.336224Z","caller":"traceutil/trace.go:171","msg":"trace[218467886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2206; }","duration":"261.174408ms","start":"2026-06-17T15:24:30.075032Z","end":"2026-06-17T15:24:30.336207Z","steps":["trace[218467886] 'agreement among raft nodes before linearized reading' (duration: 260.909291ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:30.854334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.699916ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:24:30.854598Z","caller":"traceutil/trace.go:171","msg":"trace[320439411] linearizableReadLoop","detail":"{readStateIndex:2289; appliedIndex:2286; }","duration":"235.434437ms","start":"2026-06-17T15:24:30.61914Z","end":"2026-06-17T15:24:30.854574Z","steps":["trace[320439411] 'read index received' (duration: 118.398041ms)","trace[320439411] 'applied index is now lower than readState.Index' (duration: 117.035086ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:24:30.854644Z","caller":"traceutil/trace.go:171","msg":"trace[503898594] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"513.481412ms","start":"2026-06-17T15:24:30.341115Z","end":"2026-06-17T15:24:30.854596Z","steps":["trace[503898594] 'process raft request' (duration: 396.410915ms)","trace[503898594] 'compare' (duration: 116.572503ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:24:30.854697Z","caller":"traceutil/trace.go:171","msg":"trace[45157916] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"513.524843ms","start":"2026-06-17T15:24:30.341143Z","end":"2026-06-17T15:24:30.854668Z","steps":["trace[45157916] 'process raft request' (duration: 513.301656ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:30.854756Z","caller":"traceutil/trace.go:171","msg":"trace[1095570237] transaction","detail":"{read_only:false; response_revision:2210; number_of_response:1; }","duration":"513.582354ms","start":"2026-06-17T15:24:30.341158Z","end":"2026-06-17T15:24:30.85474Z","steps":["trace[1095570237] 'process raft request' (duration: 513.357688ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:30.85476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:30.341097Z","time spent":"513.609635ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:24:30.854836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:30.341129Z","time spent":"513.662496ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:24:30.85484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:24:30.341133Z","time spent":"513.644426ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:24:30.854908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.768807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-17T15:24:30.854972Z","caller":"traceutil/trace.go:171","msg":"trace[729717894] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2210; }","duration":"235.878989ms","start":"2026-06-17T15:24:30.619081Z","end":"2026-06-17T15:24:30.85496Z","steps":["trace[729717894] 'agreement among raft nodes before linearized reading' (duration: 235.786366ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:24:30.854983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.98905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-17T15:24:30.855028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.207262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-17T15:24:30.855078Z","caller":"traceutil/trace.go:171","msg":"trace[563645520] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:2210; }","duration":"138.150734ms","start":"2026-06-17T15:24:30.716904Z","end":"2026-06-17T15:24:30.855055Z","steps":["trace[563645520] 'agreement among raft nodes before linearized reading' (duration: 137.968578ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:24:30.855111Z","caller":"traceutil/trace.go:171","msg":"trace[1518426046] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2210; }","duration":"187.329484ms","start":"2026-06-17T15:24:30.667746Z","end":"2026-06-17T15:24:30.855075Z","steps":["trace[1518426046] 'agreement among raft nodes before linearized reading' (duration: 187.065947ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:26:03.454916Z","caller":"traceutil/trace.go:171","msg":"trace[1608595468] linearizableReadLoop","detail":"{readStateIndex:3006; appliedIndex:3005; }","duration":"131.398962ms","start":"2026-06-17T15:26:03.323488Z","end":"2026-06-17T15:26:03.454887Z","steps":["trace[1608595468] 'read index received' (duration: 131.176486ms)","trace[1608595468] 'applied index is now lower than readState.Index' (duration: 221.536µs)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:26:03.455194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.350687ms","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-06-17T15:26:03.455239Z","caller":"traceutil/trace.go:171","msg":"trace[587950926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2899; }","duration":"126.441578ms","start":"2026-06-17T15:26:03.328787Z","end":"2026-06-17T15:26:03.455228Z","steps":["trace[587950926] 'agreement among raft nodes before linearized reading' (duration: 126.248603ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:26:03.455106Z","caller":"traceutil/trace.go:171","msg":"trace[555345577] transaction","detail":"{read_only:false; response_revision:2899; number_of_response:1; }","duration":"134.573378ms","start":"2026-06-17T15:26:03.320387Z","end":"2026-06-17T15:26:03.454961Z","steps":["trace[555345577] 'process raft request' (duration: 134.343752ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:26:03.455276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.748501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-17T15:26:03.45532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.292015ms","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-06-17T15:26:03.455416Z","caller":"traceutil/trace.go:171","msg":"trace[1847187930] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2899; }","duration":"131.926596ms","start":"2026-06-17T15:26:03.323444Z","end":"2026-06-17T15:26:03.45537Z","steps":["trace[1847187930] 'agreement among raft nodes before linearized reading' (duration: 131.583016ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:26:03.455446Z","caller":"traceutil/trace.go:171","msg":"trace[1117629970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2899; }","duration":"126.430448ms","start":"2026-06-17T15:26:03.328988Z","end":"2026-06-17T15:26:03.455418Z","steps":["trace[1117629970] 'agreement among raft nodes before linearized reading' (duration: 126.13306ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:29:57.690361Z","caller":"traceutil/trace.go:171","msg":"trace[1179753464] transaction","detail":"{read_only:false; response_revision:5074; number_of_response:1; }","duration":"180.514143ms","start":"2026-06-17T15:29:57.509807Z","end":"2026-06-17T15:29:57.690321Z","steps":["trace[1179753464] 'process raft request' (duration: 107.566383ms)","trace[1179753464] 'compare' (duration: 72.517757ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:29:57.816357Z","caller":"traceutil/trace.go:171","msg":"trace[1420626586] transaction","detail":"{read_only:false; response_revision:5075; number_of_response:1; }","duration":"299.766591ms","start":"2026-06-17T15:29:57.516564Z","end":"2026-06-17T15:29:57.81633Z","steps":["trace[1420626586] 'process raft request' (duration: 297.585814ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:29:57.816344Z","caller":"traceutil/trace.go:171","msg":"trace[333897795] linearizableReadLoop","detail":"{readStateIndex:5263; appliedIndex:5260; }","duration":"254.182525ms","start":"2026-06-17T15:29:57.562133Z","end":"2026-06-17T15:29:57.816316Z","steps":["trace[333897795] 'read index received' (duration: 55.246251ms)","trace[333897795] 'applied index is now lower than readState.Index' (duration: 198.935154ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:29:57.816436Z","caller":"traceutil/trace.go:171","msg":"trace[600822835] transaction","detail":"{read_only:false; response_revision:5076; number_of_response:1; }","duration":"299.816562ms","start":"2026-06-17T15:29:57.516578Z","end":"2026-06-17T15:29:57.816394Z","steps":["trace[600822835] 'process raft request' (duration: 299.666888ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:29:57.816556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.406311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9ff03ded-d12b-4b81-8778-3e42ede8f02d\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-06-17T15:29:57.816618Z","caller":"traceutil/trace.go:171","msg":"trace[973332800] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9ff03ded-d12b-4b81-8778-3e42ede8f02d; range_end:; response_count:1; response_revision:5076; }","duration":"254.519854ms","start":"2026-06-17T15:29:57.562084Z","end":"2026-06-17T15:29:57.816604Z","steps":["trace[973332800] 'agreement among raft nodes before linearized reading' (duration: 254.321858ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:29:57.825353Z","caller":"traceutil/trace.go:171","msg":"trace[1666493535] transaction","detail":"{read_only:false; response_revision:5077; number_of_response:1; }","duration":"113.564855ms","start":"2026-06-17T15:29:57.711761Z","end":"2026-06-17T15:29:57.825326Z","steps":["trace[1666493535] 'process raft request' (duration: 113.348759ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:29:57.825679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.718821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-8qcz8-tls\" ","response":"range_response_count:1 size:2184"} {"level":"warn","ts":"2026-06-17T15:29:57.825695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.200468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-17T15:29:57.825751Z","caller":"traceutil/trace.go:171","msg":"trace[1887176192] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:5078; }","duration":"239.285331ms","start":"2026-06-17T15:29:57.586453Z","end":"2026-06-17T15:29:57.825738Z","steps":["trace[1887176192] 'agreement among raft nodes before linearized reading' (duration: 239.120216ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:29:57.82579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.201543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-17T15:29:57.825816Z","caller":"traceutil/trace.go:171","msg":"trace[1023812141] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:5078; }","duration":"182.244846ms","start":"2026-06-17T15:29:57.643564Z","end":"2026-06-17T15:29:57.825809Z","steps":["trace[1023812141] 'agreement among raft nodes before linearized reading' (duration: 182.171904ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:29:57.825714Z","caller":"traceutil/trace.go:171","msg":"trace[321211736] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-8qcz8-tls; range_end:; response_count:1; response_revision:5078; }","duration":"232.802973ms","start":"2026-06-17T15:29:57.5929Z","end":"2026-06-17T15:29:57.825703Z","steps":["trace[321211736] 'agreement among raft nodes before linearized reading' (duration: 232.640148ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:30:09.035026Z","caller":"traceutil/trace.go:171","msg":"trace[1529836837] transaction","detail":"{read_only:false; response_revision:5305; number_of_response:1; }","duration":"105.566872ms","start":"2026-06-17T15:30:08.929407Z","end":"2026-06-17T15:30:09.034974Z","steps":["trace[1529836837] 'process raft request' (duration: 105.331485ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:30:09.213924Z","caller":"traceutil/trace.go:171","msg":"trace[499546743] transaction","detail":"{read_only:false; response_revision:5308; number_of_response:1; }","duration":"160.69499ms","start":"2026-06-17T15:30:09.0532Z","end":"2026-06-17T15:30:09.213895Z","steps":["trace[499546743] 'process raft request' (duration: 76.067485ms)","trace[499546743] 'compare' (duration: 84.368347ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:30:09.214403Z","caller":"traceutil/trace.go:171","msg":"trace[297083013] transaction","detail":"{read_only:false; response_revision:5309; number_of_response:1; }","duration":"161.162554ms","start":"2026-06-17T15:30:09.053217Z","end":"2026-06-17T15:30:09.21438Z","steps":["trace[297083013] 'process raft request' (duration: 160.612527ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:30:09.598805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.751005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:30:09.599018Z","caller":"traceutil/trace.go:171","msg":"trace[254818426] transaction","detail":"{read_only:false; response_revision:5315; number_of_response:1; }","duration":"352.169515ms","start":"2026-06-17T15:30:09.24682Z","end":"2026-06-17T15:30:09.598989Z","steps":["trace[254818426] 'process raft request' (duration: 88.167343ms)","trace[254818426] 'compare' (duration: 263.586969ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:30:09.599067Z","caller":"traceutil/trace.go:171","msg":"trace[597745041] transaction","detail":"{read_only:false; response_revision:5317; number_of_response:1; }","duration":"352.138024ms","start":"2026-06-17T15:30:09.246921Z","end":"2026-06-17T15:30:09.599059Z","steps":["trace[597745041] 'process raft request' (duration: 352.025811ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:30:09.599054Z","caller":"traceutil/trace.go:171","msg":"trace[416984545] transaction","detail":"{read_only:false; response_revision:5316; number_of_response:1; }","duration":"352.146415ms","start":"2026-06-17T15:30:09.246879Z","end":"2026-06-17T15:30:09.599025Z","steps":["trace[416984545] 'process raft request' (duration: 351.996749ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:30:09.599115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:30:09.246912Z","time spent":"352.180605ms","remote":"127.0.0.1:42772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":529,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:30:09.599164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:30:09.2468Z","time spent":"352.287839ms","remote":"127.0.0.1:42772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":681,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:30:09.599169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:30:09.246873Z","time spent":"352.249737ms","remote":"127.0.0.1:42906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1038,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:30:09.599311Z","caller":"traceutil/trace.go:171","msg":"trace[1868847415] transaction","detail":"{read_only:false; response_revision:5318; number_of_response:1; }","duration":"336.763716ms","start":"2026-06-17T15:30:09.262528Z","end":"2026-06-17T15:30:09.599291Z","steps":["trace[1868847415] 'process raft request' (duration: 336.450377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:30:09.599424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:30:09.262511Z","time spent":"336.84013ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:30:17.190204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.606784ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:30:17.190329Z","caller":"traceutil/trace.go:171","msg":"trace[192972874] linearizableReadLoop","detail":"{readStateIndex:5680; appliedIndex:5679; }","duration":"110.46061ms","start":"2026-06-17T15:30:17.079852Z","end":"2026-06-17T15:30:17.190313Z","steps":["trace[192972874] 'read index received' (duration: 55.781µs)","trace[192972874] 'applied index is now lower than readState.Index' (duration: 110.403399ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:30:17.190574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.713348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-06-17T15:30:17.190579Z","caller":"traceutil/trace.go:171","msg":"trace[476628890] transaction","detail":"{read_only:false; response_revision:5481; number_of_response:1; }","duration":"140.068511ms","start":"2026-06-17T15:30:17.050465Z","end":"2026-06-17T15:30:17.190534Z","steps":["trace[476628890] 'compare' (duration: 131.224863ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:30:17.190658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.082347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-k9kg5\" ","response":"range_response_count:1 size:6522"} {"level":"info","ts":"2026-06-17T15:30:17.19068Z","caller":"traceutil/trace.go:171","msg":"trace[194820827] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5481; }","duration":"110.828713ms","start":"2026-06-17T15:30:17.079829Z","end":"2026-06-17T15:30:17.190658Z","steps":["trace[194820827] 'agreement among raft nodes before linearized reading' (duration: 110.575245ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:30:17.190708Z","caller":"traceutil/trace.go:171","msg":"trace[362416242] range","detail":"{range_begin:/registry/pods/openstack/keepalived-k9kg5; range_end:; response_count:1; response_revision:5481; }","duration":"106.16921ms","start":"2026-06-17T15:30:17.084525Z","end":"2026-06-17T15:30:17.190694Z","steps":["trace[362416242] 'agreement among raft nodes before linearized reading' (duration: 105.890382ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:30:17.577548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.082555ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-17T15:30:17.577914Z","caller":"traceutil/trace.go:171","msg":"trace[1680871101] transaction","detail":"{read_only:false; response_revision:5483; number_of_response:1; }","duration":"381.444777ms","start":"2026-06-17T15:30:17.196424Z","end":"2026-06-17T15:30:17.577869Z","steps":["trace[1680871101] 'process raft request' (duration: 185.967889ms)","trace[1680871101] 'compare' (duration: 194.930891ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:30:17.577972Z","caller":"traceutil/trace.go:171","msg":"trace[299249289] transaction","detail":"{read_only:false; response_revision:5484; number_of_response:1; }","duration":"380.545399ms","start":"2026-06-17T15:30:17.197397Z","end":"2026-06-17T15:30:17.577942Z","steps":["trace[299249289] 'process raft request' (duration: 380.25432ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:30:17.578006Z","caller":"traceutil/trace.go:171","msg":"trace[1345171553] transaction","detail":"{read_only:false; response_revision:5485; number_of_response:1; }","duration":"380.510297ms","start":"2026-06-17T15:30:17.197465Z","end":"2026-06-17T15:30:17.577975Z","steps":["trace[1345171553] 'process raft request' (duration: 380.377333ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:30:17.57807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:30:17.196411Z","time spent":"381.597381ms","remote":"127.0.0.1:42906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1463,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-17T15:30:17.578109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:30:17.19738Z","time spent":"380.646451ms","remote":"127.0.0.1:42772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1081,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:30:17.578168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:30:17.197443Z","time spent":"380.639772ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:30:17.578165Z","caller":"traceutil/trace.go:171","msg":"trace[357207552] transaction","detail":"{read_only:false; response_revision:5487; number_of_response:1; }","duration":"380.146267ms","start":"2026-06-17T15:30:17.198008Z","end":"2026-06-17T15:30:17.578154Z","steps":["trace[357207552] 'process raft request' (duration: 379.94096ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:30:17.577999Z","caller":"traceutil/trace.go:171","msg":"trace[878600529] transaction","detail":"{read_only:false; response_revision:5486; number_of_response:1; }","duration":"380.354553ms","start":"2026-06-17T15:30:17.197626Z","end":"2026-06-17T15:30:17.57798Z","steps":["trace[878600529] 'process raft request' (duration: 380.27267ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:30:17.578321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:30:17.197999Z","time spent":"380.224668ms","remote":"127.0.0.1:42906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1394,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:30:17.578369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:30:17.197612Z","time spent":"380.655092ms","remote":"127.0.0.1:42772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":967,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:30:17.578401Z","caller":"traceutil/trace.go:171","msg":"trace[1609791786] transaction","detail":"{read_only:false; response_revision:5488; number_of_response:1; }","duration":"380.323763ms","start":"2026-06-17T15:30:17.198064Z","end":"2026-06-17T15:30:17.578387Z","steps":["trace[1609791786] 'process raft request' (duration: 380.032893ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:30:17.578328Z","caller":"traceutil/trace.go:171","msg":"trace[351789781] linearizableReadLoop","detail":"{readStateIndex:5687; appliedIndex:5681; }","duration":"289.742826ms","start":"2026-06-17T15:30:17.288569Z","end":"2026-06-17T15:30:17.578312Z","steps":["trace[351789781] 'read index received' (duration: 93.812875ms)","trace[351789781] 'applied index is now lower than readState.Index' (duration: 195.929061ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:30:17.578471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.89465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bb9bfd83-a2f2-4cc3-8b42-a4af4273693b\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-06-17T15:30:17.578512Z","caller":"traceutil/trace.go:171","msg":"trace[2075073274] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bb9bfd83-a2f2-4cc3-8b42-a4af4273693b; range_end:; response_count:1; response_revision:5488; }","duration":"289.938112ms","start":"2026-06-17T15:30:17.288563Z","end":"2026-06-17T15:30:17.578502Z","steps":["trace[2075073274] 'agreement among raft nodes before linearized reading' (duration: 289.790737ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:30:17.578497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:30:17.198052Z","time spent":"380.397794ms","remote":"127.0.0.1:42906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1498,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:30:17.959713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.560836ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-06-17T15:30:17.960149Z","caller":"traceutil/trace.go:171","msg":"trace[571549686] linearizableReadLoop","detail":"{readStateIndex:5688; appliedIndex:5687; }","duration":"337.067176ms","start":"2026-06-17T15:30:17.623053Z","end":"2026-06-17T15:30:17.96012Z","steps":["trace[571549686] 'read index received' (duration: 56.088377ms)","trace[571549686] 'applied index is now lower than readState.Index' (duration: 280.975269ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:30:17.960243Z","caller":"traceutil/trace.go:171","msg":"trace[930970842] transaction","detail":"{read_only:false; response_revision:5490; number_of_response:1; }","duration":"136.495143ms","start":"2026-06-17T15:30:17.823727Z","end":"2026-06-17T15:30:17.960223Z","steps":["trace[930970842] 'process raft request' (duration: 136.446112ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:30:17.960033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:30:17.588924Z","time spent":"371.104071ms","remote":"127.0.0.1:42642","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-17T15:30:17.960274Z","caller":"traceutil/trace.go:171","msg":"trace[311590395] transaction","detail":"{read_only:false; response_revision:5489; number_of_response:1; }","duration":"335.956583ms","start":"2026-06-17T15:30:17.6243Z","end":"2026-06-17T15:30:17.960257Z","steps":["trace[311590395] 'process raft request' (duration: 335.741396ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:30:17.960393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:30:17.624279Z","time spent":"336.029504ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:30:17.960644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.579402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-17T15:30:17.960865Z","caller":"traceutil/trace.go:171","msg":"trace[1902334048] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5490; }","duration":"337.853999ms","start":"2026-06-17T15:30:17.622995Z","end":"2026-06-17T15:30:17.960849Z","steps":["trace[1902334048] 'agreement among raft nodes before linearized reading' (duration: 337.22398ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:30:17.960934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:30:17.622885Z","time spent":"338.036066ms","remote":"127.0.0.1:42678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":623,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-06-17T15:30:17.961421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.818439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-17T15:30:17.961478Z","caller":"traceutil/trace.go:171","msg":"trace[99491049] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:5492; }","duration":"104.944713ms","start":"2026-06-17T15:30:17.856521Z","end":"2026-06-17T15:30:17.961466Z","steps":["trace[99491049] 'agreement among raft nodes before linearized reading' (duration: 104.8285ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:30:17.961497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.876364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-17T15:30:17.961262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.513592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-17T15:30:17.961545Z","caller":"traceutil/trace.go:171","msg":"trace[605677741] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:5492; }","duration":"226.961415ms","start":"2026-06-17T15:30:17.734571Z","end":"2026-06-17T15:30:17.961532Z","steps":["trace[605677741] 'agreement among raft nodes before linearized reading' (duration: 226.887873ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:30:17.961595Z","caller":"traceutil/trace.go:171","msg":"trace[303695522] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:5492; }","duration":"295.852992ms","start":"2026-06-17T15:30:17.665688Z","end":"2026-06-17T15:30:17.961541Z","steps":["trace[303695522] 'agreement among raft nodes before linearized reading' (duration: 295.494061ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:30:17.961501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.500934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:30:17.962031Z","caller":"traceutil/trace.go:171","msg":"trace[876801479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5492; }","duration":"139.066952ms","start":"2026-06-17T15:30:17.822938Z","end":"2026-06-17T15:30:17.962005Z","steps":["trace[876801479] 'agreement among raft nodes before linearized reading' (duration: 138.503955ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:31:04.248683Z","caller":"traceutil/trace.go:171","msg":"trace[455975443] transaction","detail":"{read_only:false; response_revision:5957; number_of_response:1; }","duration":"104.588387ms","start":"2026-06-17T15:31:04.144071Z","end":"2026-06-17T15:31:04.24866Z","steps":["trace[455975443] 'process raft request' (duration: 104.385361ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:31:04.248875Z","caller":"traceutil/trace.go:171","msg":"trace[1156033979] transaction","detail":"{read_only:false; response_revision:5958; number_of_response:1; }","duration":"103.428832ms","start":"2026-06-17T15:31:04.145418Z","end":"2026-06-17T15:31:04.248847Z","steps":["trace[1156033979] 'process raft request' (duration: 103.201715ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:31:14.47612Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2958} {"level":"info","ts":"2026-06-17T15:31:14.557272Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2958,"took":"79.244165ms","hash":3441309641,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":33931264,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-17T15:31:14.557329Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3441309641,"revision":2958,"compact-revision":-1} {"level":"info","ts":"2026-06-17T15:36:14.48206Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6040} {"level":"info","ts":"2026-06-17T15:36:14.583059Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6040,"took":"99.083044ms","hash":3242304078,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":21434368,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-06-17T15:36:14.583127Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3242304078,"revision":6040,"compact-revision":2958} {"level":"info","ts":"2026-06-17T15:36:31.037338Z","caller":"traceutil/trace.go:171","msg":"trace[1005614877] linearizableReadLoop","detail":"{readStateIndex:8636; appliedIndex:8635; }","duration":"244.738059ms","start":"2026-06-17T15:36:30.792574Z","end":"2026-06-17T15:36:31.037312Z","steps":["trace[1005614877] 'read index received' (duration: 244.43176ms)","trace[1005614877] 'applied index is now lower than readState.Index' (duration: 305.099µs)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:36:31.037727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.052984ms","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-06-17T15:36:31.037748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.14645ms","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-06-17T15:36:31.03781Z","caller":"traceutil/trace.go:171","msg":"trace[2124538620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8345; }","duration":"238.179877ms","start":"2026-06-17T15:36:30.799612Z","end":"2026-06-17T15:36:31.037792Z","steps":["trace[2124538620] 'agreement among raft nodes before linearized reading' (duration: 237.980031ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:36:31.037835Z","caller":"traceutil/trace.go:171","msg":"trace[930440139] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8345; }","duration":"245.275423ms","start":"2026-06-17T15:36:30.792545Z","end":"2026-06-17T15:36:31.037821Z","steps":["trace[930440139] 'agreement among raft nodes before linearized reading' (duration: 244.960665ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:36:31.038053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.176826ms","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-06-17T15:36:31.038175Z","caller":"traceutil/trace.go:171","msg":"trace[1856588161] transaction","detail":"{read_only:false; response_revision:8345; number_of_response:1; }","duration":"255.827804ms","start":"2026-06-17T15:36:30.781683Z","end":"2026-06-17T15:36:31.037511Z","steps":["trace[1856588161] 'process raft request' (duration: 255.461913ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:36:31.038734Z","caller":"traceutil/trace.go:171","msg":"trace[153349012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8345; }","duration":"239.030451ms","start":"2026-06-17T15:36:30.799664Z","end":"2026-06-17T15:36:31.038694Z","steps":["trace[153349012] 'agreement among raft nodes before linearized reading' (duration: 237.92739ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:36:41.46792Z","caller":"traceutil/trace.go:171","msg":"trace[955073901] transaction","detail":"{read_only:false; response_revision:8404; number_of_response:1; }","duration":"113.757037ms","start":"2026-06-17T15:36:41.354128Z","end":"2026-06-17T15:36:41.467885Z","steps":["trace[955073901] 'process raft request' (duration: 113.470399ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:36:43.276759Z","caller":"traceutil/trace.go:171","msg":"trace[1584599431] linearizableReadLoop","detail":"{readStateIndex:8701; appliedIndex:8700; }","duration":"188.399299ms","start":"2026-06-17T15:36:43.088335Z","end":"2026-06-17T15:36:43.276734Z","steps":["trace[1584599431] 'read index received' (duration: 146.778525ms)","trace[1584599431] 'applied index is now lower than readState.Index' (duration: 41.619264ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:36:43.277021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.103248ms","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-06-17T15:36:43.277021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.661426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-17T15:36:43.277072Z","caller":"traceutil/trace.go:171","msg":"trace[1274454676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8407; }","duration":"185.17008ms","start":"2026-06-17T15:36:43.09189Z","end":"2026-06-17T15:36:43.27706Z","steps":["trace[1274454676] 'agreement among raft nodes before linearized reading' (duration: 184.989375ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:36:43.277079Z","caller":"traceutil/trace.go:171","msg":"trace[682935395] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8407; }","duration":"188.76372ms","start":"2026-06-17T15:36:43.088303Z","end":"2026-06-17T15:36:43.277067Z","steps":["trace[682935395] 'agreement among raft nodes before linearized reading' (duration: 188.563745ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:36:43.277234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.400373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-17T15:36:43.277259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.368572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-17T15:36:43.277324Z","caller":"traceutil/trace.go:171","msg":"trace[1662667930] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8407; }","duration":"182.555918ms","start":"2026-06-17T15:36:43.094747Z","end":"2026-06-17T15:36:43.277303Z","steps":["trace[1662667930] 'agreement among raft nodes before linearized reading' (duration: 182.156828ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:36:43.277351Z","caller":"traceutil/trace.go:171","msg":"trace[618134159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8407; }","duration":"183.489825ms","start":"2026-06-17T15:36:43.093839Z","end":"2026-06-17T15:36:43.277329Z","steps":["trace[618134159] 'agreement among raft nodes before linearized reading' (duration: 183.181736ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:37:17.635551Z","caller":"traceutil/trace.go:171","msg":"trace[1279937988] linearizableReadLoop","detail":"{readStateIndex:8952; appliedIndex:8951; }","duration":"155.401932ms","start":"2026-06-17T15:37:17.480121Z","end":"2026-06-17T15:37:17.635523Z","steps":["trace[1279937988] 'read index received' (duration: 155.320761ms)","trace[1279937988] 'applied index is now lower than readState.Index' (duration: 79.821µs)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:37:17.63573Z","caller":"traceutil/trace.go:171","msg":"trace[620658227] transaction","detail":"{read_only:false; response_revision:8649; number_of_response:1; }","duration":"161.643494ms","start":"2026-06-17T15:37:17.474076Z","end":"2026-06-17T15:37:17.635719Z","steps":["trace[620658227] 'process raft request' (duration: 161.267574ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:37:17.635906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.767823ms","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-06-17T15:37:17.635998Z","caller":"traceutil/trace.go:171","msg":"trace[794119337] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8649; }","duration":"155.894637ms","start":"2026-06-17T15:37:17.480085Z","end":"2026-06-17T15:37:17.63598Z","steps":["trace[794119337] 'agreement among raft nodes before linearized reading' (duration: 155.704391ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:37:17.750896Z","caller":"traceutil/trace.go:171","msg":"trace[1399564364] transaction","detail":"{read_only:false; response_revision:8650; number_of_response:1; }","duration":"184.179354ms","start":"2026-06-17T15:37:17.566663Z","end":"2026-06-17T15:37:17.750843Z","steps":["trace[1399564364] 'process raft request' (duration: 182.22288ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:37:17.751138Z","caller":"traceutil/trace.go:171","msg":"trace[859135756] linearizableReadLoop","detail":"{readStateIndex:8954; appliedIndex:8952; }","duration":"115.465124ms","start":"2026-06-17T15:37:17.635658Z","end":"2026-06-17T15:37:17.751123Z","steps":["trace[859135756] 'read index received' (duration: 113.222192ms)","trace[859135756] 'applied index is now lower than readState.Index' (duration: 2.240442ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:37:17.751165Z","caller":"traceutil/trace.go:171","msg":"trace[998416606] transaction","detail":"{read_only:false; response_revision:8652; number_of_response:1; }","duration":"111.087865ms","start":"2026-06-17T15:37:17.640051Z","end":"2026-06-17T15:37:17.751139Z","steps":["trace[998416606] 'process raft request' (duration: 110.89317ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:37:17.751312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.281889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-17T15:37:17.751042Z","caller":"traceutil/trace.go:171","msg":"trace[1823544922] transaction","detail":"{read_only:false; response_revision:8651; number_of_response:1; }","duration":"146.687903ms","start":"2026-06-17T15:37:17.604316Z","end":"2026-06-17T15:37:17.751004Z","steps":["trace[1823544922] 'process raft request' (duration: 146.353354ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:37:17.751327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.671861ms","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-06-17T15:37:17.751439Z","caller":"traceutil/trace.go:171","msg":"trace[1743554981] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:8654; }","duration":"139.440173ms","start":"2026-06-17T15:37:17.611969Z","end":"2026-06-17T15:37:17.751374Z","steps":["trace[1743554981] 'agreement among raft nodes before linearized reading' (duration: 139.198177ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:37:17.75146Z","caller":"traceutil/trace.go:171","msg":"trace[784898562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8654; }","duration":"258.813976ms","start":"2026-06-17T15:37:17.492629Z","end":"2026-06-17T15:37:17.751443Z","steps":["trace[784898562] 'agreement among raft nodes before linearized reading' (duration: 258.541488ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:37:17.75169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.067813ms","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-06-17T15:37:17.751795Z","caller":"traceutil/trace.go:171","msg":"trace[1274751945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8654; }","duration":"259.187146ms","start":"2026-06-17T15:37:17.492565Z","end":"2026-06-17T15:37:17.751752Z","steps":["trace[1274751945] 'agreement among raft nodes before linearized reading' (duration: 258.633991ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:37:29.481433Z","caller":"traceutil/trace.go:171","msg":"trace[715601048] transaction","detail":"{read_only:false; response_revision:8751; number_of_response:1; }","duration":"151.988348ms","start":"2026-06-17T15:37:29.329418Z","end":"2026-06-17T15:37:29.481406Z","steps":["trace[715601048] 'process raft request' (duration: 151.838565ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:37:29.756645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.75648ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-17T15:37:29.756858Z","caller":"traceutil/trace.go:171","msg":"trace[683787680] transaction","detail":"{read_only:false; response_revision:8753; number_of_response:1; }","duration":"241.824529ms","start":"2026-06-17T15:37:29.515014Z","end":"2026-06-17T15:37:29.756839Z","steps":["trace[683787680] 'process raft request' (duration: 241.742647ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:37:29.757064Z","caller":"traceutil/trace.go:171","msg":"trace[1441054134] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8752; }","duration":"427.079802ms","start":"2026-06-17T15:37:29.329849Z","end":"2026-06-17T15:37:29.756928Z","steps":["trace[1441054134] 'process raft request' (duration: 285.89719ms)","trace[1441054134] 'compare' (duration: 140.514663ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:37:29.757138Z","caller":"traceutil/trace.go:171","msg":"trace[160702423] linearizableReadLoop","detail":"{readStateIndex:9061; appliedIndex:9060; }","duration":"271.788671ms","start":"2026-06-17T15:37:29.485335Z","end":"2026-06-17T15:37:29.757124Z","steps":["trace[160702423] 'read index received' (duration: 130.540109ms)","trace[160702423] 'applied index is now lower than readState.Index' (duration: 141.246802ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:37:29.757294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:37:29.329828Z","time spent":"427.320258ms","remote":"127.0.0.1:36534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80,"response count":0,"response size":40,"request content":"compare: success: > failure: >"} {"level":"warn","ts":"2026-06-17T15:37:29.757538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.200513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-xr6wk\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-06-17T15:37:29.757594Z","caller":"traceutil/trace.go:171","msg":"trace[1494811574] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-xr6wk; range_end:; response_count:1; response_revision:8753; }","duration":"272.294227ms","start":"2026-06-17T15:37:29.485287Z","end":"2026-06-17T15:37:29.757581Z","steps":["trace[1494811574] 'agreement among raft nodes before linearized reading' (duration: 272.105431ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:37:29.757883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.599807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-xr6wk\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-06-17T15:37:29.757935Z","caller":"traceutil/trace.go:171","msg":"trace[1192107370] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-xr6wk; range_end:; response_count:1; response_revision:8753; }","duration":"243.68157ms","start":"2026-06-17T15:37:29.514239Z","end":"2026-06-17T15:37:29.757921Z","steps":["trace[1192107370] 'agreement among raft nodes before linearized reading' (duration: 243.556336ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:37:30.790605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.867887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-17T15:37:30.790702Z","caller":"traceutil/trace.go:171","msg":"trace[1826395808] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8764; }","duration":"175.038783ms","start":"2026-06-17T15:37:30.615645Z","end":"2026-06-17T15:37:30.790683Z","steps":["trace[1826395808] 'range keys from in-memory index tree' (duration: 174.684053ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:37:31.499646Z","caller":"traceutil/trace.go:171","msg":"trace[161355585] transaction","detail":"{read_only:false; response_revision:8770; number_of_response:1; }","duration":"116.141953ms","start":"2026-06-17T15:37:31.383462Z","end":"2026-06-17T15:37:31.499604Z","steps":["trace[161355585] 'process raft request' (duration: 115.702141ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:38:00.174339Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024b1.wal"} {"level":"info","ts":"2026-06-17T15:39:04.480434Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-17T15:39:04.487585Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-17T15:39:04.48773Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-17T15:41:14.489237Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8122} {"level":"info","ts":"2026-06-17T15:41:14.557295Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8122,"took":"66.082498ms","hash":2643322736,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":28221440,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-17T15:41:14.557352Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2643322736,"revision":8122,"compact-revision":6040} {"level":"info","ts":"2026-06-17T15:44:00.100662Z","caller":"traceutil/trace.go:171","msg":"trace[2082411074] linearizableReadLoop","detail":"{readStateIndex:12210; appliedIndex:12209; }","duration":"111.340973ms","start":"2026-06-17T15:43:59.989283Z","end":"2026-06-17T15:44:00.100624Z","steps":["trace[2082411074] 'read index received' (duration: 109.746889ms)","trace[2082411074] 'applied index is now lower than readState.Index' (duration: 1.592364ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:44:00.10069Z","caller":"traceutil/trace.go:171","msg":"trace[1634064374] transaction","detail":"{read_only:false; response_revision:11807; number_of_response:1; }","duration":"215.006015ms","start":"2026-06-17T15:43:59.885654Z","end":"2026-06-17T15:44:00.10066Z","steps":["trace[1634064374] 'process raft request' (duration: 213.300768ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:44:00.100879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.569699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-17T15:44:00.100963Z","caller":"traceutil/trace.go:171","msg":"trace[1263188152] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:11807; }","duration":"111.709194ms","start":"2026-06-17T15:43:59.989236Z","end":"2026-06-17T15:44:00.100945Z","steps":["trace[1263188152] 'agreement among raft nodes before linearized reading' (duration: 111.481708ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:44:17.40813Z","caller":"traceutil/trace.go:171","msg":"trace[710879736] transaction","detail":"{read_only:false; response_revision:11900; number_of_response:1; }","duration":"171.315394ms","start":"2026-06-17T15:44:17.236789Z","end":"2026-06-17T15:44:17.408105Z","steps":["trace[710879736] 'process raft request' (duration: 80.712201ms)","trace[710879736] 'compare' (duration: 90.448259ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:44:17.408382Z","caller":"traceutil/trace.go:171","msg":"trace[1590587239] transaction","detail":"{read_only:false; response_revision:11901; number_of_response:1; }","duration":"115.743994ms","start":"2026-06-17T15:44:17.292612Z","end":"2026-06-17T15:44:17.408356Z","steps":["trace[1590587239] 'process raft request' (duration: 115.441446ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:44:17.408931Z","caller":"traceutil/trace.go:171","msg":"trace[1074738739] transaction","detail":"{read_only:false; response_revision:11902; number_of_response:1; }","duration":"111.367303ms","start":"2026-06-17T15:44:17.297533Z","end":"2026-06-17T15:44:17.408901Z","steps":["trace[1074738739] 'process raft request' (duration: 110.735986ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:44:17.409389Z","caller":"traceutil/trace.go:171","msg":"trace[1873454858] transaction","detail":"{read_only:false; response_revision:11903; number_of_response:1; }","duration":"111.381754ms","start":"2026-06-17T15:44:17.29797Z","end":"2026-06-17T15:44:17.409352Z","steps":["trace[1873454858] 'process raft request' (duration: 110.834909ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:44:17.409434Z","caller":"traceutil/trace.go:171","msg":"trace[1235130167] transaction","detail":"{read_only:false; response_revision:11904; number_of_response:1; }","duration":"110.995293ms","start":"2026-06-17T15:44:17.298406Z","end":"2026-06-17T15:44:17.409401Z","steps":["trace[1235130167] 'process raft request' (duration: 110.793727ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:44:17.639694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.006386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:44:17.639804Z","caller":"traceutil/trace.go:171","msg":"trace[1213710480] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:11905; }","duration":"102.228003ms","start":"2026-06-17T15:44:17.53755Z","end":"2026-06-17T15:44:17.639778Z","steps":["trace[1213710480] 'count revisions from in-memory index tree' (duration: 101.830412ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:46:14.495144Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10491} {"level":"info","ts":"2026-06-17T15:46:14.574643Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10491,"took":"76.712171ms","hash":5179981,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":26476544,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-17T15:46:14.574737Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":5179981,"revision":10491,"compact-revision":8122} {"level":"info","ts":"2026-06-17T15:50:24.152199Z","caller":"traceutil/trace.go:171","msg":"trace[1050131893] transaction","detail":"{read_only:false; response_revision:14381; number_of_response:1; }","duration":"151.334895ms","start":"2026-06-17T15:50:24.000833Z","end":"2026-06-17T15:50:24.152168Z","steps":["trace[1050131893] 'process raft request' (duration: 151.17024ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:50:43.104082Z","caller":"traceutil/trace.go:171","msg":"trace[1752546178] transaction","detail":"{read_only:false; response_revision:14484; number_of_response:1; }","duration":"158.849912ms","start":"2026-06-17T15:50:42.945212Z","end":"2026-06-17T15:50:43.104061Z","steps":["trace[1752546178] 'process raft request' (duration: 158.693397ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:50:57.964032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.139432ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-06-17T15:50:57.964413Z","caller":"traceutil/trace.go:171","msg":"trace[1233200651] linearizableReadLoop","detail":"{readStateIndex:15058; appliedIndex:15056; }","duration":"142.382489ms","start":"2026-06-17T15:50:57.822009Z","end":"2026-06-17T15:50:57.964392Z","steps":["trace[1233200651] 'read index received' (duration: 140.415005ms)","trace[1233200651] 'applied index is now lower than readState.Index' (duration: 1.966214ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:50:57.964545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.534772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:50:57.964582Z","caller":"traceutil/trace.go:171","msg":"trace[522484416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14558; }","duration":"142.614285ms","start":"2026-06-17T15:50:57.821957Z","end":"2026-06-17T15:50:57.964572Z","steps":["trace[522484416] 'agreement among raft nodes before linearized reading' (duration: 142.529952ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:50:57.964542Z","caller":"traceutil/trace.go:171","msg":"trace[1678706364] transaction","detail":"{read_only:false; response_revision:14558; number_of_response:1; }","duration":"292.955883ms","start":"2026-06-17T15:50:57.671552Z","end":"2026-06-17T15:50:57.964508Z","steps":["trace[1678706364] 'process raft request' (duration: 292.727056ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:50:57.965198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.962024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-k9kg5\" ","response":"range_response_count:1 size:6522"} {"level":"info","ts":"2026-06-17T15:50:57.965316Z","caller":"traceutil/trace.go:171","msg":"trace[1409153758] range","detail":"{range_begin:/registry/pods/openstack/keepalived-k9kg5; range_end:; response_count:1; response_revision:14558; }","duration":"139.107878ms","start":"2026-06-17T15:50:57.82616Z","end":"2026-06-17T15:50:57.965268Z","steps":["trace[1409153758] 'agreement among raft nodes before linearized reading' (duration: 138.490721ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:51:09.743353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.835653ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:51:09.744275Z","caller":"traceutil/trace.go:171","msg":"trace[1118763078] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:14755; }","duration":"177.769599ms","start":"2026-06-17T15:51:09.566485Z","end":"2026-06-17T15:51:09.744254Z","steps":["trace[1118763078] 'range keys from in-memory index tree' (duration: 176.819863ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:51:09.743747Z","caller":"traceutil/trace.go:171","msg":"trace[19325142] transaction","detail":"{read_only:false; response_revision:14756; number_of_response:1; }","duration":"356.053251ms","start":"2026-06-17T15:51:09.38767Z","end":"2026-06-17T15:51:09.743723Z","steps":["trace[19325142] 'process raft request' (duration: 355.757693ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:51:09.744559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:51:09.387644Z","time spent":"356.804852ms","remote":"127.0.0.1:42792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7252,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:51:09.763488Z","caller":"traceutil/trace.go:171","msg":"trace[1687680288] linearizableReadLoop","detail":"{readStateIndex:15261; appliedIndex:15259; }","duration":"340.984376ms","start":"2026-06-17T15:51:09.422489Z","end":"2026-06-17T15:51:09.763473Z","steps":["trace[1687680288] 'read index received' (duration: 321.056037ms)","trace[1687680288] 'applied index is now lower than readState.Index' (duration: 19.927649ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:51:09.763627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.130121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:51:09.763655Z","caller":"traceutil/trace.go:171","msg":"trace[1869264874] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:14757; }","duration":"341.171751ms","start":"2026-06-17T15:51:09.422474Z","end":"2026-06-17T15:51:09.763645Z","steps":["trace[1869264874] 'agreement among raft nodes before linearized reading' (duration: 341.100099ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:51:09.763675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:51:09.422417Z","time spent":"341.252503ms","remote":"127.0.0.1:58896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true "} {"level":"info","ts":"2026-06-17T15:51:09.763639Z","caller":"traceutil/trace.go:171","msg":"trace[553190272] transaction","detail":"{read_only:false; response_revision:14757; number_of_response:1; }","duration":"351.768643ms","start":"2026-06-17T15:51:09.411806Z","end":"2026-06-17T15:51:09.763575Z","steps":["trace[553190272] 'process raft request' (duration: 351.537467ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:51:09.764684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:51:09.41177Z","time spent":"352.810641ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:51:09.915552Z","caller":"traceutil/trace.go:171","msg":"trace[1119512230] transaction","detail":"{read_only:false; response_revision:14758; number_of_response:1; }","duration":"138.922818ms","start":"2026-06-17T15:51:09.776602Z","end":"2026-06-17T15:51:09.915525Z","steps":["trace[1119512230] 'process raft request' (duration: 127.875284ms)","trace[1119512230] 'compare' (duration: 10.89292ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:51:09.917951Z","caller":"traceutil/trace.go:171","msg":"trace[1064038250] transaction","detail":"{read_only:false; response_revision:14759; number_of_response:1; }","duration":"140.802749ms","start":"2026-06-17T15:51:09.77711Z","end":"2026-06-17T15:51:09.917912Z","steps":["trace[1064038250] 'process raft request' (duration: 140.45073ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:51:09.918173Z","caller":"traceutil/trace.go:171","msg":"trace[46344894] transaction","detail":"{read_only:false; response_revision:14760; number_of_response:1; }","duration":"137.194021ms","start":"2026-06-17T15:51:09.780949Z","end":"2026-06-17T15:51:09.918143Z","steps":["trace[46344894] 'process raft request' (duration: 136.791679ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:51:14.501977Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12684} {"level":"info","ts":"2026-06-17T15:51:14.578913Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12684,"took":"74.930685ms","hash":2690069535,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":28225536,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-17T15:51:14.578993Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2690069535,"revision":12684,"compact-revision":10491} {"level":"warn","ts":"2026-06-17T15:52:15.415783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.231947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" ","response":"range_response_count:1 size:1565"} {"level":"info","ts":"2026-06-17T15:52:15.415879Z","caller":"traceutil/trace.go:171","msg":"trace[123362829] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0; range_end:; response_count:1; response_revision:15663; }","duration":"106.384881ms","start":"2026-06-17T15:52:15.309473Z","end":"2026-06-17T15:52:15.415858Z","steps":["trace[123362829] 'range keys from in-memory index tree' (duration: 105.975931ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:52:48.506921Z","caller":"traceutil/trace.go:171","msg":"trace[844513024] transaction","detail":"{read_only:false; response_revision:15833; number_of_response:1; }","duration":"110.541555ms","start":"2026-06-17T15:52:48.396343Z","end":"2026-06-17T15:52:48.506885Z","steps":["trace[844513024] 'process raft request' (duration: 110.324089ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:52:48.886007Z","caller":"traceutil/trace.go:171","msg":"trace[1796709025] transaction","detail":"{read_only:false; response_revision:15834; number_of_response:1; }","duration":"373.992416ms","start":"2026-06-17T15:52:48.511984Z","end":"2026-06-17T15:52:48.885976Z","steps":["trace[1796709025] 'process raft request' (duration: 341.881101ms)","trace[1796709025] 'compare' (duration: 31.260021ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:52:48.88603Z","caller":"traceutil/trace.go:171","msg":"trace[2128917194] linearizableReadLoop","detail":"{readStateIndex:16376; appliedIndex:16373; }","duration":"373.486231ms","start":"2026-06-17T15:52:48.51252Z","end":"2026-06-17T15:52:48.886006Z","steps":["trace[2128917194] 'read index received' (duration: 341.333196ms)","trace[2128917194] 'applied index is now lower than readState.Index' (duration: 32.151785ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:52:48.886127Z","caller":"traceutil/trace.go:171","msg":"trace[1435017248] transaction","detail":"{read_only:false; response_revision:15835; number_of_response:1; }","duration":"374.072597ms","start":"2026-06-17T15:52:48.512019Z","end":"2026-06-17T15:52:48.886091Z","steps":["trace[1435017248] 'process raft request' (duration: 373.838211ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:52:48.886294Z","caller":"traceutil/trace.go:171","msg":"trace[48654389] transaction","detail":"{read_only:false; response_revision:15836; number_of_response:1; }","duration":"374.157869ms","start":"2026-06-17T15:52:48.51212Z","end":"2026-06-17T15:52:48.886278Z","steps":["trace[48654389] 'process raft request' (duration: 373.795689ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:52:48.886362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:52:48.512002Z","time spent":"374.247772ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:52:48.886409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.276606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"warn","ts":"2026-06-17T15:52:48.886434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:52:48.512089Z","time spent":"374.256353ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:52:48.88654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.628248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7911"} {"level":"info","ts":"2026-06-17T15:52:48.886574Z","caller":"traceutil/trace.go:171","msg":"trace[737250581] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:15836; }","duration":"171.696482ms","start":"2026-06-17T15:52:48.71487Z","end":"2026-06-17T15:52:48.886566Z","steps":["trace[737250581] 'agreement among raft nodes before linearized reading' (duration: 171.607329ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:52:48.886455Z","caller":"traceutil/trace.go:171","msg":"trace[928706961] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15836; }","duration":"373.357056ms","start":"2026-06-17T15:52:48.513086Z","end":"2026-06-17T15:52:48.886443Z","steps":["trace[928706961] 'agreement among raft nodes before linearized reading' (duration: 373.207243ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:52:48.886576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.038366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-17T15:52:48.886652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:52:48.513069Z","time spent":"373.568324ms","remote":"127.0.0.1:42772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":688,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"info","ts":"2026-06-17T15:52:48.886659Z","caller":"traceutil/trace.go:171","msg":"trace[2072069291] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15836; }","duration":"374.156039ms","start":"2026-06-17T15:52:48.512485Z","end":"2026-06-17T15:52:48.886641Z","steps":["trace[2072069291] 'agreement among raft nodes before linearized reading' (duration: 374.041946ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:52:48.886164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:52:48.511967Z","time spent":"374.116489ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:52:48.886714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:52:48.512475Z","time spent":"374.228282ms","remote":"127.0.0.1:42860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-06-17T15:52:48.886852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.796343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-9xfph\" ","response":"range_response_count:1 size:9791"} {"level":"info","ts":"2026-06-17T15:52:48.886895Z","caller":"traceutil/trace.go:171","msg":"trace[1315006938] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-9xfph; range_end:; response_count:1; response_revision:15836; }","duration":"245.885966ms","start":"2026-06-17T15:52:48.641001Z","end":"2026-06-17T15:52:48.886887Z","steps":["trace[1315006938] 'agreement among raft nodes before linearized reading' (duration: 245.771762ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:52:48.887032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.190464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-06-17T15:52:48.887101Z","caller":"traceutil/trace.go:171","msg":"trace[1535738099] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15836; }","duration":"373.277375ms","start":"2026-06-17T15:52:48.513802Z","end":"2026-06-17T15:52:48.887079Z","steps":["trace[1535738099] 'agreement among raft nodes before linearized reading' (duration: 373.168392ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:52:48.887129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:52:48.513792Z","time spent":"373.331588ms","remote":"127.0.0.1:42860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5543,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-06-17T15:52:48.887246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.921731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-4c5vl\" ","response":"range_response_count:1 size:20637"} {"level":"info","ts":"2026-06-17T15:52:48.887287Z","caller":"traceutil/trace.go:171","msg":"trace[891206072] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-4c5vl; range_end:; response_count:1; response_revision:15836; }","duration":"329.990363ms","start":"2026-06-17T15:52:48.557287Z","end":"2026-06-17T15:52:48.887277Z","steps":["trace[891206072] 'agreement among raft nodes before linearized reading' (duration: 329.88968ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:52:48.88732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:52:48.557272Z","time spent":"330.040943ms","remote":"127.0.0.1:42792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20659,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-4c5vl\" "} {"level":"info","ts":"2026-06-17T15:52:49.350836Z","caller":"traceutil/trace.go:171","msg":"trace[446815385] linearizableReadLoop","detail":"{readStateIndex:16377; appliedIndex:16376; }","duration":"458.988798ms","start":"2026-06-17T15:52:48.891813Z","end":"2026-06-17T15:52:49.350801Z","steps":["trace[446815385] 'read index received' (duration: 447.307036ms)","trace[446815385] 'applied index is now lower than readState.Index' (duration: 11.680442ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:52:49.350941Z","caller":"traceutil/trace.go:171","msg":"trace[1484828990] transaction","detail":"{read_only:false; response_revision:15837; number_of_response:1; }","duration":"459.199983ms","start":"2026-06-17T15:52:48.891728Z","end":"2026-06-17T15:52:49.350928Z","steps":["trace[1484828990] 'process raft request' (duration: 447.590594ms)","trace[1484828990] 'compare' (duration: 11.215119ms)"],"step_count":2} {"level":"warn","ts":"2026-06-17T15:52:49.351043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:52:48.89171Z","time spent":"459.262395ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:52:49.35169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.853161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769786"} {"level":"info","ts":"2026-06-17T15:52:49.351952Z","caller":"traceutil/trace.go:171","msg":"trace[278243790] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15837; }","duration":"460.154719ms","start":"2026-06-17T15:52:48.89178Z","end":"2026-06-17T15:52:49.351934Z","steps":["trace[278243790] 'agreement among raft nodes before linearized reading' (duration: 459.162981ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:52:49.352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:52:48.891764Z","time spent":"460.22397ms","remote":"127.0.0.1:42792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":79,"response size":769808,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-06-17T15:52:49.366886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.623565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769786"} {"level":"info","ts":"2026-06-17T15:52:49.366974Z","caller":"traceutil/trace.go:171","msg":"trace[1139601881] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15842; }","duration":"471.724718ms","start":"2026-06-17T15:52:48.89523Z","end":"2026-06-17T15:52:49.366955Z","steps":["trace[1139601881] 'agreement among raft nodes before linearized reading' (duration: 470.968747ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:52:49.367021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:52:48.895198Z","time spent":"471.811741ms","remote":"127.0.0.1:42792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":79,"response size":769808,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-06-17T15:52:49.367538Z","caller":"traceutil/trace.go:171","msg":"trace[1466581001] transaction","detail":"{read_only:false; response_revision:15838; number_of_response:1; }","duration":"474.783732ms","start":"2026-06-17T15:52:48.892741Z","end":"2026-06-17T15:52:49.367525Z","steps":["trace[1466581001] 'process raft request' (duration: 473.178269ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:52:49.367633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:52:48.892729Z","time spent":"474.843564ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:52:49.3678Z","caller":"traceutil/trace.go:171","msg":"trace[1913558884] transaction","detail":"{read_only:false; response_revision:15839; number_of_response:1; }","duration":"214.050358ms","start":"2026-06-17T15:52:49.153739Z","end":"2026-06-17T15:52:49.367789Z","steps":["trace[1913558884] 'process raft request' (duration: 212.29907ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:52:49.367914Z","caller":"traceutil/trace.go:171","msg":"trace[651401784] transaction","detail":"{read_only:false; response_revision:15840; number_of_response:1; }","duration":"112.217842ms","start":"2026-06-17T15:52:49.255687Z","end":"2026-06-17T15:52:49.367905Z","steps":["trace[651401784] 'process raft request' (duration: 110.388312ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:52:49.36804Z","caller":"traceutil/trace.go:171","msg":"trace[1194598916] transaction","detail":"{read_only:false; response_revision:15841; number_of_response:1; }","duration":"110.961797ms","start":"2026-06-17T15:52:49.257067Z","end":"2026-06-17T15:52:49.368029Z","steps":["trace[1194598916] 'process raft request' (duration: 109.051364ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:52:49.368152Z","caller":"traceutil/trace.go:171","msg":"trace[540266767] transaction","detail":"{read_only:false; response_revision:15842; number_of_response:1; }","duration":"110.900646ms","start":"2026-06-17T15:52:49.257243Z","end":"2026-06-17T15:52:49.368144Z","steps":["trace[540266767] 'process raft request' (duration: 108.904972ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:52:49.368804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.806885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-06-17T15:52:49.368832Z","caller":"traceutil/trace.go:171","msg":"trace[2040923234] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15842; }","duration":"199.873538ms","start":"2026-06-17T15:52:49.168952Z","end":"2026-06-17T15:52:49.368825Z","steps":["trace[2040923234] 'agreement among raft nodes before linearized reading' (duration: 199.808596ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:52:49.368949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.563763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-17T15:52:49.368968Z","caller":"traceutil/trace.go:171","msg":"trace[583224300] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:15842; }","duration":"226.601723ms","start":"2026-06-17T15:52:49.142361Z","end":"2026-06-17T15:52:49.368963Z","steps":["trace[583224300] 'agreement among raft nodes before linearized reading' (duration: 226.567612ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:52:49.369111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.475286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-06-17T15:52:49.369129Z","caller":"traceutil/trace.go:171","msg":"trace[290533247] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15842; }","duration":"232.504896ms","start":"2026-06-17T15:52:49.136619Z","end":"2026-06-17T15:52:49.369124Z","steps":["trace[290533247] 'agreement among raft nodes before linearized reading' (duration: 232.437275ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:52:49.36926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.680548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-06-17T15:52:49.369279Z","caller":"traceutil/trace.go:171","msg":"trace[1363287297] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15842; }","duration":"234.745429ms","start":"2026-06-17T15:52:49.134526Z","end":"2026-06-17T15:52:49.369272Z","steps":["trace[1363287297] 'agreement among raft nodes before linearized reading' (duration: 234.694597ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:52:49.369369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.557504ms","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-06-17T15:52:49.369386Z","caller":"traceutil/trace.go:171","msg":"trace[1854664782] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15842; }","duration":"238.604175ms","start":"2026-06-17T15:52:49.130777Z","end":"2026-06-17T15:52:49.369381Z","steps":["trace[1854664782] 'agreement among raft nodes before linearized reading' (duration: 238.576323ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:52:49.3695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.194698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-k9kg5\" ","response":"range_response_count:1 size:6522"} {"level":"info","ts":"2026-06-17T15:52:49.369522Z","caller":"traceutil/trace.go:171","msg":"trace[918923546] range","detail":"{range_begin:/registry/pods/openstack/keepalived-k9kg5; range_end:; response_count:1; response_revision:15842; }","duration":"318.248169ms","start":"2026-06-17T15:52:49.051268Z","end":"2026-06-17T15:52:49.369516Z","steps":["trace[918923546] 'agreement among raft nodes before linearized reading' (duration: 318.189737ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:52:49.369538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:52:49.051249Z","time spent":"318.28481ms","remote":"127.0.0.1:42792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6544,"request content":"key:\"/registry/pods/openstack/keepalived-k9kg5\" "} {"level":"warn","ts":"2026-06-17T15:52:49.369662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.825664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-s429k\" ","response":"range_response_count:1 size:22093"} {"level":"info","ts":"2026-06-17T15:52:49.36969Z","caller":"traceutil/trace.go:171","msg":"trace[1369204406] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-s429k; range_end:; response_count:1; response_revision:15842; }","duration":"396.894987ms","start":"2026-06-17T15:52:48.97279Z","end":"2026-06-17T15:52:49.369685Z","steps":["trace[1369204406] 'agreement among raft nodes before linearized reading' (duration: 396.824994ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:52:49.369724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:52:48.97277Z","time spent":"396.933288ms","remote":"127.0.0.1:42792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22115,"request content":"key:\"/registry/pods/openstack/nova-compute-default-s429k\" "} {"level":"warn","ts":"2026-06-17T15:52:49.369844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.601588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-s429k\" ","response":"range_response_count:1 size:22093"} {"level":"info","ts":"2026-06-17T15:52:49.369868Z","caller":"traceutil/trace.go:171","msg":"trace[901653782] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-s429k; range_end:; response_count:1; response_revision:15842; }","duration":"416.644461ms","start":"2026-06-17T15:52:48.953218Z","end":"2026-06-17T15:52:49.369863Z","steps":["trace[901653782] 'agreement among raft nodes before linearized reading' (duration: 416.58429ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:52:49.369888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:52:48.953209Z","time spent":"416.674921ms","remote":"127.0.0.1:42792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22115,"request content":"key:\"/registry/pods/openstack/nova-compute-default-s429k\" "} {"level":"warn","ts":"2026-06-17T15:52:49.369972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.807366ms","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-06-17T15:52:49.369995Z","caller":"traceutil/trace.go:171","msg":"trace[1229890637] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15842; }","duration":"440.850707ms","start":"2026-06-17T15:52:48.929139Z","end":"2026-06-17T15:52:49.36999Z","steps":["trace[1229890637] 'agreement among raft nodes before linearized reading' (duration: 440.820257ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:52:49.370012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:52:48.929133Z","time spent":"440.875648ms","remote":"127.0.0.1:42860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-06-17T15:52:49.370124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.386902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-06-17T15:52:49.370145Z","caller":"traceutil/trace.go:171","msg":"trace[406129640] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15842; }","duration":"441.407663ms","start":"2026-06-17T15:52:48.928732Z","end":"2026-06-17T15:52:49.37014Z","steps":["trace[406129640] 'agreement among raft nodes before linearized reading' (duration: 441.354291ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:52:49.370162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:52:48.928708Z","time spent":"441.450294ms","remote":"127.0.0.1:42860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5543,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-06-17T15:52:49.370251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.806305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:52:49.370272Z","caller":"traceutil/trace.go:171","msg":"trace[1831044160] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15842; }","duration":"441.841205ms","start":"2026-06-17T15:52:48.928426Z","end":"2026-06-17T15:52:49.370267Z","steps":["trace[1831044160] 'agreement among raft nodes before linearized reading' (duration: 441.811075ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:52:49.370295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:52:48.92842Z","time spent":"441.870626ms","remote":"127.0.0.1:42860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-ks-user\" "} {"level":"warn","ts":"2026-06-17T15:52:49.370376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.966528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-17T15:52:49.370399Z","caller":"traceutil/trace.go:171","msg":"trace[1274491737] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15842; }","duration":"442.009909ms","start":"2026-06-17T15:52:48.928384Z","end":"2026-06-17T15:52:49.370394Z","steps":["trace[1274491737] 'agreement among raft nodes before linearized reading' (duration: 441.980019ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:52:49.370432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:52:48.928373Z","time spent":"442.05528ms","remote":"127.0.0.1:42860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"info","ts":"2026-06-17T15:56:14.508253Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14860} {"level":"info","ts":"2026-06-17T15:56:14.602627Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14860,"took":"91.339529ms","hash":3118046323,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":30580736,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-17T15:56:14.602707Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3118046323,"revision":14860,"compact-revision":12684} {"level":"warn","ts":"2026-06-17T15:58:34.249676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.343938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-06-17T15:58:34.249773Z","caller":"traceutil/trace.go:171","msg":"trace[308917001] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:18374; }","duration":"112.572774ms","start":"2026-06-17T15:58:34.13718Z","end":"2026-06-17T15:58:34.249753Z","steps":["trace[308917001] 'range keys from in-memory index tree' (duration: 112.136852ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:58:34.390946Z","caller":"traceutil/trace.go:171","msg":"trace[1820050995] transaction","detail":"{read_only:false; response_revision:18376; number_of_response:1; }","duration":"136.595217ms","start":"2026-06-17T15:58:34.254317Z","end":"2026-06-17T15:58:34.390912Z","steps":["trace[1820050995] 'process raft request' (duration: 99.958436ms)","trace[1820050995] 'compare' (duration: 36.504997ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:58:34.798824Z","caller":"traceutil/trace.go:171","msg":"trace[1843465486] transaction","detail":"{read_only:false; response_revision:18377; number_of_response:1; }","duration":"195.676816ms","start":"2026-06-17T15:58:34.603118Z","end":"2026-06-17T15:58:34.798794Z","steps":["trace[1843465486] 'process raft request' (duration: 195.45866ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:58:34.877747Z","caller":"traceutil/trace.go:171","msg":"trace[254886854] transaction","detail":"{read_only:false; response_revision:18379; number_of_response:1; }","duration":"251.522877ms","start":"2026-06-17T15:58:34.626201Z","end":"2026-06-17T15:58:34.877724Z","steps":["trace[254886854] 'process raft request' (duration: 251.463975ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:58:34.87778Z","caller":"traceutil/trace.go:171","msg":"trace[96430487] transaction","detail":"{read_only:false; response_revision:18378; number_of_response:1; }","duration":"262.445717ms","start":"2026-06-17T15:58:34.615298Z","end":"2026-06-17T15:58:34.877744Z","steps":["trace[96430487] 'process raft request' (duration: 262.231782ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:58:34.877983Z","caller":"traceutil/trace.go:171","msg":"trace[781402380] transaction","detail":"{read_only:false; response_revision:18380; number_of_response:1; }","duration":"250.335153ms","start":"2026-06-17T15:58:34.627637Z","end":"2026-06-17T15:58:34.877972Z","steps":["trace[781402380] 'process raft request' (duration: 250.057586ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:58:34.877991Z","caller":"traceutil/trace.go:171","msg":"trace[1694394528] linearizableReadLoop","detail":"{readStateIndex:19005; appliedIndex:19000; }","duration":"245.630244ms","start":"2026-06-17T15:58:34.632343Z","end":"2026-06-17T15:58:34.877973Z","steps":["trace[1694394528] 'read index received' (duration: 166.377908ms)","trace[1694394528] 'applied index is now lower than readState.Index' (duration: 79.251296ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:58:34.878047Z","caller":"traceutil/trace.go:171","msg":"trace[1398277012] transaction","detail":"{read_only:false; response_revision:18381; number_of_response:1; }","duration":"250.262651ms","start":"2026-06-17T15:58:34.627734Z","end":"2026-06-17T15:58:34.877997Z","steps":["trace[1398277012] 'process raft request' (duration: 250.128628ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:58:34.878188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.822349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-17T15:58:34.878203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.200323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-17T15:58:34.878219Z","caller":"traceutil/trace.go:171","msg":"trace[4628843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18381; }","duration":"245.892281ms","start":"2026-06-17T15:58:34.632321Z","end":"2026-06-17T15:58:34.878213Z","steps":["trace[4628843] 'agreement among raft nodes before linearized reading' (duration: 245.754518ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:58:34.878238Z","caller":"traceutil/trace.go:171","msg":"trace[270232720] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18381; }","duration":"245.290914ms","start":"2026-06-17T15:58:34.632938Z","end":"2026-06-17T15:58:34.878229Z","steps":["trace[270232720] 'agreement among raft nodes before linearized reading' (duration: 245.16472ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:58:34.878327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.647906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-17T15:58:34.878356Z","caller":"traceutil/trace.go:171","msg":"trace[1730453117] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:18381; }","duration":"133.736329ms","start":"2026-06-17T15:58:34.744609Z","end":"2026-06-17T15:58:34.878345Z","steps":["trace[1730453117] 'agreement among raft nodes before linearized reading' (duration: 133.692467ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:58:35.220017Z","caller":"traceutil/trace.go:171","msg":"trace[1021986667] linearizableReadLoop","detail":"{readStateIndex:19007; appliedIndex:19005; }","duration":"280.990009ms","start":"2026-06-17T15:58:34.938996Z","end":"2026-06-17T15:58:35.219986Z","steps":["trace[1021986667] 'read index received' (duration: 18.096059ms)","trace[1021986667] 'applied index is now lower than readState.Index' (duration: 262.89247ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:58:35.220038Z","caller":"traceutil/trace.go:171","msg":"trace[791108962] transaction","detail":"{read_only:false; response_revision:18383; number_of_response:1; }","duration":"337.519358ms","start":"2026-06-17T15:58:34.882483Z","end":"2026-06-17T15:58:35.220002Z","steps":["trace[791108962] 'process raft request' (duration: 335.723328ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:58:35.220197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.177014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-17T15:58:35.220251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:58:34.882457Z","time spent":"337.660151ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:58:35.220266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.758898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-17T15:58:35.220323Z","caller":"traceutil/trace.go:171","msg":"trace[128020720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18383; }","duration":"204.86069ms","start":"2026-06-17T15:58:35.015447Z","end":"2026-06-17T15:58:35.220308Z","steps":["trace[128020720] 'agreement among raft nodes before linearized reading' (duration: 204.703256ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:58:35.220323Z","caller":"traceutil/trace.go:171","msg":"trace[560663223] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:18383; }","duration":"281.391221ms","start":"2026-06-17T15:58:34.938905Z","end":"2026-06-17T15:58:35.220296Z","steps":["trace[560663223] 'agreement among raft nodes before linearized reading' (duration: 281.182895ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T15:58:35.62104Z","caller":"traceutil/trace.go:171","msg":"trace[1906930216] transaction","detail":"{read_only:false; response_revision:18384; number_of_response:1; }","duration":"396.089253ms","start":"2026-06-17T15:58:35.224919Z","end":"2026-06-17T15:58:35.621008Z","steps":["trace[1906930216] 'process raft request' (duration: 395.736023ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:58:35.621202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:58:35.224897Z","time spent":"396.226817ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-17T15:58:35.645229Z","caller":"traceutil/trace.go:171","msg":"trace[435761443] linearizableReadLoop","detail":"{readStateIndex:19009; appliedIndex:19007; }","duration":"343.04944ms","start":"2026-06-17T15:58:35.302154Z","end":"2026-06-17T15:58:35.645204Z","steps":["trace[435761443] 'read index received' (duration: 318.531155ms)","trace[435761443] 'applied index is now lower than readState.Index' (duration: 24.516965ms)"],"step_count":2} {"level":"info","ts":"2026-06-17T15:58:35.645273Z","caller":"traceutil/trace.go:171","msg":"trace[427654303] transaction","detail":"{read_only:false; response_revision:18385; number_of_response:1; }","duration":"419.745156ms","start":"2026-06-17T15:58:35.225501Z","end":"2026-06-17T15:58:35.645246Z","steps":["trace[427654303] 'process raft request' (duration: 419.56794ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:58:35.645423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:58:35.225476Z","time spent":"419.863369ms","remote":"127.0.0.1:42890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-17T15:58:35.64546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.282956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-06-17T15:58:35.645497Z","caller":"traceutil/trace.go:171","msg":"trace[765024081] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18385; }","duration":"343.378069ms","start":"2026-06-17T15:58:35.302108Z","end":"2026-06-17T15:58:35.645486Z","steps":["trace[765024081] 'agreement among raft nodes before linearized reading' (duration: 343.219955ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:58:35.645541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:58:35.302085Z","time spent":"343.447091ms","remote":"127.0.0.1:42772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":725,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-06-17T15:58:35.64553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.473333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-9xfph\" ","response":"range_response_count:1 size:9792"} {"level":"info","ts":"2026-06-17T15:58:35.645601Z","caller":"traceutil/trace.go:171","msg":"trace[1301362892] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-9xfph; range_end:; response_count:1; response_revision:18385; }","duration":"330.591117ms","start":"2026-06-17T15:58:35.314989Z","end":"2026-06-17T15:58:35.64558Z","steps":["trace[1301362892] 'agreement among raft nodes before linearized reading' (duration: 330.381091ms)"],"step_count":1} {"level":"warn","ts":"2026-06-17T15:58:35.645614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.129566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-s429k\" ","response":"range_response_count:1 size:22094"} {"level":"warn","ts":"2026-06-17T15:58:35.645648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-17T15:58:35.314968Z","time spent":"330.672889ms","remote":"127.0.0.1:42792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9814,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-9xfph\" "} {"level":"info","ts":"2026-06-17T15:58:35.645693Z","caller":"traceutil/trace.go:171","msg":"trace[133304875] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-s429k; range_end:; response_count:1; response_revision:18385; }","duration":"106.24607ms","start":"2026-06-17T15:58:35.539423Z","end":"2026-06-17T15:58:35.64567Z","steps":["trace[133304875] 'agreement among raft nodes before linearized reading' (duration: 106.019104ms)"],"step_count":1} {"level":"info","ts":"2026-06-17T16:01:09.699615Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-17T16:01:09.704659Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-17T16:01:09.704806Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-17T16:01:14.515189Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17432} {"level":"info","ts":"2026-06-17T16:01:14.609293Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17432,"took":"90.942051ms","hash":2795652399,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":31178752,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-17T16:01:14.609353Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2795652399,"revision":17432,"compact-revision":14860} {"level":"info","ts":"2026-06-17T16:06:14.520971Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19362} {"level":"info","ts":"2026-06-17T16:06:14.611991Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19362,"took":"87.977449ms","hash":1589881123,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":25497600,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-17T16:06:14.612035Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1589881123,"revision":19362,"compact-revision":17432}