{"level":"warn","ts":"2026-04-08T00:27:55.140297Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T00:27:55.140441Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74: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.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74: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-04-08T00:27:55.140522Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T00:27:55.140536Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-04-08T00:27:55.140563Z","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-04-08T00:27:55.141172Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-04-08T00:27:55.141246Z","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":8,"max-cpu-available":8,"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.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74: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-04-08T00:27:55.14832Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.860586ms"} {"level":"info","ts":"2026-04-08T00:27:55.160178Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-04-08T00:27:55.160253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T00:27:55.160283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-04-08T00:27:55.160297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T00:27:55.160305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-04-08T00:27:55.160353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-04-08T00:27:55.170108Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T00:27:55.172998Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T00:27:55.174259Z","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-04-08T00:27:55.177729Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T00:27:55.178728Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T00:27:55.178807Z","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-04-08T00:27:55.17887Z","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-04-08T00:27:55.178879Z","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-04-08T00:27:55.179785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-04-08T00:27:55.17987Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-04-08T00:27:55.180686Z","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-04-08T00:27:55.180751Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-04-08T00:27:55.180775Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-04-08T00:27:55.180939Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T00:27:55.180984Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T00:27:55.261209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T00:27:55.261287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T00:27:55.261312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-04-08T00:27:55.261332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-04-08T00:27:55.261349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-04-08T00:27:55.261366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-04-08T00:27:55.261384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-04-08T00:27:55.262408Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:27:55.263014Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T00:27:55.263162Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T00:27:55.263227Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T00:27:55.263424Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:27:55.263586Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:27:55.263645Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:27:55.263714Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T00:27:55.26374Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T00:27:55.266298Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-04-08T00:27:55.267087Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T00:27:58.578322Z","caller":"traceutil/trace.go:171","msg":"trace[406831291] linearizableReadLoop","detail":"{readStateIndex:117; appliedIndex:116; }","duration":"119.715554ms","start":"2026-04-08T00:27:58.45858Z","end":"2026-04-08T00:27:58.578296Z","steps":["trace[406831291] 'read index received' (duration: 119.569002ms)","trace[406831291] 'applied index is now lower than readState.Index' (duration: 145.342µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:27:58.578369Z","caller":"traceutil/trace.go:171","msg":"trace[61767447] transaction","detail":"{read_only:false; response_revision:112; number_of_response:1; }","duration":"121.396989ms","start":"2026-04-08T00:27:58.456948Z","end":"2026-04-08T00:27:58.578345Z","steps":["trace[61767447] 'process raft request' (duration: 121.201266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:27:58.578849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.041719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T00:27:58.57897Z","caller":"traceutil/trace.go:171","msg":"trace[407047955] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:112; }","duration":"120.372244ms","start":"2026-04-08T00:27:58.458541Z","end":"2026-04-08T00:27:58.578913Z","steps":["trace[407047955] 'agreement among raft nodes before linearized reading' (duration: 119.913696ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:05.364468Z","caller":"traceutil/trace.go:171","msg":"trace[1804409442] linearizableReadLoop","detail":"{readStateIndex:351; appliedIndex:350; }","duration":"223.39488ms","start":"2026-04-08T00:28:05.141048Z","end":"2026-04-08T00:28:05.364443Z","steps":["trace[1804409442] 'read index received' (duration: 152.811348ms)","trace[1804409442] 'applied index is now lower than readState.Index' (duration: 70.582672ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:28:05.364487Z","caller":"traceutil/trace.go:171","msg":"trace[1457295226] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"233.375909ms","start":"2026-04-08T00:28:05.131088Z","end":"2026-04-08T00:28:05.364464Z","steps":["trace[1457295226] 'process raft request' (duration: 162.832008ms)","trace[1457295226] 'compare' (duration: 70.4046ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:28:05.364616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.551622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-04-08T00:28:05.364646Z","caller":"traceutil/trace.go:171","msg":"trace[2081123602] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:340; }","duration":"223.647733ms","start":"2026-04-08T00:28:05.140989Z","end":"2026-04-08T00:28:05.364637Z","steps":["trace[2081123602] 'agreement among raft nodes before linearized reading' (duration: 223.567042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:28:05.364815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.413884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-04-08T00:28:05.364879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.887953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-08T00:28:05.364957Z","caller":"traceutil/trace.go:171","msg":"trace[1742612597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:340; }","duration":"106.000435ms","start":"2026-04-08T00:28:05.258936Z","end":"2026-04-08T00:28:05.364936Z","steps":["trace[1742612597] 'agreement among raft nodes before linearized reading' (duration: 105.857624ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:05.364865Z","caller":"traceutil/trace.go:171","msg":"trace[2040395450] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:340; }","duration":"172.497704ms","start":"2026-04-08T00:28:05.192354Z","end":"2026-04-08T00:28:05.364852Z","steps":["trace[2040395450] 'agreement among raft nodes before linearized reading' (duration: 172.399263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:28:05.365038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.341655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-08T00:28:05.365723Z","caller":"traceutil/trace.go:171","msg":"trace[1238635363] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:340; }","duration":"124.554997ms","start":"2026-04-08T00:28:05.240599Z","end":"2026-04-08T00:28:05.365154Z","steps":["trace[1238635363] 'agreement among raft nodes before linearized reading' (duration: 124.234673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:28:05.645066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.377873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:28:05.645164Z","caller":"traceutil/trace.go:171","msg":"trace[1593946211] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:351; }","duration":"254.942321ms","start":"2026-04-08T00:28:05.390205Z","end":"2026-04-08T00:28:05.645147Z","steps":["trace[1593946211] 'read index received' (duration: 50.613011ms)","trace[1593946211] 'applied index is now lower than readState.Index' (duration: 204.32777ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:28:05.645252Z","caller":"traceutil/trace.go:171","msg":"trace[1076680101] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"275.57554ms","start":"2026-04-08T00:28:05.369664Z","end":"2026-04-08T00:28:05.645239Z","steps":["trace[1076680101] 'process raft request' (duration: 102.447198ms)","trace[1076680101] 'compare' (duration: 172.278851ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:28:05.645637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.433088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-08T00:28:05.645688Z","caller":"traceutil/trace.go:171","msg":"trace[1083108532] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:342; }","duration":"255.474009ms","start":"2026-04-08T00:28:05.390193Z","end":"2026-04-08T00:28:05.645667Z","steps":["trace[1083108532] 'agreement among raft nodes before linearized reading' (duration: 255.413598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:28:05.645785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.11985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-08T00:28:05.645922Z","caller":"traceutil/trace.go:171","msg":"trace[96816529] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:342; }","duration":"205.313783ms","start":"2026-04-08T00:28:05.440583Z","end":"2026-04-08T00:28:05.645897Z","steps":["trace[96816529] 'agreement among raft nodes before linearized reading' (duration: 205.09999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:28:05.645959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.710905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-08T00:28:05.645992Z","caller":"traceutil/trace.go:171","msg":"trace[279262556] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:342; }","duration":"103.765046ms","start":"2026-04-08T00:28:05.542217Z","end":"2026-04-08T00:28:05.645983Z","steps":["trace[279262556] 'agreement among raft nodes before linearized reading' (duration: 103.711085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:28:05.646209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.518271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-04-08T00:28:05.646284Z","caller":"traceutil/trace.go:171","msg":"trace[518903617] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:342; }","duration":"155.642083ms","start":"2026-04-08T00:28:05.490625Z","end":"2026-04-08T00:28:05.646267Z","steps":["trace[518903617] 'agreement among raft nodes before linearized reading' (duration: 155.534892ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:26.791621Z","caller":"traceutil/trace.go:171","msg":"trace[1449341124] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"189.262756ms","start":"2026-04-08T00:28:26.602339Z","end":"2026-04-08T00:28:26.791602Z","steps":["trace[1449341124] 'process raft request' (duration: 189.143545ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:26.797442Z","caller":"traceutil/trace.go:171","msg":"trace[878397270] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"177.702852ms","start":"2026-04-08T00:28:26.61971Z","end":"2026-04-08T00:28:26.797413Z","steps":["trace[878397270] 'process raft request' (duration: 177.581031ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:26.797699Z","caller":"traceutil/trace.go:171","msg":"trace[1121408148] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"177.879464ms","start":"2026-04-08T00:28:26.619801Z","end":"2026-04-08T00:28:26.79768Z","steps":["trace[1121408148] 'process raft request' (duration: 177.562641ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:26.797677Z","caller":"traceutil/trace.go:171","msg":"trace[906313805] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"177.875385ms","start":"2026-04-08T00:28:26.619769Z","end":"2026-04-08T00:28:26.797644Z","steps":["trace[906313805] 'process raft request' (duration: 177.561762ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:26.79787Z","caller":"traceutil/trace.go:171","msg":"trace[903002667] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"161.10497ms","start":"2026-04-08T00:28:26.636753Z","end":"2026-04-08T00:28:26.797858Z","steps":["trace[903002667] 'process raft request' (duration: 160.888398ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:26.79788Z","caller":"traceutil/trace.go:171","msg":"trace[1618986109] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"165.020258ms","start":"2026-04-08T00:28:26.632831Z","end":"2026-04-08T00:28:26.797851Z","steps":["trace[1618986109] 'process raft request' (duration: 164.776625ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:26.797948Z","caller":"traceutil/trace.go:171","msg":"trace[2032266357] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"154.744697ms","start":"2026-04-08T00:28:26.643195Z","end":"2026-04-08T00:28:26.79794Z","steps":["trace[2032266357] 'process raft request' (duration: 154.495895ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:26.79802Z","caller":"traceutil/trace.go:171","msg":"trace[1096770016] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"142.632958ms","start":"2026-04-08T00:28:26.65538Z","end":"2026-04-08T00:28:26.798013Z","steps":["trace[1096770016] 'process raft request' (duration: 142.403756ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:26.798185Z","caller":"traceutil/trace.go:171","msg":"trace[1454635722] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"117.099938ms","start":"2026-04-08T00:28:26.681072Z","end":"2026-04-08T00:28:26.798172Z","steps":["trace[1454635722] 'process raft request' (duration: 116.770375ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:26.798222Z","caller":"traceutil/trace.go:171","msg":"trace[1425449648] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"174.674602ms","start":"2026-04-08T00:28:26.623513Z","end":"2026-04-08T00:28:26.798188Z","steps":["trace[1425449648] 'process raft request' (duration: 173.886064ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:26.798636Z","caller":"traceutil/trace.go:171","msg":"trace[423503236] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"187.154185ms","start":"2026-04-08T00:28:26.610626Z","end":"2026-04-08T00:28:26.79778Z","steps":["trace[423503236] 'process raft request' (duration: 186.534829ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:30.412433Z","caller":"traceutil/trace.go:171","msg":"trace[1136720403] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"135.473613ms","start":"2026-04-08T00:28:30.276935Z","end":"2026-04-08T00:28:30.412408Z","steps":["trace[1136720403] 'process raft request' (duration: 134.163811ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:33.554202Z","caller":"traceutil/trace.go:171","msg":"trace[458899818] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"463.730399ms","start":"2026-04-08T00:28:33.090123Z","end":"2026-04-08T00:28:33.553854Z","steps":["trace[458899818] 'process raft request' (duration: 463.485757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:28:33.554471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:28:33.090089Z","time spent":"464.307864ms","remote":"127.0.0.1:49930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:28:34.13698Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530392540377750804,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T00:28:34.469667Z","caller":"traceutil/trace.go:171","msg":"trace[2090156338] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"833.057805ms","start":"2026-04-08T00:28:33.636553Z","end":"2026-04-08T00:28:34.469611Z","steps":["trace[2090156338] 'read index received' (duration: 826.620167ms)","trace[2090156338] 'applied index is now lower than readState.Index' (duration: 6.436538ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:28:34.469909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.343289ms","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-04-08T00:28:34.469954Z","caller":"traceutil/trace.go:171","msg":"trace[455153234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:620; }","duration":"833.406429ms","start":"2026-04-08T00:28:33.636537Z","end":"2026-04-08T00:28:34.469943Z","steps":["trace[455153234] 'agreement among raft nodes before linearized reading' (duration: 833.253128ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:34.46986Z","caller":"traceutil/trace.go:171","msg":"trace[456406411] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"1.070780413s","start":"2026-04-08T00:28:33.398948Z","end":"2026-04-08T00:28:34.469728Z","steps":["trace[456406411] 'process raft request' (duration: 1.068634794s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:28:34.470037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.800239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:28:34.470061Z","caller":"traceutil/trace.go:171","msg":"trace[1562397335] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"666.863339ms","start":"2026-04-08T00:28:33.803192Z","end":"2026-04-08T00:28:34.470055Z","steps":["trace[1562397335] 'agreement among raft nodes before linearized reading' (duration: 666.828229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:28:34.470079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:28:33.803166Z","time spent":"666.907769ms","remote":"127.0.0.1:49754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T00:28:34.470212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:28:33.398917Z","time spent":"1.071118066s","remote":"127.0.0.1:49840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:28:34.470226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.008289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-08T00:28:34.470368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.332852ms","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-04-08T00:28:34.470483Z","caller":"traceutil/trace.go:171","msg":"trace[119767845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:620; }","duration":"566.511504ms","start":"2026-04-08T00:28:33.903952Z","end":"2026-04-08T00:28:34.470464Z","steps":["trace[119767845] 'agreement among raft nodes before linearized reading' (duration: 566.254112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:28:34.469992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:28:33.636476Z","time spent":"833.50806ms","remote":"127.0.0.1:49986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T00:28:34.47052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:28:33.903928Z","time spent":"566.584924ms","remote":"127.0.0.1:49986","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-04-08T00:28:34.470576Z","caller":"traceutil/trace.go:171","msg":"trace[1325978282] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:620; }","duration":"208.272722ms","start":"2026-04-08T00:28:34.262164Z","end":"2026-04-08T00:28:34.470436Z","steps":["trace[1325978282] 'agreement among raft nodes before linearized reading' (duration: 207.959669ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:34.627538Z","caller":"traceutil/trace.go:171","msg":"trace[780984765] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"151.71192ms","start":"2026-04-08T00:28:34.475798Z","end":"2026-04-08T00:28:34.62751Z","steps":["trace[780984765] 'process raft request' (duration: 95.428872ms)","trace[780984765] 'compare' (duration: 56.086766ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:28:34.627705Z","caller":"traceutil/trace.go:171","msg":"trace[877888110] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"151.60962ms","start":"2026-04-08T00:28:34.476064Z","end":"2026-04-08T00:28:34.627674Z","steps":["trace[877888110] 'process raft request' (duration: 151.399518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:28:35.285723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.625037ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:28:35.286195Z","caller":"traceutil/trace.go:171","msg":"trace[2094196378] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"630.325599ms","start":"2026-04-08T00:28:34.655826Z","end":"2026-04-08T00:28:35.286151Z","steps":["trace[2094196378] 'process raft request' (duration: 394.214647ms)","trace[2094196378] 'compare' (duration: 235.461476ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:28:35.286391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:28:34.655814Z","time spent":"630.48421ms","remote":"127.0.0.1:49930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:28:35.29145Z","caller":"traceutil/trace.go:171","msg":"trace[770887392] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:647; }","duration":"486.896714ms","start":"2026-04-08T00:28:34.804529Z","end":"2026-04-08T00:28:35.291426Z","steps":["trace[770887392] 'read index received' (duration: 245.517735ms)","trace[770887392] 'applied index is now lower than readState.Index' (duration: 241.377849ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:28:35.291682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.106576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:28:35.291712Z","caller":"traceutil/trace.go:171","msg":"trace[542091479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"487.230806ms","start":"2026-04-08T00:28:34.804474Z","end":"2026-04-08T00:28:35.291705Z","steps":["trace[542091479] 'agreement among raft nodes before linearized reading' (duration: 487.126715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:28:35.291798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:28:34.804454Z","time spent":"487.338237ms","remote":"127.0.0.1:49754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T00:28:35.291859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.280398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-08T00:28:35.292093Z","caller":"traceutil/trace.go:171","msg":"trace[1817090745] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:625; }","duration":"261.348849ms","start":"2026-04-08T00:28:35.030533Z","end":"2026-04-08T00:28:35.291882Z","steps":["trace[1817090745] 'agreement among raft nodes before linearized reading' (duration: 261.246278ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:40.515304Z","caller":"traceutil/trace.go:171","msg":"trace[815382142] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"128.945115ms","start":"2026-04-08T00:28:40.386335Z","end":"2026-04-08T00:28:40.51528Z","steps":["trace[815382142] 'process raft request' (duration: 128.516331ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:40.531484Z","caller":"traceutil/trace.go:171","msg":"trace[1680784401] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"144.337596ms","start":"2026-04-08T00:28:40.38712Z","end":"2026-04-08T00:28:40.531458Z","steps":["trace[1680784401] 'process raft request' (duration: 144.171015ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:28:51.433092Z","caller":"traceutil/trace.go:171","msg":"trace[1518899313] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"227.511661ms","start":"2026-04-08T00:28:51.20552Z","end":"2026-04-08T00:28:51.433031Z","steps":["trace[1518899313] 'process raft request' (duration: 227.262249ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:29:20.925334Z","caller":"traceutil/trace.go:171","msg":"trace[1680944215] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"175.121365ms","start":"2026-04-08T00:29:20.750162Z","end":"2026-04-08T00:29:20.925283Z","steps":["trace[1680944215] 'process raft request' (duration: 174.841114ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:29:20.925212Z","caller":"traceutil/trace.go:171","msg":"trace[765544882] linearizableReadLoop","detail":"{readStateIndex:1216; appliedIndex:1215; }","duration":"146.285186ms","start":"2026-04-08T00:29:20.778898Z","end":"2026-04-08T00:29:20.925183Z","steps":["trace[765544882] 'read index received' (duration: 146.041566ms)","trace[765544882] 'applied index is now lower than readState.Index' (duration: 242.57µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:29:20.92572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.805868ms","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-04-08T00:29:20.925824Z","caller":"traceutil/trace.go:171","msg":"trace[75281216] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1180; }","duration":"146.962354ms","start":"2026-04-08T00:29:20.778846Z","end":"2026-04-08T00:29:20.925808Z","steps":["trace[75281216] 'agreement among raft nodes before linearized reading' (duration: 146.713124ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:29:35.78053Z","caller":"traceutil/trace.go:171","msg":"trace[985392175] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"102.87558ms","start":"2026-04-08T00:29:35.677621Z","end":"2026-04-08T00:29:35.780497Z","steps":["trace[985392175] 'process raft request' (duration: 102.34833ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:29:59.266071Z","caller":"traceutil/trace.go:171","msg":"trace[1942708105] transaction","detail":"{read_only:false; response_revision:1724; number_of_response:1; }","duration":"105.122532ms","start":"2026-04-08T00:29:59.16093Z","end":"2026-04-08T00:29:59.266052Z","steps":["trace[1942708105] 'process raft request' (duration: 98.493722ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:29:59.266235Z","caller":"traceutil/trace.go:171","msg":"trace[1885735217] transaction","detail":"{read_only:false; response_revision:1725; number_of_response:1; }","duration":"105.096921ms","start":"2026-04-08T00:29:59.161099Z","end":"2026-04-08T00:29:59.266196Z","steps":["trace[1885735217] 'process raft request' (duration: 104.925686ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:30:08.512721Z","caller":"traceutil/trace.go:171","msg":"trace[1481066097] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"165.041113ms","start":"2026-04-08T00:30:08.34764Z","end":"2026-04-08T00:30:08.512681Z","steps":["trace[1481066097] 'process raft request' (duration: 164.797517ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:30:57.510575Z","caller":"traceutil/trace.go:171","msg":"trace[1249685816] linearizableReadLoop","detail":"{readStateIndex:2247; appliedIndex:2246; }","duration":"229.070446ms","start":"2026-04-08T00:30:57.281468Z","end":"2026-04-08T00:30:57.510538Z","steps":["trace[1249685816] 'read index received' (duration: 142.280379ms)","trace[1249685816] 'applied index is now lower than readState.Index' (duration: 86.788477ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:30:57.511137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.648056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-08T00:30:57.511266Z","caller":"traceutil/trace.go:171","msg":"trace[980054553] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2166; }","duration":"229.832499ms","start":"2026-04-08T00:30:57.281414Z","end":"2026-04-08T00:30:57.511247Z","steps":["trace[980054553] 'agreement among raft nodes before linearized reading' (duration: 229.31143ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:30:57.511422Z","caller":"traceutil/trace.go:171","msg":"trace[1665483656] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"290.046445ms","start":"2026-04-08T00:30:57.221299Z","end":"2026-04-08T00:30:57.511346Z","steps":["trace[1665483656] 'process raft request' (duration: 202.397153ms)","trace[1665483656] 'compare' (duration: 86.687484ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:30:57.51305Z","caller":"traceutil/trace.go:171","msg":"trace[1214527908] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"226.214045ms","start":"2026-04-08T00:30:57.286816Z","end":"2026-04-08T00:30:57.51303Z","steps":["trace[1214527908] 'process raft request' (duration: 226.005572ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:30:57.513205Z","caller":"traceutil/trace.go:171","msg":"trace[12570227] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"137.809223ms","start":"2026-04-08T00:30:57.375382Z","end":"2026-04-08T00:30:57.513191Z","steps":["trace[12570227] 'process raft request' (duration: 137.551978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:30:57.513421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.986393ms","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-04-08T00:30:57.513473Z","caller":"traceutil/trace.go:171","msg":"trace[2064940972] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2168; }","duration":"105.064554ms","start":"2026-04-08T00:30:57.408397Z","end":"2026-04-08T00:30:57.513461Z","steps":["trace[2064940972] 'agreement among raft nodes before linearized reading' (duration: 104.927141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:30:57.514359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.299516ms","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-04-08T00:30:57.514474Z","caller":"traceutil/trace.go:171","msg":"trace[2115605199] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2168; }","duration":"132.470349ms","start":"2026-04-08T00:30:57.381975Z","end":"2026-04-08T00:30:57.514445Z","steps":["trace[2115605199] 'agreement among raft nodes before linearized reading' (duration: 131.001623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:30:57.956357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.400768ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:30:57.95647Z","caller":"traceutil/trace.go:171","msg":"trace[1114792104] linearizableReadLoop","detail":"{readStateIndex:2250; appliedIndex:2249; }","duration":"438.149765ms","start":"2026-04-08T00:30:57.518299Z","end":"2026-04-08T00:30:57.956449Z","steps":["trace[1114792104] 'read index received' (duration: 131.723366ms)","trace[1114792104] 'applied index is now lower than readState.Index' (duration: 306.424879ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:30:57.956562Z","caller":"traceutil/trace.go:171","msg":"trace[1388203027] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"438.285178ms","start":"2026-04-08T00:30:57.518261Z","end":"2026-04-08T00:30:57.956546Z","steps":["trace[1388203027] 'process raft request' (duration: 131.604045ms)","trace[1388203027] 'compare' (duration: 306.252766ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:30:57.956646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:30:57.518241Z","time spent":"438.360649ms","remote":"127.0.0.1:49986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:30:57.957869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.563069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:15 size:25768"} {"level":"info","ts":"2026-04-08T00:30:57.95794Z","caller":"traceutil/trace.go:171","msg":"trace[1902208018] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:15; response_revision:2169; }","duration":"439.664211ms","start":"2026-04-08T00:30:57.518267Z","end":"2026-04-08T00:30:57.957931Z","steps":["trace[1902208018] 'agreement among raft nodes before linearized reading' (duration: 438.564633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:30:57.95798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:30:57.518248Z","time spent":"439.724532ms","remote":"127.0.0.1:49936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":15,"response size":25791,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"info","ts":"2026-04-08T00:30:57.972502Z","caller":"traceutil/trace.go:171","msg":"trace[185917377] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"453.823697ms","start":"2026-04-08T00:30:57.51866Z","end":"2026-04-08T00:30:57.972483Z","steps":["trace[185917377] 'process raft request' (duration: 453.725135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:30:57.972826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:30:57.518635Z","time spent":"453.930839ms","remote":"127.0.0.1:49986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:30:57.973042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.845458ms","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-04-08T00:30:57.973071Z","caller":"traceutil/trace.go:171","msg":"trace[150233325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2170; }","duration":"434.895049ms","start":"2026-04-08T00:30:57.538168Z","end":"2026-04-08T00:30:57.973064Z","steps":["trace[150233325] 'agreement among raft nodes before linearized reading' (duration: 434.805227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:30:57.973032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.687622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T00:30:57.973091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:30:57.538157Z","time spent":"434.929699ms","remote":"127.0.0.1:49986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T00:30:57.973221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.007751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-08T00:30:57.973287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.894259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-08T00:30:57.973329Z","caller":"traceutil/trace.go:171","msg":"trace[1147700806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2170; }","duration":"435.162984ms","start":"2026-04-08T00:30:57.538144Z","end":"2026-04-08T00:30:57.973307Z","steps":["trace[1147700806] 'agreement among raft nodes before linearized reading' (duration: 434.850509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:30:57.973375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:30:57.538124Z","time spent":"435.244765ms","remote":"127.0.0.1:49986","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-04-08T00:30:57.973158Z","caller":"traceutil/trace.go:171","msg":"trace[1581248953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2170; }","duration":"163.807084ms","start":"2026-04-08T00:30:57.809299Z","end":"2026-04-08T00:30:57.973106Z","steps":["trace[1581248953] 'agreement among raft nodes before linearized reading' (duration: 163.690862ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:30:57.973751Z","caller":"traceutil/trace.go:171","msg":"trace[1218228321] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2170; }","duration":"453.965649ms","start":"2026-04-08T00:30:57.519361Z","end":"2026-04-08T00:30:57.973327Z","steps":["trace[1218228321] 'agreement among raft nodes before linearized reading' (duration: 453.879668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:30:57.973799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:30:57.51935Z","time spent":"454.437827ms","remote":"127.0.0.1:49812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-04-08T00:31:45.058886Z","caller":"traceutil/trace.go:171","msg":"trace[925855676] transaction","detail":"{read_only:false; response_revision:2531; number_of_response:1; }","duration":"129.438106ms","start":"2026-04-08T00:31:44.929423Z","end":"2026-04-08T00:31:45.058861Z","steps":["trace[925855676] 'process raft request' (duration: 129.382245ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:45.059246Z","caller":"traceutil/trace.go:171","msg":"trace[1681271231] linearizableReadLoop","detail":"{readStateIndex:2623; appliedIndex:2622; }","duration":"256.623295ms","start":"2026-04-08T00:31:44.802605Z","end":"2026-04-08T00:31:45.059228Z","steps":["trace[1681271231] 'read index received' (duration: 252.723151ms)","trace[1681271231] 'applied index is now lower than readState.Index' (duration: 3.897984ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:31:45.059431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.794367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:31:45.059583Z","caller":"traceutil/trace.go:171","msg":"trace[1213263368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2531; }","duration":"256.99864ms","start":"2026-04-08T00:31:44.802562Z","end":"2026-04-08T00:31:45.059561Z","steps":["trace[1213263368] 'agreement among raft nodes before linearized reading' (duration: 256.772947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:45.059631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.329637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-04-08T00:31:45.059658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.119794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:31:45.059806Z","caller":"traceutil/trace.go:171","msg":"trace[1935032434] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2531; }","duration":"245.372377ms","start":"2026-04-08T00:31:44.814417Z","end":"2026-04-08T00:31:45.059789Z","steps":["trace[1935032434] 'agreement among raft nodes before linearized reading' (duration: 245.024463ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:45.059704Z","caller":"traceutil/trace.go:171","msg":"trace[2039383467] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2531; }","duration":"186.448238ms","start":"2026-04-08T00:31:44.87324Z","end":"2026-04-08T00:31:45.059688Z","steps":["trace[2039383467] 'agreement among raft nodes before linearized reading' (duration: 186.256075ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:45.059364Z","caller":"traceutil/trace.go:171","msg":"trace[1991457109] transaction","detail":"{read_only:false; response_revision:2530; number_of_response:1; }","duration":"319.135113ms","start":"2026-04-08T00:31:44.740139Z","end":"2026-04-08T00:31:45.059274Z","steps":["trace[1991457109] 'process raft request' (duration: 315.175719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:45.060263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:44.74012Z","time spent":"319.967044ms","remote":"127.0.0.1:49986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:32:18.110771Z","caller":"traceutil/trace.go:171","msg":"trace[316084534] transaction","detail":"{read_only:false; response_revision:2711; number_of_response:1; }","duration":"207.526117ms","start":"2026-04-08T00:32:17.903211Z","end":"2026-04-08T00:32:18.110738Z","steps":["trace[316084534] 'process raft request' (duration: 120.969008ms)","trace[316084534] 'compare' (duration: 86.414997ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:32:18.294201Z","caller":"traceutil/trace.go:171","msg":"trace[620582189] transaction","detail":"{read_only:false; response_revision:2712; number_of_response:1; }","duration":"120.302136ms","start":"2026-04-08T00:32:18.173869Z","end":"2026-04-08T00:32:18.294171Z","steps":["trace[620582189] 'process raft request' (duration: 83.508082ms)","trace[620582189] 'compare' (duration: 36.642092ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:42.673547Z","caller":"traceutil/trace.go:171","msg":"trace[1662237419] transaction","detail":"{read_only:false; response_revision:3560; number_of_response:1; }","duration":"153.789449ms","start":"2026-04-08T00:34:42.519711Z","end":"2026-04-08T00:34:42.6735Z","steps":["trace[1662237419] 'process raft request' (duration: 151.884894ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:45.933906Z","caller":"traceutil/trace.go:171","msg":"trace[903091514] transaction","detail":"{read_only:false; response_revision:3577; number_of_response:1; }","duration":"104.301582ms","start":"2026-04-08T00:34:45.829577Z","end":"2026-04-08T00:34:45.933879Z","steps":["trace[903091514] 'process raft request' (duration: 104.116096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:46.149867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.866706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:34:46.149998Z","caller":"traceutil/trace.go:171","msg":"trace[1021094845] linearizableReadLoop","detail":"{readStateIndex:3712; appliedIndex:3711; }","duration":"212.288651ms","start":"2026-04-08T00:34:45.937688Z","end":"2026-04-08T00:34:46.149977Z","steps":["trace[1021094845] 'read index received' (duration: 44.202309ms)","trace[1021094845] 'applied index is now lower than readState.Index' (duration: 168.084492ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:34:46.15017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.475736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak.18a43a7ae92f2e7f\" ","response":"range_response_count:1 size:676"} {"level":"info","ts":"2026-04-08T00:34:46.150169Z","caller":"traceutil/trace.go:171","msg":"trace[1931731715] transaction","detail":"{read_only:false; response_revision:3578; number_of_response:1; }","duration":"276.368915ms","start":"2026-04-08T00:34:45.873758Z","end":"2026-04-08T00:34:46.150127Z","steps":["trace[1931731715] 'process raft request' (duration: 108.142689ms)","trace[1931731715] 'compare' (duration: 167.572668ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:46.150216Z","caller":"traceutil/trace.go:171","msg":"trace[529754313] range","detail":"{range_begin:/registry/events/auth-system/keycloak.18a43a7ae92f2e7f; range_end:; response_count:1; response_revision:3578; }","duration":"212.553188ms","start":"2026-04-08T00:34:45.937651Z","end":"2026-04-08T00:34:46.150204Z","steps":["trace[529754313] 'agreement among raft nodes before linearized reading' (duration: 212.383213ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:46.291309Z","caller":"traceutil/trace.go:171","msg":"trace[1578627512] transaction","detail":"{read_only:false; response_revision:3580; number_of_response:1; }","duration":"115.026639ms","start":"2026-04-08T00:34:46.176247Z","end":"2026-04-08T00:34:46.291273Z","steps":["trace[1578627512] 'process raft request' (duration: 88.973863ms)","trace[1578627512] 'compare' (duration: 25.620514ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:50.803424Z","caller":"traceutil/trace.go:171","msg":"trace[210580044] transaction","detail":"{read_only:false; response_revision:3603; number_of_response:1; }","duration":"105.54396ms","start":"2026-04-08T00:34:50.697859Z","end":"2026-04-08T00:34:50.803403Z","steps":["trace[210580044] 'process raft request' (duration: 34.474887ms)","trace[210580044] 'compare' (duration: 70.783625ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:55.468414Z","caller":"traceutil/trace.go:171","msg":"trace[884572621] linearizableReadLoop","detail":"{readStateIndex:3764; appliedIndex:3762; }","duration":"153.206259ms","start":"2026-04-08T00:34:55.315179Z","end":"2026-04-08T00:34:55.468385Z","steps":["trace[884572621] 'read index received' (duration: 35.645581ms)","trace[884572621] 'applied index is now lower than readState.Index' (duration: 117.556778ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:55.46865Z","caller":"traceutil/trace.go:171","msg":"trace[453948796] transaction","detail":"{read_only:false; response_revision:3628; number_of_response:1; }","duration":"177.152584ms","start":"2026-04-08T00:34:55.291446Z","end":"2026-04-08T00:34:55.468599Z","steps":["trace[453948796] 'process raft request' (duration: 135.121601ms)","trace[453948796] 'compare' (duration: 41.647353ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:34:55.46912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.416951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-04-08T00:34:55.46921Z","caller":"traceutil/trace.go:171","msg":"trace[287477220] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3628; }","duration":"151.538164ms","start":"2026-04-08T00:34:55.317655Z","end":"2026-04-08T00:34:55.469193Z","steps":["trace[287477220] 'agreement among raft nodes before linearized reading' (duration: 151.182394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:55.469278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.955059ms","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-04-08T00:34:55.469499Z","caller":"traceutil/trace.go:171","msg":"trace[1304847855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3628; }","duration":"154.36301ms","start":"2026-04-08T00:34:55.315113Z","end":"2026-04-08T00:34:55.469476Z","steps":["trace[1304847855] 'agreement among raft nodes before linearized reading' (duration: 153.541949ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:55.824204Z","caller":"traceutil/trace.go:171","msg":"trace[118641992] transaction","detail":"{read_only:false; response_revision:3631; number_of_response:1; }","duration":"129.626083ms","start":"2026-04-08T00:34:55.694552Z","end":"2026-04-08T00:34:55.824178Z","steps":["trace[118641992] 'process raft request' (duration: 129.450808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:55.824329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.284941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T00:34:55.824202Z","caller":"traceutil/trace.go:171","msg":"trace[1862989900] linearizableReadLoop","detail":"{readStateIndex:3767; appliedIndex:3766; }","duration":"107.125957ms","start":"2026-04-08T00:34:55.717019Z","end":"2026-04-08T00:34:55.824145Z","steps":["trace[1862989900] 'read index received' (duration: 106.962143ms)","trace[1862989900] 'applied index is now lower than readState.Index' (duration: 162.924µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:55.824374Z","caller":"traceutil/trace.go:171","msg":"trace[1248715425] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:3631; }","duration":"107.370434ms","start":"2026-04-08T00:34:55.71699Z","end":"2026-04-08T00:34:55.824361Z","steps":["trace[1248715425] 'agreement among raft nodes before linearized reading' (duration: 107.284831ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:35:39.792222Z","caller":"traceutil/trace.go:171","msg":"trace[1450370940] transaction","detail":"{read_only:false; number_of_response:0; response_revision:3938; }","duration":"107.607922ms","start":"2026-04-08T00:35:39.68459Z","end":"2026-04-08T00:35:39.792198Z","steps":["trace[1450370940] 'process raft request' (duration: 89.928743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:35:57.517596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.539497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-08T00:35:57.517663Z","caller":"traceutil/trace.go:171","msg":"trace[551868374] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4051; }","duration":"100.64911ms","start":"2026-04-08T00:35:57.416998Z","end":"2026-04-08T00:35:57.517647Z","steps":["trace[551868374] 'range keys from in-memory index tree' (duration: 100.388884ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:05.469023Z","caller":"traceutil/trace.go:171","msg":"trace[1971958172] linearizableReadLoop","detail":"{readStateIndex:4646; appliedIndex:4645; }","duration":"127.932827ms","start":"2026-04-08T00:36:05.341074Z","end":"2026-04-08T00:36:05.469007Z","steps":["trace[1971958172] 'read index received' (duration: 127.887726ms)","trace[1971958172] 'applied index is now lower than readState.Index' (duration: 44.561µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:05.469222Z","caller":"traceutil/trace.go:171","msg":"trace[251874166] transaction","detail":"{read_only:false; response_revision:4478; number_of_response:1; }","duration":"131.366511ms","start":"2026-04-08T00:36:05.337831Z","end":"2026-04-08T00:36:05.469197Z","steps":["trace[251874166] 'process raft request' (duration: 131.060864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:05.469939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.852397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"info","ts":"2026-04-08T00:36:05.469988Z","caller":"traceutil/trace.go:171","msg":"trace[1024617867] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4478; }","duration":"128.960109ms","start":"2026-04-08T00:36:05.341019Z","end":"2026-04-08T00:36:05.469979Z","steps":["trace[1024617867] 'agreement among raft nodes before linearized reading' (duration: 128.090511ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:05.480498Z","caller":"traceutil/trace.go:171","msg":"trace[450147938] transaction","detail":"{read_only:false; response_revision:4481; number_of_response:1; }","duration":"135.612161ms","start":"2026-04-08T00:36:05.344863Z","end":"2026-04-08T00:36:05.480475Z","steps":["trace[450147938] 'process raft request' (duration: 135.57ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:05.48085Z","caller":"traceutil/trace.go:171","msg":"trace[1303336236] transaction","detail":"{read_only:false; response_revision:4479; number_of_response:1; }","duration":"139.439404ms","start":"2026-04-08T00:36:05.3414Z","end":"2026-04-08T00:36:05.48084Z","steps":["trace[1303336236] 'process raft request' (duration: 138.876733ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:05.481164Z","caller":"traceutil/trace.go:171","msg":"trace[1329600836] transaction","detail":"{read_only:false; response_revision:4480; number_of_response:1; }","duration":"138.124306ms","start":"2026-04-08T00:36:05.343011Z","end":"2026-04-08T00:36:05.481135Z","steps":["trace[1329600836] 'process raft request' (duration: 137.36182ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:10.82942Z","caller":"traceutil/trace.go:171","msg":"trace[1250382250] linearizableReadLoop","detail":"{readStateIndex:4826; appliedIndex:4825; }","duration":"165.992386ms","start":"2026-04-08T00:36:10.663403Z","end":"2026-04-08T00:36:10.829396Z","steps":["trace[1250382250] 'read index received' (duration: 164.351932ms)","trace[1250382250] 'applied index is now lower than readState.Index' (duration: 1.639294ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:10.829535Z","caller":"traceutil/trace.go:171","msg":"trace[2118078053] transaction","detail":"{read_only:false; response_revision:4653; number_of_response:1; }","duration":"166.63196ms","start":"2026-04-08T00:36:10.662892Z","end":"2026-04-08T00:36:10.829524Z","steps":["trace[2118078053] 'process raft request' (duration: 164.837942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:10.830773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.310394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-9962d3df-ac88-4678-89ad-115ba45b857e\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-04-08T00:36:10.830886Z","caller":"traceutil/trace.go:171","msg":"trace[1379917614] range","detail":"{range_begin:/registry/persistentvolumes/pvc-9962d3df-ac88-4678-89ad-115ba45b857e; range_end:; response_count:1; response_revision:4653; }","duration":"167.485788ms","start":"2026-04-08T00:36:10.663379Z","end":"2026-04-08T00:36:10.830865Z","steps":["trace[1379917614] 'agreement among raft nodes before linearized reading' (duration: 166.570339ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:10.835233Z","caller":"traceutil/trace.go:171","msg":"trace[41889168] transaction","detail":"{read_only:false; response_revision:4654; number_of_response:1; }","duration":"116.947337ms","start":"2026-04-08T00:36:10.718254Z","end":"2026-04-08T00:36:10.835201Z","steps":["trace[41889168] 'process raft request' (duration: 116.358524ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:11.567733Z","caller":"traceutil/trace.go:171","msg":"trace[640022490] transaction","detail":"{read_only:false; response_revision:4662; number_of_response:1; }","duration":"245.290723ms","start":"2026-04-08T00:36:11.322417Z","end":"2026-04-08T00:36:11.567708Z","steps":["trace[640022490] 'process raft request' (duration: 234.383582ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:11.57094Z","caller":"traceutil/trace.go:171","msg":"trace[275334345] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"166.350404ms","start":"2026-04-08T00:36:11.404568Z","end":"2026-04-08T00:36:11.570918Z","steps":["trace[275334345] 'process raft request' (duration: 166.303253ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:11.571403Z","caller":"traceutil/trace.go:171","msg":"trace[138947230] transaction","detail":"{read_only:false; response_revision:4663; number_of_response:1; }","duration":"238.09162ms","start":"2026-04-08T00:36:11.333299Z","end":"2026-04-08T00:36:11.571391Z","steps":["trace[138947230] 'process raft request' (duration: 237.457097ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:17.198282Z","caller":"traceutil/trace.go:171","msg":"trace[970350850] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"521.33394ms","start":"2026-04-08T00:36:16.67691Z","end":"2026-04-08T00:36:17.198244Z","steps":["trace[970350850] 'process raft request' (duration: 426.861215ms)","trace[970350850] 'compare' (duration: 94.167169ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:17.198355Z","caller":"traceutil/trace.go:171","msg":"trace[1508217189] linearizableReadLoop","detail":"{readStateIndex:4890; appliedIndex:4889; }","duration":"390.739808ms","start":"2026-04-08T00:36:16.807578Z","end":"2026-04-08T00:36:17.198318Z","steps":["trace[1508217189] 'read index received' (duration: 296.284083ms)","trace[1508217189] 'applied index is now lower than readState.Index' (duration: 94.450295ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:36:17.198417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:16.676887Z","time spent":"521.459972ms","remote":"127.0.0.1:49986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:36:17.198402Z","caller":"traceutil/trace.go:171","msg":"trace[348996135] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"136.570631ms","start":"2026-04-08T00:36:17.061799Z","end":"2026-04-08T00:36:17.19837Z","steps":["trace[348996135] 'process raft request' (duration: 136.506259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:17.19847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.887661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:17.198519Z","caller":"traceutil/trace.go:171","msg":"trace[1982698417] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4716; }","duration":"390.959293ms","start":"2026-04-08T00:36:16.807549Z","end":"2026-04-08T00:36:17.198508Z","steps":["trace[1982698417] 'agreement among raft nodes before linearized reading' (duration: 390.8519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:17.198546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:16.807536Z","time spent":"391.002993ms","remote":"127.0.0.1:49754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T00:36:17.234268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.960258ms","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-04-08T00:36:17.234341Z","caller":"traceutil/trace.go:171","msg":"trace[1935849292] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:4717; }","duration":"318.082921ms","start":"2026-04-08T00:36:16.91624Z","end":"2026-04-08T00:36:17.234323Z","steps":["trace[1935849292] 'agreement among raft nodes before linearized reading' (duration: 317.949208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:17.234373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:16.91622Z","time spent":"318.143382ms","remote":"127.0.0.1:49982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-04-08T00:36:17.234669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.683353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:17.234767Z","caller":"traceutil/trace.go:171","msg":"trace[742621687] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:0; response_revision:4717; }","duration":"331.821536ms","start":"2026-04-08T00:36:16.902923Z","end":"2026-04-08T00:36:17.234745Z","steps":["trace[742621687] 'agreement among raft nodes before linearized reading' (duration: 331.645313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:17.234812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:16.902909Z","time spent":"331.894227ms","remote":"127.0.0.1:42200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-04-08T00:36:17.67055Z","caller":"traceutil/trace.go:171","msg":"trace[940644418] transaction","detail":"{read_only:false; response_revision:4723; number_of_response:1; }","duration":"256.26521ms","start":"2026-04-08T00:36:17.414246Z","end":"2026-04-08T00:36:17.670511Z","steps":["trace[940644418] 'process raft request' (duration: 256.119757ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:17.809271Z","caller":"traceutil/trace.go:171","msg":"trace[1825512832] transaction","detail":"{read_only:false; response_revision:4724; number_of_response:1; }","duration":"394.846002ms","start":"2026-04-08T00:36:17.4144Z","end":"2026-04-08T00:36:17.809246Z","steps":["trace[1825512832] 'process raft request' (duration: 389.300567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:17.809411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:17.414377Z","time spent":"394.959944ms","remote":"127.0.0.1:49908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1272,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:36:17.809928Z","caller":"traceutil/trace.go:171","msg":"trace[1146577136] linearizableReadLoop","detail":"{readStateIndex:4899; appliedIndex:4897; }","duration":"395.325712ms","start":"2026-04-08T00:36:17.414574Z","end":"2026-04-08T00:36:17.809899Z","steps":["trace[1146577136] 'read index received' (duration: 255.779669ms)","trace[1146577136] 'applied index is now lower than readState.Index' (duration: 139.543633ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:17.810007Z","caller":"traceutil/trace.go:171","msg":"trace[1817944750] transaction","detail":"{read_only:false; response_revision:4727; number_of_response:1; }","duration":"380.595315ms","start":"2026-04-08T00:36:17.42939Z","end":"2026-04-08T00:36:17.809985Z","steps":["trace[1817944750] 'process raft request' (duration: 380.33533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:17.810131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.538996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12972"} {"level":"info","ts":"2026-04-08T00:36:17.81017Z","caller":"traceutil/trace.go:171","msg":"trace[1406669153] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4727; }","duration":"395.604647ms","start":"2026-04-08T00:36:17.414558Z","end":"2026-04-08T00:36:17.810162Z","steps":["trace[1406669153] 'agreement among raft nodes before linearized reading' (duration: 395.461374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:17.810196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:17.414551Z","time spent":"395.639148ms","remote":"127.0.0.1:49930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":12995,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-04-08T00:36:17.809975Z","caller":"traceutil/trace.go:171","msg":"trace[168451465] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"394.538696ms","start":"2026-04-08T00:36:17.415426Z","end":"2026-04-08T00:36:17.809964Z","steps":["trace[168451465] 'process raft request' (duration: 394.151108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:17.81026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:17.429372Z","time spent":"380.681357ms","remote":"127.0.0.1:42200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2725,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:36:17.810538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.060726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-08T00:36:17.81057Z","caller":"traceutil/trace.go:171","msg":"trace[750684008] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4727; }","duration":"394.106587ms","start":"2026-04-08T00:36:17.416454Z","end":"2026-04-08T00:36:17.810561Z","steps":["trace[750684008] 'agreement among raft nodes before linearized reading' (duration: 394.036705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:17.8106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:17.416447Z","time spent":"394.147908ms","remote":"127.0.0.1:49900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-04-08T00:36:17.810775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:17.41541Z","time spent":"394.941934ms","remote":"127.0.0.1:49908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:36:17.811224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.61412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-08T00:36:17.811262Z","caller":"traceutil/trace.go:171","msg":"trace[598549499] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4727; }","duration":"108.65514ms","start":"2026-04-08T00:36:17.702597Z","end":"2026-04-08T00:36:17.811252Z","steps":["trace[598549499] 'agreement among raft nodes before linearized reading' (duration: 108.524228ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:17.809776Z","caller":"traceutil/trace.go:171","msg":"trace[1663548099] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"395.087887ms","start":"2026-04-08T00:36:17.414644Z","end":"2026-04-08T00:36:17.809732Z","steps":["trace[1663548099] 'process raft request' (duration: 394.522796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:17.811748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:17.414632Z","time spent":"397.010447ms","remote":"127.0.0.1:49990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1852,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:36:17.981705Z","caller":"traceutil/trace.go:171","msg":"trace[224730167] linearizableReadLoop","detail":"{readStateIndex:4904; appliedIndex:4902; }","duration":"164.735436ms","start":"2026-04-08T00:36:17.816943Z","end":"2026-04-08T00:36:17.981679Z","steps":["trace[224730167] 'read index received' (duration: 84.710411ms)","trace[224730167] 'applied index is now lower than readState.Index' (duration: 80.023835ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:17.981793Z","caller":"traceutil/trace.go:171","msg":"trace[1079695841] transaction","detail":"{read_only:false; response_revision:4730; number_of_response:1; }","duration":"161.141541ms","start":"2026-04-08T00:36:17.820613Z","end":"2026-04-08T00:36:17.981754Z","steps":["trace[1079695841] 'process raft request' (duration: 160.985748ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:17.98183Z","caller":"traceutil/trace.go:171","msg":"trace[73721663] transaction","detail":"{read_only:false; response_revision:4729; number_of_response:1; }","duration":"165.81589ms","start":"2026-04-08T00:36:17.815998Z","end":"2026-04-08T00:36:17.981813Z","steps":["trace[73721663] 'process raft request' (duration: 155.133217ms)","trace[73721663] 'compare' (duration: 10.13037ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:36:17.98183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.862449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:17.982033Z","caller":"traceutil/trace.go:171","msg":"trace[2086443750] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:0; response_revision:4730; }","duration":"165.103264ms","start":"2026-04-08T00:36:17.816918Z","end":"2026-04-08T00:36:17.982021Z","steps":["trace[2086443750] 'agreement among raft nodes before linearized reading' (duration: 164.858609ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:18.274812Z","caller":"traceutil/trace.go:171","msg":"trace[1796025570] transaction","detail":"{read_only:false; response_revision:4739; number_of_response:1; }","duration":"169.19907ms","start":"2026-04-08T00:36:18.105587Z","end":"2026-04-08T00:36:18.274786Z","steps":["trace[1796025570] 'process raft request' (duration: 168.981885ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:18.282211Z","caller":"traceutil/trace.go:171","msg":"trace[689581434] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"175.257986ms","start":"2026-04-08T00:36:18.106938Z","end":"2026-04-08T00:36:18.282196Z","steps":["trace[689581434] 'process raft request' (duration: 175.194325ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:18.28224Z","caller":"traceutil/trace.go:171","msg":"trace[1858618302] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"175.641334ms","start":"2026-04-08T00:36:18.106573Z","end":"2026-04-08T00:36:18.282215Z","steps":["trace[1858618302] 'process raft request' (duration: 175.470611ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:18.282439Z","caller":"traceutil/trace.go:171","msg":"trace[1194621933] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"174.887577ms","start":"2026-04-08T00:36:18.107527Z","end":"2026-04-08T00:36:18.282415Z","steps":["trace[1194621933] 'process raft request' (duration: 174.638162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:20.952772Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530392540377760507,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T00:36:20.961345Z","caller":"traceutil/trace.go:171","msg":"trace[560788408] transaction","detail":"{read_only:false; response_revision:4818; number_of_response:1; }","duration":"512.11049ms","start":"2026-04-08T00:36:20.449205Z","end":"2026-04-08T00:36:20.961315Z","steps":["trace[560788408] 'process raft request' (duration: 511.944776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:20.961506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:20.449032Z","time spent":"512.390135ms","remote":"127.0.0.1:49814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:36:21.006761Z","caller":"traceutil/trace.go:171","msg":"trace[1115105232] linearizableReadLoop","detail":"{readStateIndex:4995; appliedIndex:4993; }","duration":"554.32711ms","start":"2026-04-08T00:36:20.452406Z","end":"2026-04-08T00:36:21.006733Z","steps":["trace[1115105232] 'read index received' (duration: 508.730069ms)","trace[1115105232] 'applied index is now lower than readState.Index' (duration: 45.596001ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:21.006819Z","caller":"traceutil/trace.go:171","msg":"trace[1340716107] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"282.407878ms","start":"2026-04-08T00:36:20.724376Z","end":"2026-04-08T00:36:21.006784Z","steps":["trace[1340716107] 'process raft request' (duration: 282.191113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:21.007034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.607616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-04-08T00:36:21.007081Z","caller":"traceutil/trace.go:171","msg":"trace[1973800130] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4819; }","duration":"554.704968ms","start":"2026-04-08T00:36:20.452362Z","end":"2026-04-08T00:36:21.007067Z","steps":["trace[1973800130] 'agreement among raft nodes before linearized reading' (duration: 554.509994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:21.007118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:20.452345Z","time spent":"554.764729ms","remote":"127.0.0.1:50216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":9660,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"warn","ts":"2026-04-08T00:36:21.007652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.259847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d95ca8a1-74e3-4012-a327-f0910970abcd\" ","response":"range_response_count:1 size:3938"} {"level":"info","ts":"2026-04-08T00:36:21.007733Z","caller":"traceutil/trace.go:171","msg":"trace[793269325] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d95ca8a1-74e3-4012-a327-f0910970abcd; range_end:; response_count:1; response_revision:4819; }","duration":"550.370019ms","start":"2026-04-08T00:36:20.457343Z","end":"2026-04-08T00:36:21.007713Z","steps":["trace[793269325] 'agreement among raft nodes before linearized reading' (duration: 549.556983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:21.007776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:20.457332Z","time spent":"550.43415ms","remote":"127.0.0.1:49930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3961,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d95ca8a1-74e3-4012-a327-f0910970abcd\" "} {"level":"warn","ts":"2026-04-08T00:36:30.94076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.224899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:30.940842Z","caller":"traceutil/trace.go:171","msg":"trace[814980547] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:4941; }","duration":"174.352642ms","start":"2026-04-08T00:36:30.766471Z","end":"2026-04-08T00:36:30.940824Z","steps":["trace[814980547] 'count revisions from in-memory index tree' (duration: 174.171358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:30.941161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.680042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:36:30.941198Z","caller":"traceutil/trace.go:171","msg":"trace[381478840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4941; }","duration":"136.737533ms","start":"2026-04-08T00:36:30.804452Z","end":"2026-04-08T00:36:30.941189Z","steps":["trace[381478840] 'range keys from in-memory index tree' (duration: 136.635241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:30.94139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.661988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-08T00:36:30.941454Z","caller":"traceutil/trace.go:171","msg":"trace[291747145] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4941; }","duration":"403.75784ms","start":"2026-04-08T00:36:30.537677Z","end":"2026-04-08T00:36:30.941435Z","steps":["trace[291747145] 'range keys from in-memory index tree' (duration: 403.484314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:30.941502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:30.537655Z","time spent":"403.835871ms","remote":"127.0.0.1:49900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-08T00:36:30.942116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.178417ms","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-04-08T00:36:30.942204Z","caller":"traceutil/trace.go:171","msg":"trace[312930681] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4941; }","duration":"538.30185ms","start":"2026-04-08T00:36:30.403882Z","end":"2026-04-08T00:36:30.942184Z","steps":["trace[312930681] 'range keys from in-memory index tree' (duration: 538.038534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:30.942246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:30.403862Z","time spent":"538.375991ms","remote":"127.0.0.1:49986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T00:36:30.942267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.985713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-08T00:36:30.942287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.874571ms","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-04-08T00:36:30.942319Z","caller":"traceutil/trace.go:171","msg":"trace[1590590809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4941; }","duration":"537.872171ms","start":"2026-04-08T00:36:30.404432Z","end":"2026-04-08T00:36:30.942304Z","steps":["trace[1590590809] 'range keys from in-memory index tree' (duration: 536.755419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:30.941229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.418454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T00:36:30.942367Z","caller":"traceutil/trace.go:171","msg":"trace[919940047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4941; }","duration":"557.611728ms","start":"2026-04-08T00:36:30.384745Z","end":"2026-04-08T00:36:30.942357Z","steps":["trace[919940047] 'range keys from in-memory index tree' (duration: 556.20533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:30.942363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:30.404411Z","time spent":"537.940192ms","remote":"127.0.0.1:49986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T00:36:30.942361Z","caller":"traceutil/trace.go:171","msg":"trace[1729489841] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4941; }","duration":"536.973273ms","start":"2026-04-08T00:36:30.405373Z","end":"2026-04-08T00:36:30.942347Z","steps":["trace[1729489841] 'range keys from in-memory index tree' (duration: 536.687667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:30.942397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:30.384728Z","time spent":"557.659929ms","remote":"127.0.0.1:49986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T00:36:30.942434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:30.405362Z","time spent":"537.036544ms","remote":"127.0.0.1:49986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-08T00:36:30.942413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.095474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10394"} {"level":"info","ts":"2026-04-08T00:36:30.942502Z","caller":"traceutil/trace.go:171","msg":"trace[1139251851] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4941; }","duration":"461.242867ms","start":"2026-04-08T00:36:30.48124Z","end":"2026-04-08T00:36:30.942483Z","steps":["trace[1139251851] 'range keys from in-memory index tree' (duration: 459.634605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:36:30.942567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:36:30.481232Z","time spent":"461.317728ms","remote":"127.0.0.1:49930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10417,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-04-08T00:36:31.079522Z","caller":"traceutil/trace.go:171","msg":"trace[2099250020] transaction","detail":"{read_only:false; response_revision:4943; number_of_response:1; }","duration":"132.8188ms","start":"2026-04-08T00:36:30.946676Z","end":"2026-04-08T00:36:31.079495Z","steps":["trace[2099250020] 'process raft request' (duration: 109.027353ms)","trace[2099250020] 'compare' (duration: 23.609314ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:36:31.079812Z","caller":"traceutil/trace.go:171","msg":"trace[1312746879] transaction","detail":"{read_only:false; response_revision:4945; number_of_response:1; }","duration":"132.417873ms","start":"2026-04-08T00:36:30.94737Z","end":"2026-04-08T00:36:31.079788Z","steps":["trace[1312746879] 'process raft request' (duration: 132.27762ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:31.079858Z","caller":"traceutil/trace.go:171","msg":"trace[1270717087] transaction","detail":"{read_only:false; response_revision:4944; number_of_response:1; }","duration":"132.680948ms","start":"2026-04-08T00:36:30.947161Z","end":"2026-04-08T00:36:31.079842Z","steps":["trace[1270717087] 'process raft request' (duration: 132.29092ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:31.091819Z","caller":"traceutil/trace.go:171","msg":"trace[129683075] transaction","detail":"{read_only:false; response_revision:4946; number_of_response:1; }","duration":"137.547466ms","start":"2026-04-08T00:36:30.954256Z","end":"2026-04-08T00:36:31.091804Z","steps":["trace[129683075] 'process raft request' (duration: 137.403642ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:31.091989Z","caller":"traceutil/trace.go:171","msg":"trace[246853061] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"121.690556ms","start":"2026-04-08T00:36:30.970275Z","end":"2026-04-08T00:36:31.091965Z","steps":["trace[246853061] 'process raft request' (duration: 121.486291ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:36:31.438598Z","caller":"traceutil/trace.go:171","msg":"trace[1817066310] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"123.777386ms","start":"2026-04-08T00:36:31.314792Z","end":"2026-04-08T00:36:31.438569Z","steps":["trace[1817066310] 'process raft request' (duration: 55.169629ms)","trace[1817066310] 'compare' (duration: 68.414654ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:37:17.52253Z","caller":"traceutil/trace.go:171","msg":"trace[2033109244] linearizableReadLoop","detail":"{readStateIndex:5543; appliedIndex:5542; }","duration":"154.733051ms","start":"2026-04-08T00:37:17.367773Z","end":"2026-04-08T00:37:17.522506Z","steps":["trace[2033109244] 'read index received' (duration: 154.645ms)","trace[2033109244] 'applied index is now lower than readState.Index' (duration: 86.971µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:37:17.522601Z","caller":"traceutil/trace.go:171","msg":"trace[594441385] transaction","detail":"{read_only:false; response_revision:5353; number_of_response:1; }","duration":"169.766256ms","start":"2026-04-08T00:37:17.352798Z","end":"2026-04-08T00:37:17.522565Z","steps":["trace[594441385] 'process raft request' (duration: 169.564543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:17.522751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.953546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T00:37:17.522799Z","caller":"traceutil/trace.go:171","msg":"trace[653489324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5353; }","duration":"155.049697ms","start":"2026-04-08T00:37:17.367737Z","end":"2026-04-08T00:37:17.522787Z","steps":["trace[653489324] 'agreement among raft nodes before linearized reading' (duration: 154.892095ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:37:17.577028Z","caller":"traceutil/trace.go:171","msg":"trace[249272040] transaction","detail":"{read_only:false; response_revision:5354; number_of_response:1; }","duration":"165.306305ms","start":"2026-04-08T00:37:17.41169Z","end":"2026-04-08T00:37:17.576997Z","steps":["trace[249272040] 'process raft request' (duration: 165.159892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:37:17.577826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.966689ms","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-04-08T00:37:17.578746Z","caller":"traceutil/trace.go:171","msg":"trace[782831928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5354; }","duration":"210.107012ms","start":"2026-04-08T00:37:17.36779Z","end":"2026-04-08T00:37:17.577897Z","steps":["trace[782831928] 'agreement among raft nodes before linearized reading' (duration: 209.860357ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:37:56.154864Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2910} {"level":"info","ts":"2026-04-08T00:37:56.23636Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2910,"took":"80.343126ms","hash":2811886117,"current-db-size-bytes":53608448,"current-db-size":"54 MB","current-db-size-in-use-bytes":29310976,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-08T00:37:56.236506Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2811886117,"revision":2910,"compact-revision":-1} {"level":"info","ts":"2026-04-08T00:40:00.18473Z","caller":"traceutil/trace.go:171","msg":"trace[450860759] transaction","detail":"{read_only:false; response_revision:6490; number_of_response:1; }","duration":"117.424461ms","start":"2026-04-08T00:40:00.067268Z","end":"2026-04-08T00:40:00.184692Z","steps":["trace[450860759] 'process raft request' (duration: 117.238188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:40:00.752383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.263821ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:40:00.752504Z","caller":"traceutil/trace.go:171","msg":"trace[1385242158] linearizableReadLoop","detail":"{readStateIndex:6724; appliedIndex:6723; }","duration":"307.92742ms","start":"2026-04-08T00:40:00.444561Z","end":"2026-04-08T00:40:00.752489Z","steps":["trace[1385242158] 'read index received' (duration: 174.356604ms)","trace[1385242158] 'applied index is now lower than readState.Index' (duration: 133.569396ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:40:00.752598Z","caller":"traceutil/trace.go:171","msg":"trace[1190347014] transaction","detail":"{read_only:false; response_revision:6495; number_of_response:1; }","duration":"367.003025ms","start":"2026-04-08T00:40:00.385584Z","end":"2026-04-08T00:40:00.752587Z","steps":["trace[1190347014] 'process raft request' (duration: 233.383599ms)","trace[1190347014] 'compare' (duration: 133.062128ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:40:00.752857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:40:00.38556Z","time spent":"367.071536ms","remote":"127.0.0.1:49986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:40:00.752975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.269036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"warn","ts":"2026-04-08T00:40:00.752955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.486464ms","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-04-08T00:40:00.753026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.952768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T00:40:00.753076Z","caller":"traceutil/trace.go:171","msg":"trace[603582347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6495; }","duration":"290.658007ms","start":"2026-04-08T00:40:00.4624Z","end":"2026-04-08T00:40:00.753058Z","steps":["trace[603582347] 'agreement among raft nodes before linearized reading' (duration: 290.322552ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:40:00.753075Z","caller":"traceutil/trace.go:171","msg":"trace[340932669] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:6495; }","duration":"205.03883ms","start":"2026-04-08T00:40:00.548023Z","end":"2026-04-08T00:40:00.753061Z","steps":["trace[340932669] 'agreement among raft nodes before linearized reading' (duration: 204.952838ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:40:00.753025Z","caller":"traceutil/trace.go:171","msg":"trace[366583864] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6495; }","duration":"134.364668ms","start":"2026-04-08T00:40:00.618648Z","end":"2026-04-08T00:40:00.753013Z","steps":["trace[366583864] 'agreement among raft nodes before linearized reading' (duration: 134.241496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:40:00.753366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.790083ms","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-04-08T00:40:00.753429Z","caller":"traceutil/trace.go:171","msg":"trace[1821314947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6495; }","duration":"308.900375ms","start":"2026-04-08T00:40:00.444516Z","end":"2026-04-08T00:40:00.753416Z","steps":["trace[1821314947] 'agreement among raft nodes before linearized reading' (duration: 308.744662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:40:00.753465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:40:00.4445Z","time spent":"308.958375ms","remote":"127.0.0.1:49986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T00:42:56.160403Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5665} {"level":"info","ts":"2026-04-08T00:42:56.241745Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5665,"took":"80.199532ms","hash":2490647481,"current-db-size-bytes":53608448,"current-db-size":"54 MB","current-db-size-in-use-bytes":18391040,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-08T00:42:56.241835Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2490647481,"revision":5665,"compact-revision":2910}