{"level":"warn","ts":"2026-08-22T02:51:46.668132Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T02:51:46.668225Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.181: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.19.213.181:2380","--initial-cluster=instance=https://199.19.213.181:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.181:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.181: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-08-22T02:51:46.668299Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T02:51:46.668314Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.181:2380"]} {"level":"info","ts":"2026-08-22T02:51:46.66834Z","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-08-22T02:51:46.669176Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.181:2379"]} {"level":"info","ts":"2026-08-22T02:51:46.669271Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.181:2380"],"listen-peer-urls":["https://199.19.213.181:2380"],"advertise-client-urls":["https://199.19.213.181:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.181:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.181: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-08-22T02:51:46.672034Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.485248ms"} {"level":"info","ts":"2026-08-22T02:51:46.677011Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1a532052a1358225","cluster-id":"b34324311008a1d3"} {"level":"info","ts":"2026-08-22T02:51:46.677068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 switched to configuration voters=()"} {"level":"info","ts":"2026-08-22T02:51:46.677088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became follower at term 0"} {"level":"info","ts":"2026-08-22T02:51:46.6771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1a532052a1358225 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-22T02:51:46.677109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became follower at term 1"} {"level":"info","ts":"2026-08-22T02:51:46.677141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 switched to configuration voters=(1896895407317156389)"} {"level":"warn","ts":"2026-08-22T02:51:46.681982Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-22T02:51:46.683652Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-22T02:51:46.684861Z","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-08-22T02:51:46.688023Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1a532052a1358225","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-22T02:51:46.688294Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1a532052a1358225","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-22T02:51:46.688415Z","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-08-22T02:51:46.68866Z","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-08-22T02:51:46.688709Z","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-08-22T02:51:46.689799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 switched to configuration voters=(1896895407317156389)"} {"level":"info","ts":"2026-08-22T02:51:46.69204Z","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-08-22T02:51:46.692252Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.181:2380"} {"level":"info","ts":"2026-08-22T02:51:46.692313Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.181:2380"} {"level":"info","ts":"2026-08-22T02:51:46.692337Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1a532052a1358225","initial-advertise-peer-urls":["https://199.19.213.181:2380"],"listen-peer-urls":["https://199.19.213.181:2380"],"advertise-client-urls":["https://199.19.213.181:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.181:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-22T02:51:46.692409Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-22T02:51:46.690263Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b34324311008a1d3","local-member-id":"1a532052a1358225","added-peer-id":"1a532052a1358225","added-peer-peer-urls":["https://199.19.213.181:2380"]} {"level":"info","ts":"2026-08-22T02:51:47.478218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 is starting a new election at term 1"} {"level":"info","ts":"2026-08-22T02:51:47.478278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-22T02:51:47.47831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 received MsgPreVoteResp from 1a532052a1358225 at term 1"} {"level":"info","ts":"2026-08-22T02:51:47.478333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became candidate at term 2"} {"level":"info","ts":"2026-08-22T02:51:47.478351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 received MsgVoteResp from 1a532052a1358225 at term 2"} {"level":"info","ts":"2026-08-22T02:51:47.47837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became leader at term 2"} {"level":"info","ts":"2026-08-22T02:51:47.478389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1a532052a1358225 elected leader 1a532052a1358225 at term 2"} {"level":"info","ts":"2026-08-22T02:51:47.479348Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T02:51:47.480107Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T02:51:47.480116Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1a532052a1358225","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.181:2379]}","request-path":"/0/members/1a532052a1358225/attributes","cluster-id":"b34324311008a1d3","publish-timeout":"7s"} {"level":"info","ts":"2026-08-22T02:51:47.480293Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T02:51:47.480439Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-22T02:51:47.480517Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-22T02:51:47.480662Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b34324311008a1d3","local-member-id":"1a532052a1358225","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T02:51:47.480769Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T02:51:47.480806Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T02:51:47.483673Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-22T02:51:47.484215Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.181:2379"} {"level":"info","ts":"2026-08-22T02:52:30.207315Z","caller":"traceutil/trace.go:171","msg":"trace[1389209509] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"134.853004ms","start":"2026-08-22T02:52:30.07242Z","end":"2026-08-22T02:52:30.207273Z","steps":["trace[1389209509] 'process raft request' (duration: 128.094976ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T02:52:30.418707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.068684ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T02:52:30.418944Z","caller":"traceutil/trace.go:171","msg":"trace[368317953] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"157.075469ms","start":"2026-08-22T02:52:30.261828Z","end":"2026-08-22T02:52:30.418904Z","steps":["trace[368317953] 'process raft request' (duration: 48.436136ms)","trace[368317953] 'compare' (duration: 106.85745ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T02:52:30.918461Z","caller":"traceutil/trace.go:171","msg":"trace[605270234] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"484.45465ms","start":"2026-08-22T02:52:30.43395Z","end":"2026-08-22T02:52:30.918405Z","steps":["trace[605270234] 'process raft request' (duration: 477.496617ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T02:52:30.918971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T02:52:30.433931Z","time spent":"484.640913ms","remote":"127.0.0.1:34282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T02:52:30.919148Z","caller":"traceutil/trace.go:171","msg":"trace[1589124990] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"473.183314ms","start":"2026-08-22T02:52:30.445927Z","end":"2026-08-22T02:52:30.91911Z","steps":["trace[1589124990] 'process raft request' (duration: 472.828607ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T02:52:30.919367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T02:52:30.445908Z","time spent":"473.313457ms","remote":"127.0.0.1:34106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T02:52:30.920251Z","caller":"traceutil/trace.go:171","msg":"trace[1904520274] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:656; }","duration":"182.954173ms","start":"2026-08-22T02:52:30.737258Z","end":"2026-08-22T02:52:30.920212Z","steps":["trace[1904520274] 'read index received' (duration: 174.270507ms)","trace[1904520274] 'applied index is now lower than readState.Index' (duration: 8.682156ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T02:52:30.920547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.259109ms","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-08-22T02:52:30.920589Z","caller":"traceutil/trace.go:171","msg":"trace[1109230980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:635; }","duration":"183.368432ms","start":"2026-08-22T02:52:30.73721Z","end":"2026-08-22T02:52:30.920578Z","steps":["trace[1109230980] 'agreement among raft nodes before linearized reading' (duration: 183.140106ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T02:53:12.630381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.26954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T02:53:12.630483Z","caller":"traceutil/trace.go:171","msg":"trace[994644474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1183; }","duration":"184.417324ms","start":"2026-08-22T02:53:12.446044Z","end":"2026-08-22T02:53:12.630462Z","steps":["trace[994644474] 'range keys from in-memory index tree' (duration: 184.029446ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T02:54:01.095518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.249001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cc6144c6-0b24-4f83-9ddb-b34e1e48e9a5\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-22T02:54:01.095616Z","caller":"traceutil/trace.go:171","msg":"trace[583538374] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cc6144c6-0b24-4f83-9ddb-b34e1e48e9a5; range_end:; response_count:1; response_revision:1852; }","duration":"216.408333ms","start":"2026-08-22T02:54:00.879188Z","end":"2026-08-22T02:54:01.095596Z","steps":["trace[583538374] 'range keys from in-memory index tree' (duration: 216.048327ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T02:54:01.095656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.408929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10668"} {"level":"info","ts":"2026-08-22T02:54:01.095754Z","caller":"traceutil/trace.go:171","msg":"trace[1179846422] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1852; }","duration":"233.556641ms","start":"2026-08-22T02:54:00.862175Z","end":"2026-08-22T02:54:01.095732Z","steps":["trace[1179846422] 'range keys from in-memory index tree' (duration: 233.137043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T02:54:01.095871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.667365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T02:54:01.095965Z","caller":"traceutil/trace.go:171","msg":"trace[443976775] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"157.660072ms","start":"2026-08-22T02:54:00.938286Z","end":"2026-08-22T02:54:01.095946Z","steps":["trace[443976775] 'process raft request' (duration: 47.713312ms)","trace[443976775] 'compare' (duration: 109.540512ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T02:54:52.450119Z","caller":"traceutil/trace.go:171","msg":"trace[571927135] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"113.915661ms","start":"2026-08-22T02:54:52.336173Z","end":"2026-08-22T02:54:52.450089Z","steps":["trace[571927135] 'process raft request' (duration: 113.727208ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T02:54:52.450307Z","caller":"traceutil/trace.go:171","msg":"trace[1435702866] linearizableReadLoop","detail":"{readStateIndex:2267; appliedIndex:2265; }","duration":"111.337093ms","start":"2026-08-22T02:54:52.338915Z","end":"2026-08-22T02:54:52.450252Z","steps":["trace[1435702866] 'read index received' (duration: 53.581258ms)","trace[1435702866] 'applied index is now lower than readState.Index' (duration: 57.753625ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T02:54:52.450483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.553028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-22T02:54:52.450567Z","caller":"traceutil/trace.go:171","msg":"trace[1393098559] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2185; }","duration":"111.67526ms","start":"2026-08-22T02:54:52.33887Z","end":"2026-08-22T02:54:52.450545Z","steps":["trace[1393098559] 'agreement among raft nodes before linearized reading' (duration: 111.489116ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T02:56:21.578939Z","caller":"traceutil/trace.go:171","msg":"trace[1813655355] transaction","detail":"{read_only:false; response_revision:2858; number_of_response:1; }","duration":"239.72339ms","start":"2026-08-22T02:56:21.339189Z","end":"2026-08-22T02:56:21.578913Z","steps":["trace[1813655355] 'process raft request' (duration: 239.577327ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T02:56:21.58466Z","caller":"traceutil/trace.go:171","msg":"trace[1299838609] linearizableReadLoop","detail":"{readStateIndex:2969; appliedIndex:2967; }","duration":"140.674473ms","start":"2026-08-22T02:56:21.443967Z","end":"2026-08-22T02:56:21.584641Z","steps":["trace[1299838609] 'read index received' (duration: 134.772109ms)","trace[1299838609] 'applied index is now lower than readState.Index' (duration: 5.901304ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T02:56:21.584743Z","caller":"traceutil/trace.go:171","msg":"trace[1866518317] transaction","detail":"{read_only:false; response_revision:2859; number_of_response:1; }","duration":"245.49144ms","start":"2026-08-22T02:56:21.339217Z","end":"2026-08-22T02:56:21.584709Z","steps":["trace[1866518317] 'process raft request' (duration: 245.353897ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T02:56:21.584791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.818565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T02:56:21.584945Z","caller":"traceutil/trace.go:171","msg":"trace[1199515407] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2859; }","duration":"141.024929ms","start":"2026-08-22T02:56:21.443908Z","end":"2026-08-22T02:56:21.584933Z","steps":["trace[1199515407] 'agreement among raft nodes before linearized reading' (duration: 140.837636ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:00:13.938265Z","caller":"traceutil/trace.go:171","msg":"trace[36229463] transaction","detail":"{read_only:false; response_revision:5009; number_of_response:1; }","duration":"128.664732ms","start":"2026-08-22T03:00:13.809569Z","end":"2026-08-22T03:00:13.938234Z","steps":["trace[36229463] 'process raft request' (duration: 128.244083ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:00:27.773193Z","caller":"traceutil/trace.go:171","msg":"trace[722767936] transaction","detail":"{read_only:false; response_revision:5260; number_of_response:1; }","duration":"211.903368ms","start":"2026-08-22T03:00:27.561247Z","end":"2026-08-22T03:00:27.773151Z","steps":["trace[722767936] 'process raft request' (duration: 139.409753ms)","trace[722767936] 'compare' (duration: 72.279811ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:00:27.773907Z","caller":"traceutil/trace.go:171","msg":"trace[1251132945] transaction","detail":"{read_only:false; response_revision:5261; number_of_response:1; }","duration":"212.389677ms","start":"2026-08-22T03:00:27.561382Z","end":"2026-08-22T03:00:27.773772Z","steps":["trace[1251132945] 'process raft request' (duration: 211.705544ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:00:27.77469Z","caller":"traceutil/trace.go:171","msg":"trace[240709604] linearizableReadLoop","detail":"{readStateIndex:5463; appliedIndex:5460; }","duration":"116.859389ms","start":"2026-08-22T03:00:27.657802Z","end":"2026-08-22T03:00:27.774661Z","steps":["trace[240709604] 'read index received' (duration: 42.887437ms)","trace[240709604] 'applied index is now lower than readState.Index' (duration: 73.970602ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:00:27.77486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.038353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T03:00:27.774919Z","caller":"traceutil/trace.go:171","msg":"trace[1580508718] range","detail":"{range_begin:/registry/services/specs/openstack/memcached-metrics; range_end:; response_count:0; response_revision:5262; }","duration":"117.146195ms","start":"2026-08-22T03:00:27.657758Z","end":"2026-08-22T03:00:27.774904Z","steps":["trace[1580508718] 'agreement among raft nodes before linearized reading' (duration: 116.993922ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:00:27.77489Z","caller":"traceutil/trace.go:171","msg":"trace[1249958308] transaction","detail":"{read_only:false; response_revision:5262; number_of_response:1; }","duration":"124.499534ms","start":"2026-08-22T03:00:27.650337Z","end":"2026-08-22T03:00:27.774836Z","steps":["trace[1249958308] 'process raft request' (duration: 124.163577ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:00:36.247169Z","caller":"traceutil/trace.go:171","msg":"trace[467628978] linearizableReadLoop","detail":"{readStateIndex:5630; appliedIndex:5629; }","duration":"164.889447ms","start":"2026-08-22T03:00:36.082255Z","end":"2026-08-22T03:00:36.247144Z","steps":["trace[467628978] 'read index received' (duration: 164.656443ms)","trace[467628978] 'applied index is now lower than readState.Index' (duration: 232.144µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:00:36.24736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.205843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1a83ee18-7c5c-4246-8e64-d0f633bb6709\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-08-22T03:00:36.24733Z","caller":"traceutil/trace.go:171","msg":"trace[191684834] transaction","detail":"{read_only:false; response_revision:5427; number_of_response:1; }","duration":"248.190368ms","start":"2026-08-22T03:00:35.999075Z","end":"2026-08-22T03:00:36.247266Z","steps":["trace[191684834] 'process raft request' (duration: 247.912873ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:00:36.247392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.107242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T03:00:36.247528Z","caller":"traceutil/trace.go:171","msg":"trace[1331917978] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:5427; }","duration":"165.300796ms","start":"2026-08-22T03:00:36.082198Z","end":"2026-08-22T03:00:36.247499Z","steps":["trace[1331917978] 'agreement among raft nodes before linearized reading' (duration: 165.061512ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:00:36.247572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.236133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zxfm7\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-22T03:00:36.247606Z","caller":"traceutil/trace.go:171","msg":"trace[1534016453] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zxfm7; range_end:; response_count:1; response_revision:5427; }","duration":"136.291195ms","start":"2026-08-22T03:00:36.111307Z","end":"2026-08-22T03:00:36.247598Z","steps":["trace[1534016453] 'agreement among raft nodes before linearized reading' (duration: 136.144722ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:00:36.247408Z","caller":"traceutil/trace.go:171","msg":"trace[1319902737] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1a83ee18-7c5c-4246-8e64-d0f633bb6709; range_end:; response_count:1; response_revision:5427; }","duration":"156.300975ms","start":"2026-08-22T03:00:36.091095Z","end":"2026-08-22T03:00:36.247396Z","steps":["trace[1319902737] 'agreement among raft nodes before linearized reading' (duration: 156.196553ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:00:36.24781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.284309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-08-22T03:00:36.248033Z","caller":"traceutil/trace.go:171","msg":"trace[808687203] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5427; }","duration":"152.504004ms","start":"2026-08-22T03:00:36.095497Z","end":"2026-08-22T03:00:36.248001Z","steps":["trace[808687203] 'agreement among raft nodes before linearized reading' (duration: 152.248458ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:00:36.388867Z","caller":"traceutil/trace.go:171","msg":"trace[1826054766] transaction","detail":"{read_only:false; response_revision:5428; number_of_response:1; }","duration":"135.857988ms","start":"2026-08-22T03:00:36.252971Z","end":"2026-08-22T03:00:36.388829Z","steps":["trace[1826054766] 'process raft request' (duration: 119.391955ms)","trace[1826054766] 'compare' (duration: 16.30321ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:00:36.388858Z","caller":"traceutil/trace.go:171","msg":"trace[853575985] linearizableReadLoop","detail":"{readStateIndex:5631; appliedIndex:5630; }","duration":"134.752586ms","start":"2026-08-22T03:00:36.254073Z","end":"2026-08-22T03:00:36.388826Z","steps":["trace[853575985] 'read index received' (duration: 118.389845ms)","trace[853575985] 'applied index is now lower than readState.Index' (duration: 16.361241ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:00:36.389294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.182955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80130"} {"level":"info","ts":"2026-08-22T03:00:36.38936Z","caller":"traceutil/trace.go:171","msg":"trace[1718349464] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5428; }","duration":"135.309747ms","start":"2026-08-22T03:00:36.254036Z","end":"2026-08-22T03:00:36.389345Z","steps":["trace[1718349464] 'agreement among raft nodes before linearized reading' (duration: 134.902479ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:00:36.440045Z","caller":"traceutil/trace.go:171","msg":"trace[495066371] transaction","detail":"{read_only:false; response_revision:5429; number_of_response:1; }","duration":"181.661636ms","start":"2026-08-22T03:00:36.258352Z","end":"2026-08-22T03:00:36.440013Z","steps":["trace[495066371] 'process raft request' (duration: 181.474253ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:00:36.596254Z","caller":"traceutil/trace.go:171","msg":"trace[40751832] transaction","detail":"{read_only:false; response_revision:5430; number_of_response:1; }","duration":"147.639081ms","start":"2026-08-22T03:00:36.448578Z","end":"2026-08-22T03:00:36.596218Z","steps":["trace[40751832] 'process raft request' (duration: 56.192796ms)","trace[40751832] 'compare' (duration: 91.17354ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:00:36.596594Z","caller":"traceutil/trace.go:171","msg":"trace[1202411058] transaction","detail":"{read_only:false; response_revision:5431; number_of_response:1; }","duration":"147.445188ms","start":"2026-08-22T03:00:36.449132Z","end":"2026-08-22T03:00:36.596577Z","steps":["trace[1202411058] 'process raft request' (duration: 146.999739ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:00:36.596665Z","caller":"traceutil/trace.go:171","msg":"trace[543430363] linearizableReadLoop","detail":"{readStateIndex:5635; appliedIndex:5632; }","duration":"134.93512ms","start":"2026-08-22T03:00:36.461665Z","end":"2026-08-22T03:00:36.5966Z","steps":["trace[543430363] 'read index received' (duration: 43.381203ms)","trace[543430363] 'applied index is now lower than readState.Index' (duration: 91.552347ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:00:36.596863Z","caller":"traceutil/trace.go:171","msg":"trace[1105495437] transaction","detail":"{read_only:false; response_revision:5432; number_of_response:1; }","duration":"143.077864ms","start":"2026-08-22T03:00:36.453674Z","end":"2026-08-22T03:00:36.596752Z","steps":["trace[1105495437] 'process raft request' (duration: 142.733607ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:00:36.596964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.228475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T03:00:36.597084Z","caller":"traceutil/trace.go:171","msg":"trace[1614450812] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:5432; }","duration":"135.422418ms","start":"2026-08-22T03:00:36.461632Z","end":"2026-08-22T03:00:36.597055Z","steps":["trace[1614450812] 'agreement among raft nodes before linearized reading' (duration: 135.179124ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:01:47.507385Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3001} {"level":"info","ts":"2026-08-22T03:01:47.595887Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3001,"took":"87.250255ms","hash":3322305368,"current-db-size-bytes":58482688,"current-db-size":"58 MB","current-db-size-in-use-bytes":31854592,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T03:01:47.596098Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3322305368,"revision":3001,"compact-revision":-1} {"level":"info","ts":"2026-08-22T03:06:28.959704Z","caller":"traceutil/trace.go:171","msg":"trace[437772540] transaction","detail":"{read_only:false; response_revision:8197; number_of_response:1; }","duration":"271.499266ms","start":"2026-08-22T03:06:28.688173Z","end":"2026-08-22T03:06:28.959672Z","steps":["trace[437772540] 'process raft request' (duration: 271.304713ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:06:28.959903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.07473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-22T03:06:28.959975Z","caller":"traceutil/trace.go:171","msg":"trace[329877003] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8197; }","duration":"270.165182ms","start":"2026-08-22T03:06:28.689793Z","end":"2026-08-22T03:06:28.959959Z","steps":["trace[329877003] 'agreement among raft nodes before linearized reading' (duration: 269.963128ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:06:28.959695Z","caller":"traceutil/trace.go:171","msg":"trace[1306083346] linearizableReadLoop","detail":"{readStateIndex:8486; appliedIndex:8485; }","duration":"269.821826ms","start":"2026-08-22T03:06:28.689809Z","end":"2026-08-22T03:06:28.959631Z","steps":["trace[1306083346] 'read index received' (duration: 269.749734ms)","trace[1306083346] 'applied index is now lower than readState.Index' (duration: 71.092µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:06:29.081405Z","caller":"traceutil/trace.go:171","msg":"trace[868760028] linearizableReadLoop","detail":"{readStateIndex:8487; appliedIndex:8486; }","duration":"121.222419ms","start":"2026-08-22T03:06:28.960157Z","end":"2026-08-22T03:06:29.081379Z","steps":["trace[868760028] 'read index received' (duration: 120.448455ms)","trace[868760028] 'applied index is now lower than readState.Index' (duration: 772.794µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:06:29.081467Z","caller":"traceutil/trace.go:171","msg":"trace[586209299] transaction","detail":"{read_only:false; response_revision:8198; number_of_response:1; }","duration":"345.230619ms","start":"2026-08-22T03:06:28.736176Z","end":"2026-08-22T03:06:29.081406Z","steps":["trace[586209299] 'process raft request' (duration: 344.470465ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:06:29.081556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.323914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T03:06:29.081588Z","caller":"traceutil/trace.go:171","msg":"trace[696337925] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:8198; }","duration":"163.425406ms","start":"2026-08-22T03:06:28.918155Z","end":"2026-08-22T03:06:29.08158Z","steps":["trace[696337925] 'agreement among raft nodes before linearized reading' (duration: 163.319504ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:06:29.081661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:06:28.736155Z","time spent":"345.406862ms","remote":"127.0.0.1:34272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19394,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T03:06:29.085542Z","caller":"traceutil/trace.go:171","msg":"trace[1967144796] transaction","detail":"{read_only:false; response_revision:8199; number_of_response:1; }","duration":"121.081677ms","start":"2026-08-22T03:06:28.964441Z","end":"2026-08-22T03:06:29.085523Z","steps":["trace[1967144796] 'process raft request' (duration: 120.953415ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:06:40.513238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.407536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T03:06:40.513324Z","caller":"traceutil/trace.go:171","msg":"trace[1851738033] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:8262; }","duration":"145.548699ms","start":"2026-08-22T03:06:40.367758Z","end":"2026-08-22T03:06:40.513306Z","steps":["trace[1851738033] 'count revisions from in-memory index tree' (duration: 145.275744ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:06:40.513324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.487296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-08-22T03:06:40.513396Z","caller":"traceutil/trace.go:171","msg":"trace[622355486] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:8262; }","duration":"209.606168ms","start":"2026-08-22T03:06:40.303772Z","end":"2026-08-22T03:06:40.513378Z","steps":["trace[622355486] 'range keys from in-memory index tree' (duration: 209.341643ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:06:41.445724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.224671ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T03:06:41.445964Z","caller":"traceutil/trace.go:171","msg":"trace[1835951106] linearizableReadLoop","detail":"{readStateIndex:8562; appliedIndex:8560; }","duration":"126.58285ms","start":"2026-08-22T03:06:41.319344Z","end":"2026-08-22T03:06:41.445926Z","steps":["trace[1835951106] 'read index received' (duration: 99.381286ms)","trace[1835951106] 'applied index is now lower than readState.Index' (duration: 27.200614ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:06:41.445981Z","caller":"traceutil/trace.go:171","msg":"trace[527283365] transaction","detail":"{read_only:false; response_revision:8268; number_of_response:1; }","duration":"258.577573ms","start":"2026-08-22T03:06:41.187363Z","end":"2026-08-22T03:06:41.44594Z","steps":["trace[527283365] 'process raft request' (duration: 66.034176ms)","trace[527283365] 'compare' (duration: 192.083378ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:06:41.445991Z","caller":"traceutil/trace.go:171","msg":"trace[213000172] transaction","detail":"{read_only:false; response_revision:8269; number_of_response:1; }","duration":"255.648537ms","start":"2026-08-22T03:06:41.190302Z","end":"2026-08-22T03:06:41.445951Z","steps":["trace[213000172] 'process raft request' (duration: 255.563045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:06:41.446155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.818004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-22T03:06:41.446167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.29705ms","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-08-22T03:06:41.446193Z","caller":"traceutil/trace.go:171","msg":"trace[2041877130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8269; }","duration":"126.897066ms","start":"2026-08-22T03:06:41.319287Z","end":"2026-08-22T03:06:41.446184Z","steps":["trace[2041877130] 'agreement among raft nodes before linearized reading' (duration: 126.757414ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:06:41.446215Z","caller":"traceutil/trace.go:171","msg":"trace[1760044771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8269; }","duration":"121.384422ms","start":"2026-08-22T03:06:41.324817Z","end":"2026-08-22T03:06:41.446202Z","steps":["trace[1760044771] 'agreement among raft nodes before linearized reading' (duration: 121.23756ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:06:47.520007Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6105} {"level":"info","ts":"2026-08-22T03:06:47.615979Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6105,"took":"94.084356ms","hash":2362366845,"current-db-size-bytes":58482688,"current-db-size":"58 MB","current-db-size-in-use-bytes":23318528,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-22T03:06:47.616032Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2362366845,"revision":6105,"compact-revision":3001} {"level":"info","ts":"2026-08-22T03:07:11.5908Z","caller":"traceutil/trace.go:171","msg":"trace[616433398] transaction","detail":"{read_only:false; response_revision:8484; number_of_response:1; }","duration":"126.598346ms","start":"2026-08-22T03:07:11.464173Z","end":"2026-08-22T03:07:11.590772Z","steps":["trace[616433398] 'process raft request' (duration: 126.427183ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:07:17.789566Z","caller":"traceutil/trace.go:171","msg":"trace[659422495] transaction","detail":"{read_only:false; response_revision:8517; number_of_response:1; }","duration":"113.379223ms","start":"2026-08-22T03:07:17.676168Z","end":"2026-08-22T03:07:17.789547Z","steps":["trace[659422495] 'process raft request' (duration: 113.24446ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:07:17.871485Z","caller":"traceutil/trace.go:171","msg":"trace[1769354291] linearizableReadLoop","detail":"{readStateIndex:8821; appliedIndex:8819; }","duration":"188.745648ms","start":"2026-08-22T03:07:17.682707Z","end":"2026-08-22T03:07:17.871453Z","steps":["trace[1769354291] 'read index received' (duration: 106.798886ms)","trace[1769354291] 'applied index is now lower than readState.Index' (duration: 81.945342ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:07:17.871556Z","caller":"traceutil/trace.go:171","msg":"trace[1449386416] transaction","detail":"{read_only:false; response_revision:8518; number_of_response:1; }","duration":"194.57303ms","start":"2026-08-22T03:07:17.676961Z","end":"2026-08-22T03:07:17.871534Z","steps":["trace[1449386416] 'process raft request' (duration: 153.732757ms)","trace[1449386416] 'compare' (duration: 40.630099ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:07:17.871754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.88624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-22T03:07:17.871755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.293405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-22T03:07:17.871791Z","caller":"traceutil/trace.go:171","msg":"trace[853392581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8518; }","duration":"129.982832ms","start":"2026-08-22T03:07:17.7418Z","end":"2026-08-22T03:07:17.871783Z","steps":["trace[853392581] 'agreement among raft nodes before linearized reading' (duration: 129.86599ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:07:17.871804Z","caller":"traceutil/trace.go:171","msg":"trace[175985348] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8518; }","duration":"132.367467ms","start":"2026-08-22T03:07:17.739424Z","end":"2026-08-22T03:07:17.871791Z","steps":["trace[175985348] 'agreement among raft nodes before linearized reading' (duration: 132.238375ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:07:17.871807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.494272ms","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-08-22T03:07:17.871896Z","caller":"traceutil/trace.go:171","msg":"trace[1407262141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8518; }","duration":"129.616324ms","start":"2026-08-22T03:07:17.742259Z","end":"2026-08-22T03:07:17.871875Z","steps":["trace[1407262141] 'agreement among raft nodes before linearized reading' (duration: 129.41043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:07:17.871975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.251928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19384"} {"level":"info","ts":"2026-08-22T03:07:17.872016Z","caller":"traceutil/trace.go:171","msg":"trace[1035297970] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8518; }","duration":"189.325339ms","start":"2026-08-22T03:07:17.68268Z","end":"2026-08-22T03:07:17.872005Z","steps":["trace[1035297970] 'agreement among raft nodes before linearized reading' (duration: 188.897671ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:07:19.485441Z","caller":"traceutil/trace.go:171","msg":"trace[1678653051] transaction","detail":"{read_only:false; response_revision:8525; number_of_response:1; }","duration":"118.495921ms","start":"2026-08-22T03:07:19.366906Z","end":"2026-08-22T03:07:19.485402Z","steps":["trace[1678653051] 'process raft request' (duration: 84.0095ms)","trace[1678653051] 'compare' (duration: 34.257367ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:07:22.020373Z","caller":"traceutil/trace.go:171","msg":"trace[1607974927] linearizableReadLoop","detail":"{readStateIndex:8855; appliedIndex:8853; }","duration":"128.695917ms","start":"2026-08-22T03:07:21.891639Z","end":"2026-08-22T03:07:22.020335Z","steps":["trace[1607974927] 'read index received' (duration: 5.19083ms)","trace[1607974927] 'applied index is now lower than readState.Index' (duration: 123.503416ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:07:22.02049Z","caller":"traceutil/trace.go:171","msg":"trace[1422173193] transaction","detail":"{read_only:false; response_revision:8551; number_of_response:1; }","duration":"136.519497ms","start":"2026-08-22T03:07:21.883924Z","end":"2026-08-22T03:07:22.020444Z","steps":["trace[1422173193] 'process raft request' (duration: 109.470398ms)","trace[1422173193] 'compare' (duration: 26.771373ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:07:22.020685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.020312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-22T03:07:22.020764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.543001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-w2gtx\" ","response":"range_response_count:1 size:4645"} {"level":"warn","ts":"2026-08-22T03:07:22.020758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.301046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-22T03:07:22.020774Z","caller":"traceutil/trace.go:171","msg":"trace[1792537585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8551; }","duration":"129.155355ms","start":"2026-08-22T03:07:21.891595Z","end":"2026-08-22T03:07:22.02075Z","steps":["trace[1792537585] 'agreement among raft nodes before linearized reading' (duration: 128.8764ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:07:22.020814Z","caller":"traceutil/trace.go:171","msg":"trace[1014438986] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-w2gtx; range_end:; response_count:1; response_revision:8551; }","duration":"119.642413ms","start":"2026-08-22T03:07:21.901159Z","end":"2026-08-22T03:07:22.020801Z","steps":["trace[1014438986] 'agreement among raft nodes before linearized reading' (duration: 119.400709ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:07:22.020821Z","caller":"traceutil/trace.go:171","msg":"trace[986986314] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8551; }","duration":"108.401977ms","start":"2026-08-22T03:07:21.912407Z","end":"2026-08-22T03:07:22.020809Z","steps":["trace[986986314] 'agreement among raft nodes before linearized reading' (duration: 108.265965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:07:22.020879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.902416ms","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-08-22T03:07:22.02098Z","caller":"traceutil/trace.go:171","msg":"trace[396405564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8551; }","duration":"110.044689ms","start":"2026-08-22T03:07:21.910917Z","end":"2026-08-22T03:07:22.020962Z","steps":["trace[396405564] 'agreement among raft nodes before linearized reading' (duration: 109.771624ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:07:22.164416Z","caller":"traceutil/trace.go:171","msg":"trace[2105778767] transaction","detail":"{read_only:false; response_revision:8552; number_of_response:1; }","duration":"138.130587ms","start":"2026-08-22T03:07:22.026248Z","end":"2026-08-22T03:07:22.164379Z","steps":["trace[2105778767] 'process raft request' (duration: 129.283548ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:07:22.16474Z","caller":"traceutil/trace.go:171","msg":"trace[1537150369] transaction","detail":"{read_only:false; response_revision:8553; number_of_response:1; }","duration":"138.440453ms","start":"2026-08-22T03:07:22.026263Z","end":"2026-08-22T03:07:22.164704Z","steps":["trace[1537150369] 'process raft request' (duration: 138.050116ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:07:22.164858Z","caller":"traceutil/trace.go:171","msg":"trace[1550938683] transaction","detail":"{read_only:false; response_revision:8555; number_of_response:1; }","duration":"136.606997ms","start":"2026-08-22T03:07:22.028225Z","end":"2026-08-22T03:07:22.164832Z","steps":["trace[1550938683] 'process raft request' (duration: 136.528916ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:07:22.164871Z","caller":"traceutil/trace.go:171","msg":"trace[361699513] transaction","detail":"{read_only:false; response_revision:8554; number_of_response:1; }","duration":"136.756871ms","start":"2026-08-22T03:07:22.028086Z","end":"2026-08-22T03:07:22.164843Z","steps":["trace[361699513] 'process raft request' (duration: 136.526187ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:07:49.598373Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023d8.wal"} {"level":"info","ts":"2026-08-22T03:08:44.27342Z","caller":"traceutil/trace.go:171","msg":"trace[876398742] transaction","detail":"{read_only:false; response_revision:9360; number_of_response:1; }","duration":"190.780798ms","start":"2026-08-22T03:08:44.082605Z","end":"2026-08-22T03:08:44.273386Z","steps":["trace[876398742] 'process raft request' (duration: 178.639314ms)","trace[876398742] 'compare' (duration: 11.99361ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:09:24.762816Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1a532052a1358225","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T03:09:24.767068Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-22T03:09:24.767172Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-22T03:11:47.528211Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8309} {"level":"info","ts":"2026-08-22T03:11:47.608057Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8309,"took":"77.271124ms","hash":1480605696,"current-db-size-bytes":58482688,"current-db-size":"58 MB","current-db-size-in-use-bytes":25419776,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-08-22T03:11:47.608138Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1480605696,"revision":8309,"compact-revision":6105} {"level":"info","ts":"2026-08-22T03:16:47.539679Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10800} {"level":"info","ts":"2026-08-22T03:16:47.617812Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10800,"took":"75.682115ms","hash":1636238319,"current-db-size-bytes":58482688,"current-db-size":"58 MB","current-db-size-in-use-bytes":27832320,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-22T03:16:47.617885Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1636238319,"revision":10800,"compact-revision":8309} {"level":"info","ts":"2026-08-22T03:18:10.527407Z","caller":"traceutil/trace.go:171","msg":"trace[2105603289] transaction","detail":"{read_only:false; response_revision:13560; number_of_response:1; }","duration":"100.087247ms","start":"2026-08-22T03:18:10.427291Z","end":"2026-08-22T03:18:10.527379Z","steps":["trace[2105603289] 'process raft request' (duration: 99.905054ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:18:49.78573Z","caller":"traceutil/trace.go:171","msg":"trace[1309509561] linearizableReadLoop","detail":"{readStateIndex:14253; appliedIndex:14252; }","duration":"120.27772ms","start":"2026-08-22T03:18:49.665428Z","end":"2026-08-22T03:18:49.785706Z","steps":["trace[1309509561] 'read index received' (duration: 120.096226ms)","trace[1309509561] 'applied index is now lower than readState.Index' (duration: 180.704µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:18:49.786042Z","caller":"traceutil/trace.go:171","msg":"trace[77923657] transaction","detail":"{read_only:false; response_revision:13783; number_of_response:1; }","duration":"125.038761ms","start":"2026-08-22T03:18:49.66099Z","end":"2026-08-22T03:18:49.786029Z","steps":["trace[77923657] 'process raft request' (duration: 124.593212ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:18:49.786345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.609381ms","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-08-22T03:18:49.786434Z","caller":"traceutil/trace.go:171","msg":"trace[1688212525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13783; }","duration":"116.732303ms","start":"2026-08-22T03:18:49.669682Z","end":"2026-08-22T03:18:49.786414Z","steps":["trace[1688212525] 'agreement among raft nodes before linearized reading' (duration: 116.467628ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:18:49.786486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.056014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-22T03:18:49.786534Z","caller":"traceutil/trace.go:171","msg":"trace[1069389421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13783; }","duration":"121.151066ms","start":"2026-08-22T03:18:49.665373Z","end":"2026-08-22T03:18:49.786524Z","steps":["trace[1069389421] 'agreement among raft nodes before linearized reading' (duration: 121.006943ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:18:49.786658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.932168ms","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-08-22T03:18:49.786693Z","caller":"traceutil/trace.go:171","msg":"trace[1707205916] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13783; }","duration":"114.99077ms","start":"2026-08-22T03:18:49.671694Z","end":"2026-08-22T03:18:49.786685Z","steps":["trace[1707205916] 'agreement among raft nodes before linearized reading' (duration: 114.913228ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:18:50.097026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.958036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T03:18:50.097497Z","caller":"traceutil/trace.go:171","msg":"trace[136555001] transaction","detail":"{read_only:false; response_revision:13785; number_of_response:1; }","duration":"306.990779ms","start":"2026-08-22T03:18:49.790487Z","end":"2026-08-22T03:18:50.097478Z","steps":["trace[136555001] 'process raft request' (duration: 306.878967ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:18:50.097522Z","caller":"traceutil/trace.go:171","msg":"trace[1711031002] transaction","detail":"{read_only:false; response_revision:13784; number_of_response:1; }","duration":"307.5144ms","start":"2026-08-22T03:18:49.789942Z","end":"2026-08-22T03:18:50.097456Z","steps":["trace[1711031002] 'process raft request' (duration: 122.047845ms)","trace[1711031002] 'compare' (duration: 184.805232ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:18:50.097594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:18:49.790463Z","time spent":"307.094111ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T03:18:50.097705Z","caller":"traceutil/trace.go:171","msg":"trace[1988909625] linearizableReadLoop","detail":"{readStateIndex:14256; appliedIndex:14253; }","duration":"193.09033ms","start":"2026-08-22T03:18:49.904601Z","end":"2026-08-22T03:18:50.097691Z","steps":["trace[1988909625] 'read index received' (duration: 7.35715ms)","trace[1988909625] 'applied index is now lower than readState.Index' (duration: 185.73241ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:18:50.097717Z","caller":"traceutil/trace.go:171","msg":"trace[960271185] transaction","detail":"{read_only:false; response_revision:13786; number_of_response:1; }","duration":"305.697994ms","start":"2026-08-22T03:18:49.792009Z","end":"2026-08-22T03:18:50.097707Z","steps":["trace[960271185] 'process raft request' (duration: 305.428879ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:18:50.097715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:18:49.789917Z","time spent":"307.707333ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T03:18:50.097765Z","caller":"traceutil/trace.go:171","msg":"trace[1161910494] transaction","detail":"{read_only:false; response_revision:13787; number_of_response:1; }","duration":"190.179705ms","start":"2026-08-22T03:18:49.907544Z","end":"2026-08-22T03:18:50.097724Z","steps":["trace[1161910494] 'process raft request' (duration: 190.034232ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:18:50.097933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.335994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zxfm7\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-22T03:18:50.098018Z","caller":"traceutil/trace.go:171","msg":"trace[1499646740] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zxfm7; range_end:; response_count:1; response_revision:13787; }","duration":"193.463317ms","start":"2026-08-22T03:18:49.904542Z","end":"2026-08-22T03:18:50.098005Z","steps":["trace[1499646740] 'agreement among raft nodes before linearized reading' (duration: 193.210802ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:18:50.097823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:18:49.791992Z","time spent":"305.752645ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T03:18:58.973328Z","caller":"traceutil/trace.go:171","msg":"trace[1316092009] transaction","detail":"{read_only:false; response_revision:13950; number_of_response:1; }","duration":"152.975189ms","start":"2026-08-22T03:18:58.820322Z","end":"2026-08-22T03:18:58.973297Z","steps":["trace[1316092009] 'process raft request' (duration: 140.193887ms)","trace[1316092009] 'compare' (duration: 12.557568ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:18:59.267319Z","caller":"traceutil/trace.go:171","msg":"trace[1806743969] linearizableReadLoop","detail":"{readStateIndex:14424; appliedIndex:14423; }","duration":"149.561825ms","start":"2026-08-22T03:18:59.117727Z","end":"2026-08-22T03:18:59.267289Z","steps":["trace[1806743969] 'read index received' (duration: 146.347524ms)","trace[1806743969] 'applied index is now lower than readState.Index' (duration: 3.210531ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:18:59.267331Z","caller":"traceutil/trace.go:171","msg":"trace[1760248175] transaction","detail":"{read_only:false; response_revision:13951; number_of_response:1; }","duration":"230.53478ms","start":"2026-08-22T03:18:59.036767Z","end":"2026-08-22T03:18:59.267301Z","steps":["trace[1760248175] 'process raft request' (duration: 227.278398ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:18:59.267586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.856501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c8290bd5-720b-4004-b0ac-d92c630306e6\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-08-22T03:18:59.267655Z","caller":"traceutil/trace.go:171","msg":"trace[457233522] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c8290bd5-720b-4004-b0ac-d92c630306e6; range_end:; response_count:1; response_revision:13951; }","duration":"149.974703ms","start":"2026-08-22T03:18:59.117669Z","end":"2026-08-22T03:18:59.267644Z","steps":["trace[457233522] 'agreement among raft nodes before linearized reading' (duration: 149.702727ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:20:37.706042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.215815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-jkch6\" ","response":"range_response_count:1 size:22125"} {"level":"info","ts":"2026-08-22T03:20:37.706159Z","caller":"traceutil/trace.go:171","msg":"trace[1824636318] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-jkch6; range_end:; response_count:1; response_revision:15034; }","duration":"115.380088ms","start":"2026-08-22T03:20:37.590752Z","end":"2026-08-22T03:20:37.706132Z","steps":["trace[1824636318] 'range keys from in-memory index tree' (duration: 115.036312ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:20:37.706535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.370861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769818"} {"level":"info","ts":"2026-08-22T03:20:37.706636Z","caller":"traceutil/trace.go:171","msg":"trace[755406061] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15034; }","duration":"121.632566ms","start":"2026-08-22T03:20:37.584984Z","end":"2026-08-22T03:20:37.706616Z","steps":["trace[755406061] 'range keys from in-memory index tree' (duration: 120.457634ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:20:37.826468Z","caller":"traceutil/trace.go:171","msg":"trace[285169351] transaction","detail":"{read_only:false; response_revision:15035; number_of_response:1; }","duration":"113.437732ms","start":"2026-08-22T03:20:37.713005Z","end":"2026-08-22T03:20:37.826443Z","steps":["trace[285169351] 'process raft request' (duration: 113.266559ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:20:37.827808Z","caller":"traceutil/trace.go:171","msg":"trace[784212018] linearizableReadLoop","detail":"{readStateIndex:15542; appliedIndex:15542; }","duration":"110.593618ms","start":"2026-08-22T03:20:37.717178Z","end":"2026-08-22T03:20:37.827771Z","steps":["trace[784212018] 'read index received' (duration: 110.581467ms)","trace[784212018] 'applied index is now lower than readState.Index' (duration: 10.211µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:20:37.828072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.869264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-08-22T03:20:37.828112Z","caller":"traceutil/trace.go:171","msg":"trace[452161051] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15035; }","duration":"111.009067ms","start":"2026-08-22T03:20:37.717093Z","end":"2026-08-22T03:20:37.828102Z","steps":["trace[452161051] 'agreement among raft nodes before linearized reading' (duration: 110.819773ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:20:37.828503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.110956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-08-22T03:20:37.828538Z","caller":"traceutil/trace.go:171","msg":"trace[1812977835] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15035; }","duration":"104.162657ms","start":"2026-08-22T03:20:37.724366Z","end":"2026-08-22T03:20:37.828529Z","steps":["trace[1812977835] 'agreement among raft nodes before linearized reading' (duration: 104.056655ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:20:37.963053Z","caller":"traceutil/trace.go:171","msg":"trace[1739749072] transaction","detail":"{read_only:false; response_revision:15036; number_of_response:1; }","duration":"108.981738ms","start":"2026-08-22T03:20:37.854035Z","end":"2026-08-22T03:20:37.963016Z","steps":["trace[1739749072] 'process raft request' (duration: 53.48633ms)","trace[1739749072] 'compare' (duration: 54.939698ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:21:47.545994Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12975} {"level":"info","ts":"2026-08-22T03:21:47.627953Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12975,"took":"79.791288ms","hash":2826928543,"current-db-size-bytes":58482688,"current-db-size":"58 MB","current-db-size-in-use-bytes":31539200,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T03:21:47.628014Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2826928543,"revision":12975,"compact-revision":10800} {"level":"info","ts":"2026-08-22T03:26:17.090253Z","caller":"traceutil/trace.go:171","msg":"trace[1007847584] linearizableReadLoop","detail":"{readStateIndex:18238; appliedIndex:18237; }","duration":"247.906543ms","start":"2026-08-22T03:26:16.842318Z","end":"2026-08-22T03:26:17.090224Z","steps":["trace[1007847584] 'read index received' (duration: 195.589314ms)","trace[1007847584] 'applied index is now lower than readState.Index' (duration: 52.315939ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:26:17.090583Z","caller":"traceutil/trace.go:171","msg":"trace[279129573] transaction","detail":"{read_only:false; response_revision:17645; number_of_response:1; }","duration":"251.160435ms","start":"2026-08-22T03:26:16.839373Z","end":"2026-08-22T03:26:17.090534Z","steps":["trace[279129573] 'process raft request' (duration: 198.490869ms)","trace[279129573] 'compare' (duration: 52.222337ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:26:17.090794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.506898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T03:26:17.090847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.512245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-heat-nodes\" ","response":"range_response_count:1 size:1248"} {"level":"info","ts":"2026-08-22T03:26:17.090907Z","caller":"traceutil/trace.go:171","msg":"trace[1148708155] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:17645; }","duration":"209.63282ms","start":"2026-08-22T03:26:16.881248Z","end":"2026-08-22T03:26:17.090881Z","steps":["trace[1148708155] 'agreement among raft nodes before linearized reading' (duration: 209.370615ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:26:17.09093Z","caller":"traceutil/trace.go:171","msg":"trace[2145409161] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-heat-nodes; range_end:; response_count:1; response_revision:17645; }","duration":"248.621237ms","start":"2026-08-22T03:26:16.84229Z","end":"2026-08-22T03:26:17.090911Z","steps":["trace[2145409161] 'agreement among raft nodes before linearized reading' (duration: 248.327611ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:26:23.839422Z","caller":"traceutil/trace.go:171","msg":"trace[99789251] transaction","detail":"{read_only:false; response_revision:17695; number_of_response:1; }","duration":"123.404511ms","start":"2026-08-22T03:26:23.715979Z","end":"2026-08-22T03:26:23.839383Z","steps":["trace[99789251] 'process raft request' (duration: 123.226937ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:26:24.445963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.807597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"warn","ts":"2026-08-22T03:26:24.445986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.73658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T03:26:24.446015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.543014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T03:26:24.446105Z","caller":"traceutil/trace.go:171","msg":"trace[293149763] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:17697; }","duration":"131.687978ms","start":"2026-08-22T03:26:24.314394Z","end":"2026-08-22T03:26:24.446082Z","steps":["trace[293149763] 'count revisions from in-memory index tree' (duration: 131.395691ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:26:24.446029Z","caller":"traceutil/trace.go:171","msg":"trace[2142287468] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:17697; }","duration":"430.925388ms","start":"2026-08-22T03:26:24.015088Z","end":"2026-08-22T03:26:24.446014Z","steps":["trace[2142287468] 'range keys from in-memory index tree' (duration: 430.687264ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:26:24.446166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.124811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-jkch6\" ","response":"range_response_count:1 size:22126"} {"level":"warn","ts":"2026-08-22T03:26:24.446171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:26:24.015071Z","time spent":"431.081693ms","remote":"127.0.0.1:49202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5675,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-22T03:26:24.446199Z","caller":"traceutil/trace.go:171","msg":"trace[1464139080] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-jkch6; range_end:; response_count:1; response_revision:17697; }","duration":"352.185633ms","start":"2026-08-22T03:26:24.094005Z","end":"2026-08-22T03:26:24.44619Z","steps":["trace[1464139080] 'range keys from in-memory index tree' (duration: 352.014109ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:26:24.446224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:26:24.093987Z","time spent":"352.228953ms","remote":"127.0.0.1:34282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22149,"request content":"key:\"/registry/pods/openstack/nova-compute-default-jkch6\" "} {"level":"info","ts":"2026-08-22T03:26:24.446094Z","caller":"traceutil/trace.go:171","msg":"trace[806720213] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:17697; }","duration":"228.856443ms","start":"2026-08-22T03:26:24.217206Z","end":"2026-08-22T03:26:24.446062Z","steps":["trace[806720213] 'count revisions from in-memory index tree' (duration: 228.577707ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:26:24.446305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.602541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-22T03:26:24.446402Z","caller":"traceutil/trace.go:171","msg":"trace[1253687001] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17697; }","duration":"127.746363ms","start":"2026-08-22T03:26:24.318633Z","end":"2026-08-22T03:26:24.44638Z","steps":["trace[1253687001] 'range keys from in-memory index tree' (duration: 127.417997ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:26:24.446493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.011492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-22T03:26:24.446534Z","caller":"traceutil/trace.go:171","msg":"trace[538407310] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17697; }","duration":"377.093223ms","start":"2026-08-22T03:26:24.069433Z","end":"2026-08-22T03:26:24.446526Z","steps":["trace[538407310] 'range keys from in-memory index tree' (duration: 376.871888ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:26:24.446588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:26:24.069415Z","time spent":"377.166635ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T03:26:24.446666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.622428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T03:26:24.446693Z","caller":"traceutil/trace.go:171","msg":"trace[687479117] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:17697; }","duration":"399.68036ms","start":"2026-08-22T03:26:24.047004Z","end":"2026-08-22T03:26:24.446685Z","steps":["trace[687479117] 'count revisions from in-memory index tree' (duration: 399.486586ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:26:24.446725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:26:24.046983Z","time spent":"399.73045ms","remote":"127.0.0.1:34272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-08-22T03:26:24.44742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.883312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:132 size:1638400"} {"level":"info","ts":"2026-08-22T03:26:24.447523Z","caller":"traceutil/trace.go:171","msg":"trace[923429733] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:132; response_revision:17697; }","duration":"428.013175ms","start":"2026-08-22T03:26:24.019476Z","end":"2026-08-22T03:26:24.447489Z","steps":["trace[923429733] 'range keys from in-memory index tree' (duration: 426.792042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:26:24.447563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:26:24.019461Z","time spent":"428.091506ms","remote":"127.0.0.1:34158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":132,"response size":1638423,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"info","ts":"2026-08-22T03:26:24.45219Z","caller":"traceutil/trace.go:171","msg":"trace[371348424] transaction","detail":"{read_only:false; response_revision:17698; number_of_response:1; }","duration":"103.678928ms","start":"2026-08-22T03:26:24.348487Z","end":"2026-08-22T03:26:24.452166Z","steps":["trace[371348424] 'process raft request' (duration: 103.511605ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:26:24.46776Z","caller":"traceutil/trace.go:171","msg":"trace[2092559290] transaction","detail":"{read_only:false; response_revision:17699; number_of_response:1; }","duration":"118.333225ms","start":"2026-08-22T03:26:24.34939Z","end":"2026-08-22T03:26:24.467723Z","steps":["trace[2092559290] 'process raft request' (duration: 117.794095ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:26:47.554423Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15493} {"level":"info","ts":"2026-08-22T03:26:47.657534Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15493,"took":"100.112101ms","hash":3276176598,"current-db-size-bytes":58482688,"current-db-size":"58 MB","current-db-size-in-use-bytes":33918976,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-22T03:26:47.657603Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3276176598,"revision":15493,"compact-revision":12975} {"level":"info","ts":"2026-08-22T03:29:47.541925Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1a532052a1358225","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T03:29:47.546793Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-22T03:29:47.546868Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-22T03:31:13.588841Z","caller":"traceutil/trace.go:171","msg":"trace[1124624380] transaction","detail":"{read_only:false; response_revision:19982; number_of_response:1; }","duration":"116.376068ms","start":"2026-08-22T03:31:13.472348Z","end":"2026-08-22T03:31:13.588724Z","steps":["trace[1124624380] 'process raft request' (duration: 116.125352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:31:25.342714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.8328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T03:31:25.342853Z","caller":"traceutil/trace.go:171","msg":"trace[1551121762] transaction","detail":"{read_only:false; response_revision:20042; number_of_response:1; }","duration":"309.567607ms","start":"2026-08-22T03:31:25.033255Z","end":"2026-08-22T03:31:25.342822Z","steps":["trace[1551121762] 'process raft request' (duration: 198.540224ms)","trace[1551121762] 'compare' (duration: 110.703387ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:31:25.342993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:31:25.033233Z","time spent":"309.683239ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T03:31:47.562845Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18079} {"level":"info","ts":"2026-08-22T03:31:47.683215Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18079,"took":"117.325006ms","hash":493042820,"current-db-size-bytes":58482688,"current-db-size":"58 MB","current-db-size-in-use-bytes":29499392,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-22T03:31:47.683299Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":493042820,"revision":18079,"compact-revision":15493} {"level":"info","ts":"2026-08-22T03:35:03.617904Z","caller":"traceutil/trace.go:171","msg":"trace[1845770645] transaction","detail":"{read_only:false; response_revision:22317; number_of_response:1; }","duration":"116.528794ms","start":"2026-08-22T03:35:03.501351Z","end":"2026-08-22T03:35:03.61788Z","steps":["trace[1845770645] 'process raft request' (duration: 116.342741ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:36:47.570722Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20159} {"level":"info","ts":"2026-08-22T03:36:47.681807Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20159,"took":"107.199808ms","hash":193412783,"current-db-size-bytes":58482688,"current-db-size":"58 MB","current-db-size-in-use-bytes":43589632,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-22T03:36:47.681889Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":193412783,"revision":20159,"compact-revision":18079} {"level":"info","ts":"2026-08-22T03:38:25.702686Z","caller":"traceutil/trace.go:171","msg":"trace[2083659405] linearizableReadLoop","detail":"{readStateIndex:25232; appliedIndex:25231; }","duration":"135.090518ms","start":"2026-08-22T03:38:25.567561Z","end":"2026-08-22T03:38:25.702652Z","steps":["trace[2083659405] 'read index received' (duration: 134.890264ms)","trace[2083659405] 'applied index is now lower than readState.Index' (duration: 198.814µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:38:25.702801Z","caller":"traceutil/trace.go:171","msg":"trace[2144854738] transaction","detail":"{read_only:false; response_revision:24439; number_of_response:1; }","duration":"135.662759ms","start":"2026-08-22T03:38:25.567092Z","end":"2026-08-22T03:38:25.702755Z","steps":["trace[2144854738] 'process raft request' (duration: 135.338243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:25.702999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.413214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-08-22T03:38:25.703068Z","caller":"traceutil/trace.go:171","msg":"trace[1612379391] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24439; }","duration":"135.547096ms","start":"2026-08-22T03:38:25.567506Z","end":"2026-08-22T03:38:25.703054Z","steps":["trace[1612379391] 'agreement among raft nodes before linearized reading' (duration: 135.312332ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:25.916411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.889081ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-22T03:38:25.916504Z","caller":"traceutil/trace.go:171","msg":"trace[1926150199] linearizableReadLoop","detail":"{readStateIndex:25233; appliedIndex:25232; }","duration":"213.713062ms","start":"2026-08-22T03:38:25.702775Z","end":"2026-08-22T03:38:25.916488Z","steps":["trace[1926150199] 'read index received' (duration: 106.762169ms)","trace[1926150199] 'applied index is now lower than readState.Index' (duration: 106.949643ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:38:25.916704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.285991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"warn","ts":"2026-08-22T03:38:25.916658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.476895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T03:38:25.916736Z","caller":"traceutil/trace.go:171","msg":"trace[2009231902] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24439; }","duration":"205.327532ms","start":"2026-08-22T03:38:25.7114Z","end":"2026-08-22T03:38:25.916727Z","steps":["trace[2009231902] 'agreement among raft nodes before linearized reading' (duration: 205.167409ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:25.916774Z","caller":"traceutil/trace.go:171","msg":"trace[1114818932] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:24439; }","duration":"286.682879ms","start":"2026-08-22T03:38:25.630069Z","end":"2026-08-22T03:38:25.916751Z","steps":["trace[1114818932] 'agreement among raft nodes before linearized reading' (duration: 286.466975ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:26.520021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.874191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T03:38:26.520282Z","caller":"traceutil/trace.go:171","msg":"trace[1262833519] transaction","detail":"{read_only:false; response_revision:24442; number_of_response:1; }","duration":"153.06627ms","start":"2026-08-22T03:38:26.367196Z","end":"2026-08-22T03:38:26.520263Z","steps":["trace[1262833519] 'process raft request' (duration: 152.942888ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:26.520388Z","caller":"traceutil/trace.go:171","msg":"trace[2004357329] linearizableReadLoop","detail":"{readStateIndex:25235; appliedIndex:25234; }","duration":"398.987084ms","start":"2026-08-22T03:38:26.121312Z","end":"2026-08-22T03:38:26.520299Z","steps":["trace[2004357329] 'read index received' (duration: 129.650595ms)","trace[2004357329] 'applied index is now lower than readState.Index' (duration: 269.335189ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:38:26.520547Z","caller":"traceutil/trace.go:171","msg":"trace[1556000082] transaction","detail":"{read_only:false; response_revision:24441; number_of_response:1; }","duration":"452.676205ms","start":"2026-08-22T03:38:26.067823Z","end":"2026-08-22T03:38:26.520499Z","steps":["trace[1556000082] 'process raft request' (duration: 183.221313ms)","trace[1556000082] 'compare' (duration: 267.756309ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:38:26.520617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.268769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T03:38:26.520741Z","caller":"traceutil/trace.go:171","msg":"trace[1812608026] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:24442; }","duration":"399.460923ms","start":"2026-08-22T03:38:26.121267Z","end":"2026-08-22T03:38:26.520728Z","steps":["trace[1812608026] 'agreement among raft nodes before linearized reading' (duration: 399.247199ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:26.520775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:26.0678Z","time spent":"452.850417ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T03:38:26.520805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:26.121252Z","time spent":"399.539834ms","remote":"127.0.0.1:35724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":29,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"warn","ts":"2026-08-22T03:38:26.852399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.060661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T03:38:26.852513Z","caller":"traceutil/trace.go:171","msg":"trace[517397845] linearizableReadLoop","detail":"{readStateIndex:25237; appliedIndex:25236; }","duration":"332.01397ms","start":"2026-08-22T03:38:26.52048Z","end":"2026-08-22T03:38:26.852494Z","steps":["trace[517397845] 'read index received' (duration: 231.767696ms)","trace[517397845] 'applied index is now lower than readState.Index' (duration: 100.245074ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:38:26.852744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.667012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-22T03:38:26.852806Z","caller":"traceutil/trace.go:171","msg":"trace[1500589929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24443; }","duration":"625.755074ms","start":"2026-08-22T03:38:26.22704Z","end":"2026-08-22T03:38:26.852795Z","steps":["trace[1500589929] 'agreement among raft nodes before linearized reading' (duration: 625.55493ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:26.852845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:26.227029Z","time spent":"625.806984ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-22T03:38:26.852876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.330162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T03:38:26.852855Z","caller":"traceutil/trace.go:171","msg":"trace[2103701469] transaction","detail":"{read_only:false; response_revision:24443; number_of_response:1; }","duration":"436.942445ms","start":"2026-08-22T03:38:26.415867Z","end":"2026-08-22T03:38:26.852809Z","steps":["trace[2103701469] 'process raft request' (duration: 336.367924ms)","trace[2103701469] 'compare' (duration: 99.927149ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:38:26.852976Z","caller":"traceutil/trace.go:171","msg":"trace[1033572947] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:24443; }","duration":"720.452604ms","start":"2026-08-22T03:38:26.132506Z","end":"2026-08-22T03:38:26.852958Z","steps":["trace[1033572947] 'agreement among raft nodes before linearized reading' (duration: 720.25875ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:26.852945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.48909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-08-22T03:38:26.853017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:26.132478Z","time spent":"720.527316ms","remote":"127.0.0.1:56948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-22T03:38:26.853004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.227359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-08-22T03:38:26.85307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:26.415843Z","time spent":"437.144049ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T03:38:26.853073Z","caller":"traceutil/trace.go:171","msg":"trace[1459638899] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24443; }","duration":"714.690644ms","start":"2026-08-22T03:38:26.138351Z","end":"2026-08-22T03:38:26.853042Z","steps":["trace[1459638899] 'agreement among raft nodes before linearized reading' (duration: 714.278296ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:26.853105Z","caller":"traceutil/trace.go:171","msg":"trace[1221414581] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24443; }","duration":"710.369132ms","start":"2026-08-22T03:38:26.142717Z","end":"2026-08-22T03:38:26.853086Z","steps":["trace[1221414581] 'agreement among raft nodes before linearized reading' (duration: 710.050825ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:26.853003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.055536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/glance-api-6747647989-zdkxv.18ce01f34a66dc15\" ","response":"range_response_count:1 size:2107"} {"level":"warn","ts":"2026-08-22T03:38:26.853164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:26.142707Z","time spent":"710.443333ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T03:38:26.853315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.311158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-22T03:38:26.853287Z","caller":"traceutil/trace.go:171","msg":"trace[2052807829] range","detail":"{range_begin:/registry/events/openstack/glance-api-6747647989-zdkxv.18ce01f34a66dc15; range_end:; response_count:1; response_revision:24443; }","duration":"580.340801ms","start":"2026-08-22T03:38:26.272864Z","end":"2026-08-22T03:38:26.853204Z","steps":["trace[2052807829] 'agreement among raft nodes before linearized reading' (duration: 579.953013ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:26.853142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:26.138342Z","time spent":"714.782497ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-22T03:38:26.853401Z","caller":"traceutil/trace.go:171","msg":"trace[588519110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24443; }","duration":"623.42566ms","start":"2026-08-22T03:38:26.229954Z","end":"2026-08-22T03:38:26.85338Z","steps":["trace[588519110] 'agreement among raft nodes before linearized reading' (duration: 622.604993ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:26.853551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:26.27284Z","time spent":"580.654267ms","remote":"127.0.0.1:34144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":2130,"request content":"key:\"/registry/events/openstack/glance-api-6747647989-zdkxv.18ce01f34a66dc15\" "} {"level":"warn","ts":"2026-08-22T03:38:26.853658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:26.229929Z","time spent":"623.680655ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-22T03:38:27.115533Z","caller":"traceutil/trace.go:171","msg":"trace[1435115212] linearizableReadLoop","detail":"{readStateIndex:25238; appliedIndex:25237; }","duration":"262.965528ms","start":"2026-08-22T03:38:26.852542Z","end":"2026-08-22T03:38:27.115507Z","steps":["trace[1435115212] 'read index received' (duration: 226.813871ms)","trace[1435115212] 'applied index is now lower than readState.Index' (duration: 36.150487ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:38:27.115702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.0041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T03:38:27.11574Z","caller":"traceutil/trace.go:171","msg":"trace[825121703] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:24444; }","duration":"574.086361ms","start":"2026-08-22T03:38:26.541639Z","end":"2026-08-22T03:38:27.115725Z","steps":["trace[825121703] 'agreement among raft nodes before linearized reading' (duration: 573.969879ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:27.115775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:26.541619Z","time spent":"574.145333ms","remote":"127.0.0.1:48894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":3,"response size":31,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"info","ts":"2026-08-22T03:38:27.11586Z","caller":"traceutil/trace.go:171","msg":"trace[1110307123] transaction","detail":"{read_only:false; response_revision:24444; number_of_response:1; }","duration":"572.906099ms","start":"2026-08-22T03:38:26.542919Z","end":"2026-08-22T03:38:27.115825Z","steps":["trace[1110307123] 'process raft request' (duration: 536.425366ms)","trace[1110307123] 'compare' (duration: 36.053675ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:38:27.115967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.068138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T03:38:27.116004Z","caller":"traceutil/trace.go:171","msg":"trace[1339922890] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:24444; }","duration":"457.138488ms","start":"2026-08-22T03:38:26.658852Z","end":"2026-08-22T03:38:27.11599Z","steps":["trace[1339922890] 'agreement among raft nodes before linearized reading' (duration: 457.080137ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:27.116015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:26.542909Z","time spent":"573.016781ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T03:38:27.116048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:26.658836Z","time spent":"457.19476ms","remote":"127.0.0.1:41842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"info","ts":"2026-08-22T03:38:27.290175Z","caller":"traceutil/trace.go:171","msg":"trace[248068586] transaction","detail":"{read_only:false; response_revision:24445; number_of_response:1; }","duration":"433.966408ms","start":"2026-08-22T03:38:26.856175Z","end":"2026-08-22T03:38:27.290141Z","steps":["trace[248068586] 'process raft request' (duration: 433.703172ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:27.290368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:26.856146Z","time spent":"434.108071ms","remote":"127.0.0.1:34144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2091,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T03:38:27.290514Z","caller":"traceutil/trace.go:171","msg":"trace[1791902059] transaction","detail":"{read_only:false; response_revision:24446; number_of_response:1; }","duration":"430.868029ms","start":"2026-08-22T03:38:26.859607Z","end":"2026-08-22T03:38:27.290475Z","steps":["trace[1791902059] 'process raft request' (duration: 430.436001ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:27.290558Z","caller":"traceutil/trace.go:171","msg":"trace[649400061] transaction","detail":"{read_only:false; response_revision:24448; number_of_response:1; }","duration":"430.697966ms","start":"2026-08-22T03:38:26.859832Z","end":"2026-08-22T03:38:27.29053Z","steps":["trace[649400061] 'process raft request' (duration: 430.630955ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:27.290706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:26.859798Z","time spent":"430.834709ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T03:38:27.290711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:26.859574Z","time spent":"431.025573ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T03:38:27.290738Z","caller":"traceutil/trace.go:171","msg":"trace[1825738025] transaction","detail":"{read_only:false; response_revision:24447; number_of_response:1; }","duration":"431.007642ms","start":"2026-08-22T03:38:26.859717Z","end":"2026-08-22T03:38:27.290725Z","steps":["trace[1825738025] 'process raft request' (duration: 430.618475ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:27.290813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:26.859695Z","time spent":"431.084504ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T03:38:27.290829Z","caller":"traceutil/trace.go:171","msg":"trace[1069579163] transaction","detail":"{read_only:false; response_revision:24450; number_of_response:1; }","duration":"250.355929ms","start":"2026-08-22T03:38:27.040466Z","end":"2026-08-22T03:38:27.290822Z","steps":["trace[1069579163] 'process raft request' (duration: 250.167745ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:27.290726Z","caller":"traceutil/trace.go:171","msg":"trace[230477127] transaction","detail":"{read_only:false; response_revision:24449; number_of_response:1; }","duration":"430.85009ms","start":"2026-08-22T03:38:26.859855Z","end":"2026-08-22T03:38:27.290705Z","steps":["trace[230477127] 'process raft request' (duration: 430.641456ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:27.291061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:26.859838Z","time spent":"431.109924ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T03:38:27.596369Z","caller":"traceutil/trace.go:171","msg":"trace[1398864474] transaction","detail":"{read_only:false; response_revision:24453; number_of_response:1; }","duration":"364.827583ms","start":"2026-08-22T03:38:27.231513Z","end":"2026-08-22T03:38:27.59634Z","steps":["trace[1398864474] 'process raft request' (duration: 364.730182ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:27.596404Z","caller":"traceutil/trace.go:171","msg":"trace[1262284006] transaction","detail":"{read_only:false; response_revision:24451; number_of_response:1; }","duration":"385.312944ms","start":"2026-08-22T03:38:27.211048Z","end":"2026-08-22T03:38:27.596361Z","steps":["trace[1262284006] 'process raft request' (duration: 384.998018ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:27.596573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:27.211015Z","time spent":"385.457707ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T03:38:27.596567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:27.231505Z","time spent":"364.951786ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T03:38:27.596574Z","caller":"traceutil/trace.go:171","msg":"trace[1378342278] linearizableReadLoop","detail":"{readStateIndex:25245; appliedIndex:25238; }","duration":"382.946128ms","start":"2026-08-22T03:38:27.213605Z","end":"2026-08-22T03:38:27.596551Z","steps":["trace[1378342278] 'read index received' (duration: 76.271389ms)","trace[1378342278] 'applied index is now lower than readState.Index' (duration: 306.672959ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:38:27.596556Z","caller":"traceutil/trace.go:171","msg":"trace[821194039] transaction","detail":"{read_only:false; response_revision:24452; number_of_response:1; }","duration":"371.240607ms","start":"2026-08-22T03:38:27.225209Z","end":"2026-08-22T03:38:27.596449Z","steps":["trace[821194039] 'process raft request' (duration: 370.986892ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:27.596679Z","caller":"traceutil/trace.go:171","msg":"trace[696280634] transaction","detail":"{read_only:false; response_revision:24454; number_of_response:1; }","duration":"361.939439ms","start":"2026-08-22T03:38:27.234728Z","end":"2026-08-22T03:38:27.596667Z","steps":["trace[696280634] 'process raft request' (duration: 361.565242ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:27.596762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:27.234698Z","time spent":"362.01047ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T03:38:27.596772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.158192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T03:38:27.596811Z","caller":"traceutil/trace.go:171","msg":"trace[1114082428] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:24454; }","duration":"383.230054ms","start":"2026-08-22T03:38:27.213569Z","end":"2026-08-22T03:38:27.596799Z","steps":["trace[1114082428] 'agreement among raft nodes before linearized reading' (duration: 383.132622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:27.596842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:27.213557Z","time spent":"383.276765ms","remote":"127.0.0.1:37496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true "} {"level":"warn","ts":"2026-08-22T03:38:27.596971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:27.225195Z","time spent":"371.542892ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T03:38:27.599359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.342113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T03:38:27.599562Z","caller":"traceutil/trace.go:171","msg":"trace[1151847459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24454; }","duration":"156.838811ms","start":"2026-08-22T03:38:27.442634Z","end":"2026-08-22T03:38:27.599473Z","steps":["trace[1151847459] 'agreement among raft nodes before linearized reading' (duration: 154.211091ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:28.66911Z","caller":"traceutil/trace.go:171","msg":"trace[17820784] transaction","detail":"{read_only:false; response_revision:24457; number_of_response:1; }","duration":"143.421656ms","start":"2026-08-22T03:38:28.525653Z","end":"2026-08-22T03:38:28.669074Z","steps":["trace[17820784] 'process raft request' (duration: 143.070559ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:29.096247Z","caller":"traceutil/trace.go:171","msg":"trace[965111255] transaction","detail":"{read_only:false; response_revision:24458; number_of_response:1; }","duration":"234.292503ms","start":"2026-08-22T03:38:28.861911Z","end":"2026-08-22T03:38:29.096203Z","steps":["trace[965111255] 'process raft request' (duration: 234.027968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:29.302132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.605147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"info","ts":"2026-08-22T03:38:29.302212Z","caller":"traceutil/trace.go:171","msg":"trace[561087913] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24459; }","duration":"146.71516ms","start":"2026-08-22T03:38:29.155479Z","end":"2026-08-22T03:38:29.302194Z","steps":["trace[561087913] 'range keys from in-memory index tree' (duration: 146.401644ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:29.50423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.699853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T03:38:29.504334Z","caller":"traceutil/trace.go:171","msg":"trace[216164719] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24464; }","duration":"102.841805ms","start":"2026-08-22T03:38:29.401464Z","end":"2026-08-22T03:38:29.504305Z","steps":["trace[216164719] 'range keys from in-memory index tree' (duration: 102.620291ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:30.528888Z","caller":"traceutil/trace.go:171","msg":"trace[923481370] transaction","detail":"{read_only:false; response_revision:24469; number_of_response:1; }","duration":"307.951373ms","start":"2026-08-22T03:38:30.220918Z","end":"2026-08-22T03:38:30.528869Z","steps":["trace[923481370] 'process raft request' (duration: 307.81333ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:30.52906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:30.220895Z","time spent":"308.079436ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T03:38:30.773948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.204537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.181\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-22T03:38:30.774035Z","caller":"traceutil/trace.go:171","msg":"trace[643797347] range","detail":"{range_begin:/registry/masterleases/199.19.213.181; range_end:; response_count:1; response_revision:24469; }","duration":"209.34964ms","start":"2026-08-22T03:38:30.564664Z","end":"2026-08-22T03:38:30.774014Z","steps":["trace[643797347] 'range keys from in-memory index tree' (duration: 208.872649ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:30.780551Z","caller":"traceutil/trace.go:171","msg":"trace[1820487856] transaction","detail":"{read_only:false; response_revision:24470; number_of_response:1; }","duration":"105.053237ms","start":"2026-08-22T03:38:30.675479Z","end":"2026-08-22T03:38:30.780532Z","steps":["trace[1820487856] 'process raft request' (duration: 104.898533ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:31.312234Z","caller":"traceutil/trace.go:171","msg":"trace[793258404] transaction","detail":"{read_only:false; response_revision:24473; number_of_response:1; }","duration":"155.500576ms","start":"2026-08-22T03:38:31.15669Z","end":"2026-08-22T03:38:31.312191Z","steps":["trace[793258404] 'process raft request' (duration: 115.20332ms)","trace[793258404] 'compare' (duration: 39.45284ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:38:31.590134Z","caller":"traceutil/trace.go:171","msg":"trace[1776602513] linearizableReadLoop","detail":"{readStateIndex:25269; appliedIndex:25268; }","duration":"267.23545ms","start":"2026-08-22T03:38:31.322872Z","end":"2026-08-22T03:38:31.590107Z","steps":["trace[1776602513] 'read index received' (duration: 236.567287ms)","trace[1776602513] 'applied index is now lower than readState.Index' (duration: 30.667083ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:38:31.590197Z","caller":"traceutil/trace.go:171","msg":"trace[803792285] transaction","detail":"{read_only:false; response_revision:24474; number_of_response:1; }","duration":"280.817679ms","start":"2026-08-22T03:38:31.30934Z","end":"2026-08-22T03:38:31.590158Z","steps":["trace[803792285] 'process raft request' (duration: 250.073294ms)","trace[803792285] 'compare' (duration: 30.54451ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:38:31.590379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.659367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-22T03:38:31.590425Z","caller":"traceutil/trace.go:171","msg":"trace[538059993] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24474; }","duration":"197.80796ms","start":"2026-08-22T03:38:31.392604Z","end":"2026-08-22T03:38:31.590412Z","steps":["trace[538059993] 'agreement among raft nodes before linearized reading' (duration: 197.650068ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:31.590424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.524556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-08-22T03:38:31.590476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.665047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T03:38:31.590517Z","caller":"traceutil/trace.go:171","msg":"trace[1800966851] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24474; }","duration":"267.673058ms","start":"2026-08-22T03:38:31.322823Z","end":"2026-08-22T03:38:31.590496Z","steps":["trace[1800966851] 'agreement among raft nodes before linearized reading' (duration: 267.420153ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:31.590562Z","caller":"traceutil/trace.go:171","msg":"trace[495852781] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24474; }","duration":"147.82547ms","start":"2026-08-22T03:38:31.44272Z","end":"2026-08-22T03:38:31.590545Z","steps":["trace[495852781] 'agreement among raft nodes before linearized reading' (duration: 147.660037ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:31.590394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.556168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T03:38:31.590676Z","caller":"traceutil/trace.go:171","msg":"trace[1138951459] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:24474; }","duration":"104.887974ms","start":"2026-08-22T03:38:31.485764Z","end":"2026-08-22T03:38:31.590652Z","steps":["trace[1138951459] 'agreement among raft nodes before linearized reading' (duration: 104.507887ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:31.590736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.818622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-22T03:38:31.590791Z","caller":"traceutil/trace.go:171","msg":"trace[1386456219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24474; }","duration":"196.905243ms","start":"2026-08-22T03:38:31.393873Z","end":"2026-08-22T03:38:31.590778Z","steps":["trace[1386456219] 'agreement among raft nodes before linearized reading' (duration: 196.71605ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:31.590799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.824041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-22T03:38:31.590939Z","caller":"traceutil/trace.go:171","msg":"trace[1403041061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24474; }","duration":"197.008405ms","start":"2026-08-22T03:38:31.393878Z","end":"2026-08-22T03:38:31.590886Z","steps":["trace[1403041061] 'agreement among raft nodes before linearized reading' (duration: 196.457455ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:31.776443Z","caller":"traceutil/trace.go:171","msg":"trace[184414366] transaction","detail":"{read_only:false; response_revision:24475; number_of_response:1; }","duration":"180.321967ms","start":"2026-08-22T03:38:31.59608Z","end":"2026-08-22T03:38:31.776402Z","steps":["trace[184414366] 'process raft request' (duration: 136.877912ms)","trace[184414366] 'compare' (duration: 43.121079ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:38:31.811624Z","caller":"traceutil/trace.go:171","msg":"trace[493141668] transaction","detail":"{read_only:false; response_revision:24476; number_of_response:1; }","duration":"215.543627ms","start":"2026-08-22T03:38:31.596053Z","end":"2026-08-22T03:38:31.811596Z","steps":["trace[493141668] 'process raft request' (duration: 215.289242ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:31.81168Z","caller":"traceutil/trace.go:171","msg":"trace[562233782] transaction","detail":"{read_only:false; response_revision:24477; number_of_response:1; }","duration":"215.600649ms","start":"2026-08-22T03:38:31.596054Z","end":"2026-08-22T03:38:31.811655Z","steps":["trace[562233782] 'process raft request' (duration: 215.439356ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:31.811662Z","caller":"traceutil/trace.go:171","msg":"trace[2057632942] transaction","detail":"{read_only:false; response_revision:24478; number_of_response:1; }","duration":"215.453655ms","start":"2026-08-22T03:38:31.596178Z","end":"2026-08-22T03:38:31.811632Z","steps":["trace[2057632942] 'process raft request' (duration: 215.358353ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:31.811928Z","caller":"traceutil/trace.go:171","msg":"trace[2053865014] transaction","detail":"{read_only:false; response_revision:24479; number_of_response:1; }","duration":"177.621416ms","start":"2026-08-22T03:38:31.634275Z","end":"2026-08-22T03:38:31.811897Z","steps":["trace[2053865014] 'process raft request' (duration: 177.30291ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:31.812054Z","caller":"traceutil/trace.go:171","msg":"trace[11515812] transaction","detail":"{read_only:false; response_revision:24480; number_of_response:1; }","duration":"105.367963ms","start":"2026-08-22T03:38:31.706655Z","end":"2026-08-22T03:38:31.812023Z","steps":["trace[11515812] 'process raft request' (duration: 105.139149ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:31.812058Z","caller":"traceutil/trace.go:171","msg":"trace[1345703623] transaction","detail":"{read_only:false; response_revision:24481; number_of_response:1; }","duration":"103.222083ms","start":"2026-08-22T03:38:31.708807Z","end":"2026-08-22T03:38:31.812029Z","steps":["trace[1345703623] 'process raft request' (duration: 103.09151ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:32.194235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.999551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T03:38:32.194615Z","caller":"traceutil/trace.go:171","msg":"trace[822436538] transaction","detail":"{read_only:false; response_revision:24482; number_of_response:1; }","duration":"330.41693ms","start":"2026-08-22T03:38:31.864177Z","end":"2026-08-22T03:38:32.194594Z","steps":["trace[822436538] 'process raft request' (duration: 18.987011ms)","trace[822436538] 'compare' (duration: 309.630755ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:38:32.194685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:31.86416Z","time spent":"330.493651ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T03:38:41.911586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.306444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T03:38:41.911675Z","caller":"traceutil/trace.go:171","msg":"trace[394960117] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24550; }","duration":"466.410296ms","start":"2026-08-22T03:38:41.44525Z","end":"2026-08-22T03:38:41.911661Z","steps":["trace[394960117] 'range keys from in-memory index tree' (duration: 466.224061ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:41.911708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:41.445191Z","time spent":"466.509418ms","remote":"127.0.0.1:34058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T03:38:41.913174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.762952ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T03:38:41.91327Z","caller":"traceutil/trace.go:171","msg":"trace[2031486269] transaction","detail":"{read_only:false; response_revision:24551; number_of_response:1; }","duration":"379.233009ms","start":"2026-08-22T03:38:41.534021Z","end":"2026-08-22T03:38:41.913254Z","steps":["trace[2031486269] 'process raft request' (duration: 175.300152ms)","trace[2031486269] 'compare' (duration: 202.56863ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:38:41.913328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:41.533995Z","time spent":"379.314001ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T03:38:41.977171Z","caller":"traceutil/trace.go:171","msg":"trace[1278463971] linearizableReadLoop","detail":"{readStateIndex:25349; appliedIndex:25347; }","duration":"327.058415ms","start":"2026-08-22T03:38:41.650085Z","end":"2026-08-22T03:38:41.977144Z","steps":["trace[1278463971] 'read index received' (duration: 59.272806ms)","trace[1278463971] 'applied index is now lower than readState.Index' (duration: 267.784549ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:38:41.977474Z","caller":"traceutil/trace.go:171","msg":"trace[1890214325] transaction","detail":"{read_only:false; response_revision:24552; number_of_response:1; }","duration":"341.675423ms","start":"2026-08-22T03:38:41.635782Z","end":"2026-08-22T03:38:41.977457Z","steps":["trace[1890214325] 'process raft request' (duration: 341.217565ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:41.977593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:41.635758Z","time spent":"341.755766ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T03:38:41.977665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.528086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T03:38:41.977775Z","caller":"traceutil/trace.go:171","msg":"trace[104001222] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:24552; }","duration":"327.712509ms","start":"2026-08-22T03:38:41.650038Z","end":"2026-08-22T03:38:41.97775Z","steps":["trace[104001222] 'agreement among raft nodes before linearized reading' (duration: 327.378391ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:41.97783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:41.650017Z","time spent":"327.801081ms","remote":"127.0.0.1:34340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":77,"response size":31,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-08-22T03:38:42.331106Z","caller":"traceutil/trace.go:171","msg":"trace[2009325158] transaction","detail":"{read_only:false; response_revision:24554; number_of_response:1; }","duration":"470.653805ms","start":"2026-08-22T03:38:41.86043Z","end":"2026-08-22T03:38:42.331084Z","steps":["trace[2009325158] 'process raft request' (duration: 470.598754ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:42.331152Z","caller":"traceutil/trace.go:171","msg":"trace[842856998] transaction","detail":"{read_only:false; response_revision:24553; number_of_response:1; }","duration":"475.929896ms","start":"2026-08-22T03:38:41.855172Z","end":"2026-08-22T03:38:42.331102Z","steps":["trace[842856998] 'process raft request' (duration: 444.303445ms)","trace[842856998] 'compare' (duration: 31.432788ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:38:42.331235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:41.860404Z","time spent":"470.766668ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T03:38:42.331217Z","caller":"traceutil/trace.go:171","msg":"trace[1788432337] linearizableReadLoop","detail":"{readStateIndex:25352; appliedIndex:25349; }","duration":"353.946198ms","start":"2026-08-22T03:38:41.977251Z","end":"2026-08-22T03:38:42.331197Z","steps":["trace[1788432337] 'read index received' (duration: 322.201704ms)","trace[1788432337] 'applied index is now lower than readState.Index' (duration: 31.743844ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:38:42.331322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:41.855146Z","time spent":"476.086639ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T03:38:42.331394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.580891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-22T03:38:42.331415Z","caller":"traceutil/trace.go:171","msg":"trace[1506776173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24555; }","duration":"464.626961ms","start":"2026-08-22T03:38:41.866781Z","end":"2026-08-22T03:38:42.331408Z","steps":["trace[1506776173] 'agreement among raft nodes before linearized reading' (duration: 464.515279ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:42.33144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:41.866772Z","time spent":"464.662872ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-22T03:38:42.331491Z","caller":"traceutil/trace.go:171","msg":"trace[820675725] transaction","detail":"{read_only:false; response_revision:24555; number_of_response:1; }","duration":"470.614164ms","start":"2026-08-22T03:38:41.860862Z","end":"2026-08-22T03:38:42.331477Z","steps":["trace[820675725] 'process raft request' (duration: 470.196637ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:42.331546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:41.860852Z","time spent":"470.668986ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T03:38:42.331565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.830966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T03:38:42.331629Z","caller":"traceutil/trace.go:171","msg":"trace[2076522240] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:24555; }","duration":"645.943088ms","start":"2026-08-22T03:38:41.68567Z","end":"2026-08-22T03:38:42.331613Z","steps":["trace[2076522240] 'agreement among raft nodes before linearized reading' (duration: 645.831596ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:42.331591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.381147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-22T03:38:42.331735Z","caller":"traceutil/trace.go:171","msg":"trace[89175355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24555; }","duration":"464.54919ms","start":"2026-08-22T03:38:41.867155Z","end":"2026-08-22T03:38:42.331704Z","steps":["trace[89175355] 'agreement among raft nodes before linearized reading' (duration: 464.072141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:42.331771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.136131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-22T03:38:42.331792Z","caller":"traceutil/trace.go:171","msg":"trace[1944229504] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24555; }","duration":"465.186411ms","start":"2026-08-22T03:38:41.8666Z","end":"2026-08-22T03:38:42.331787Z","steps":["trace[1944229504] 'agreement among raft nodes before linearized reading' (duration: 465.074849ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:42.331808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:41.86659Z","time spent":"465.213533ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-22T03:38:42.33181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:41.867133Z","time spent":"464.664341ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T03:38:42.331834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.261327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-22T03:38:42.331855Z","caller":"traceutil/trace.go:171","msg":"trace[1936215162] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24555; }","duration":"512.320287ms","start":"2026-08-22T03:38:41.81953Z","end":"2026-08-22T03:38:42.33185Z","steps":["trace[1936215162] 'agreement among raft nodes before linearized reading' (duration: 512.263606ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:42.331661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:41.685652Z","time spent":"646.003059ms","remote":"127.0.0.1:35190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true "} {"level":"warn","ts":"2026-08-22T03:38:42.331875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:41.819505Z","time spent":"512.366119ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T03:38:42.872306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.225872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T03:38:42.872443Z","caller":"traceutil/trace.go:171","msg":"trace[1491556937] transaction","detail":"{read_only:false; response_revision:24556; number_of_response:1; }","duration":"535.537198ms","start":"2026-08-22T03:38:42.336867Z","end":"2026-08-22T03:38:42.872404Z","steps":["trace[1491556937] 'process raft request' (duration: 250.118803ms)","trace[1491556937] 'compare' (duration: 284.988457ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:38:42.87251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:42.336853Z","time spent":"535.62714ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T03:38:42.872865Z","caller":"traceutil/trace.go:171","msg":"trace[1226169283] transaction","detail":"{read_only:false; response_revision:24557; number_of_response:1; }","duration":"535.878195ms","start":"2026-08-22T03:38:42.336972Z","end":"2026-08-22T03:38:42.872851Z","steps":["trace[1226169283] 'process raft request' (duration: 535.671911ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:42.872971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:42.336941Z","time spent":"535.994407ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T03:38:42.872963Z","caller":"traceutil/trace.go:171","msg":"trace[297633863] linearizableReadLoop","detail":"{readStateIndex:25357; appliedIndex:25352; }","duration":"483.796575ms","start":"2026-08-22T03:38:42.389091Z","end":"2026-08-22T03:38:42.872888Z","steps":["trace[297633863] 'read index received' (duration: 198.035424ms)","trace[297633863] 'applied index is now lower than readState.Index' (duration: 285.760081ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:38:42.873041Z","caller":"traceutil/trace.go:171","msg":"trace[1830586254] transaction","detail":"{read_only:false; response_revision:24559; number_of_response:1; }","duration":"534.462789ms","start":"2026-08-22T03:38:42.33855Z","end":"2026-08-22T03:38:42.873013Z","steps":["trace[1830586254] 'process raft request' (duration: 534.251665ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:42.873081Z","caller":"traceutil/trace.go:171","msg":"trace[981098157] transaction","detail":"{read_only:false; response_revision:24560; number_of_response:1; }","duration":"534.314346ms","start":"2026-08-22T03:38:42.338757Z","end":"2026-08-22T03:38:42.873071Z","steps":["trace[981098157] 'process raft request' (duration: 534.088042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:42.873173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:42.33874Z","time spent":"534.364826ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T03:38:42.873221Z","caller":"traceutil/trace.go:171","msg":"trace[21473936] transaction","detail":"{read_only:false; response_revision:24558; number_of_response:1; }","duration":"535.596399ms","start":"2026-08-22T03:38:42.337615Z","end":"2026-08-22T03:38:42.873211Z","steps":["trace[21473936] 'process raft request' (duration: 535.139961ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:42.873272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:42.337589Z","time spent":"535.653811ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T03:38:42.873173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:42.338516Z","time spent":"534.58184ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T03:38:42.873371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.289775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T03:38:42.873404Z","caller":"traceutil/trace.go:171","msg":"trace[1351559670] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:24560; }","duration":"484.371856ms","start":"2026-08-22T03:38:42.389023Z","end":"2026-08-22T03:38:42.873395Z","steps":["trace[1351559670] 'agreement among raft nodes before linearized reading' (duration: 484.241543ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:42.873444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:42.389003Z","time spent":"484.429898ms","remote":"127.0.0.1:34490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":126,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-08-22T03:38:42.884265Z","caller":"traceutil/trace.go:171","msg":"trace[1692401907] transaction","detail":"{read_only:false; response_revision:24561; number_of_response:1; }","duration":"258.981013ms","start":"2026-08-22T03:38:42.625264Z","end":"2026-08-22T03:38:42.884245Z","steps":["trace[1692401907] 'process raft request' (duration: 258.878631ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:42.884497Z","caller":"traceutil/trace.go:171","msg":"trace[42950231] transaction","detail":"{read_only:false; response_revision:24562; number_of_response:1; }","duration":"175.837982ms","start":"2026-08-22T03:38:42.708646Z","end":"2026-08-22T03:38:42.884484Z","steps":["trace[42950231] 'process raft request' (duration: 175.569127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:42.884629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.590799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T03:38:42.884663Z","caller":"traceutil/trace.go:171","msg":"trace[855314588] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:24563; }","duration":"366.671249ms","start":"2026-08-22T03:38:42.517984Z","end":"2026-08-22T03:38:42.884655Z","steps":["trace[855314588] 'agreement among raft nodes before linearized reading' (duration: 366.599858ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:42.884696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:42.517959Z","time spent":"366.731321ms","remote":"127.0.0.1:34314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-08-22T03:38:42.884742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.551229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T03:38:42.884813Z","caller":"traceutil/trace.go:171","msg":"trace[119110872] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:24563; }","duration":"213.602959ms","start":"2026-08-22T03:38:42.671176Z","end":"2026-08-22T03:38:42.884779Z","steps":["trace[119110872] 'agreement among raft nodes before linearized reading' (duration: 213.449417ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:42.884839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.650478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T03:38:42.884922Z","caller":"traceutil/trace.go:171","msg":"trace[348747904] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:24563; }","duration":"336.75807ms","start":"2026-08-22T03:38:42.548126Z","end":"2026-08-22T03:38:42.884884Z","steps":["trace[348747904] 'agreement among raft nodes before linearized reading' (duration: 336.653408ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:42.88497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:42.548086Z","time spent":"336.873393ms","remote":"127.0.0.1:36886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-22T03:38:42.88499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.929277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-22T03:38:42.885024Z","caller":"traceutil/trace.go:171","msg":"trace[215056987] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24563; }","duration":"231.999029ms","start":"2026-08-22T03:38:42.653012Z","end":"2026-08-22T03:38:42.885011Z","steps":["trace[215056987] 'agreement among raft nodes before linearized reading' (duration: 231.867096ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:42.884956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.722007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T03:38:42.885612Z","caller":"traceutil/trace.go:171","msg":"trace[14970454] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:24563; }","duration":"139.39396ms","start":"2026-08-22T03:38:42.746145Z","end":"2026-08-22T03:38:42.885539Z","steps":["trace[14970454] 'agreement among raft nodes before linearized reading' (duration: 138.629864ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:43.455008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.969109ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T03:38:43.455162Z","caller":"traceutil/trace.go:171","msg":"trace[2000771954] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:24564; }","duration":"322.134842ms","start":"2026-08-22T03:38:43.133008Z","end":"2026-08-22T03:38:43.455143Z","steps":["trace[2000771954] 'range keys from in-memory index tree' (duration: 321.951329ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:43.586424Z","caller":"traceutil/trace.go:171","msg":"trace[1536024570] linearizableReadLoop","detail":"{readStateIndex:25362; appliedIndex:25361; }","duration":"331.084422ms","start":"2026-08-22T03:38:43.255315Z","end":"2026-08-22T03:38:43.586399Z","steps":["trace[1536024570] 'read index received' (duration: 331.004521ms)","trace[1536024570] 'applied index is now lower than readState.Index' (duration: 78.941µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:38:43.586498Z","caller":"traceutil/trace.go:171","msg":"trace[426189550] transaction","detail":"{read_only:false; response_revision:24565; number_of_response:1; }","duration":"435.08798ms","start":"2026-08-22T03:38:43.151376Z","end":"2026-08-22T03:38:43.586464Z","steps":["trace[426189550] 'process raft request' (duration: 434.839645ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:43.586652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:43.151347Z","time spent":"435.216922ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T03:38:43.586657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.326588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T03:38:43.586705Z","caller":"traceutil/trace.go:171","msg":"trace[1325716858] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:24565; }","duration":"331.431019ms","start":"2026-08-22T03:38:43.255259Z","end":"2026-08-22T03:38:43.58669Z","steps":["trace[1325716858] 'agreement among raft nodes before linearized reading' (duration: 331.258566ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:43.586748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:43.255236Z","time spent":"331.500651ms","remote":"127.0.0.1:34702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":60,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-08-22T03:38:43.922784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.451807ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T03:38:43.922908Z","caller":"traceutil/trace.go:171","msg":"trace[2106841363] linearizableReadLoop","detail":"{readStateIndex:25363; appliedIndex:25362; }","duration":"336.399174ms","start":"2026-08-22T03:38:43.586491Z","end":"2026-08-22T03:38:43.92289Z","steps":["trace[2106841363] 'read index received' (duration: 162.731103ms)","trace[2106841363] 'applied index is now lower than readState.Index' (duration: 173.666621ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:38:43.922995Z","caller":"traceutil/trace.go:171","msg":"trace[924411195] transaction","detail":"{read_only:false; response_revision:24566; number_of_response:1; }","duration":"497.794252ms","start":"2026-08-22T03:38:43.425188Z","end":"2026-08-22T03:38:43.922983Z","steps":["trace[924411195] 'process raft request' (duration: 324.05026ms)","trace[924411195] 'compare' (duration: 173.317774ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:38:43.923064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:43.42516Z","time spent":"497.867853ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T03:38:43.923391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.444424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T03:38:43.923671Z","caller":"traceutil/trace.go:171","msg":"trace[709846330] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24566; }","duration":"478.629718ms","start":"2026-08-22T03:38:43.444854Z","end":"2026-08-22T03:38:43.923484Z","steps":["trace[709846330] 'agreement among raft nodes before linearized reading' (duration: 478.289952ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:43.923757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:43.444833Z","time spent":"478.899452ms","remote":"127.0.0.1:34070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T03:38:43.924151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.612919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T03:38:43.924242Z","caller":"traceutil/trace.go:171","msg":"trace[2100272482] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:24567; }","duration":"284.769572ms","start":"2026-08-22T03:38:43.63945Z","end":"2026-08-22T03:38:43.924219Z","steps":["trace[2100272482] 'agreement among raft nodes before linearized reading' (duration: 284.601918ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:43.924396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.232166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T03:38:43.924494Z","caller":"traceutil/trace.go:171","msg":"trace[751737031] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:24567; }","duration":"229.384649ms","start":"2026-08-22T03:38:43.695087Z","end":"2026-08-22T03:38:43.924472Z","steps":["trace[751737031] 'agreement among raft nodes before linearized reading' (duration: 229.213886ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:49.780341Z","caller":"traceutil/trace.go:171","msg":"trace[829283257] linearizableReadLoop","detail":"{readStateIndex:25463; appliedIndex:25462; }","duration":"101.440168ms","start":"2026-08-22T03:38:49.678876Z","end":"2026-08-22T03:38:49.780316Z","steps":["trace[829283257] 'read index received' (duration: 101.197553ms)","trace[829283257] 'applied index is now lower than readState.Index' (duration: 241.605µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:38:49.780473Z","caller":"traceutil/trace.go:171","msg":"trace[127136941] transaction","detail":"{read_only:false; response_revision:24665; number_of_response:1; }","duration":"163.615931ms","start":"2026-08-22T03:38:49.616804Z","end":"2026-08-22T03:38:49.78042Z","steps":["trace[127136941] 'process raft request' (duration: 163.369216ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:49.780535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.641252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-896456fe-09aa-43c5-90fb-6164a0fb5498\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-08-22T03:38:49.780575Z","caller":"traceutil/trace.go:171","msg":"trace[578291253] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-896456fe-09aa-43c5-90fb-6164a0fb5498; range_end:; response_count:1; response_revision:24665; }","duration":"101.727194ms","start":"2026-08-22T03:38:49.678832Z","end":"2026-08-22T03:38:49.78056Z","steps":["trace[578291253] 'agreement among raft nodes before linearized reading' (duration: 101.577891ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:49.962101Z","caller":"traceutil/trace.go:171","msg":"trace[1275356702] linearizableReadLoop","detail":"{readStateIndex:25464; appliedIndex:25463; }","duration":"151.292556ms","start":"2026-08-22T03:38:49.810788Z","end":"2026-08-22T03:38:49.962081Z","steps":["trace[1275356702] 'read index received' (duration: 151.225005ms)","trace[1275356702] 'applied index is now lower than readState.Index' (duration: 66.071µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:38:49.962222Z","caller":"traceutil/trace.go:171","msg":"trace[1819377804] transaction","detail":"{read_only:false; response_revision:24666; number_of_response:1; }","duration":"175.651309ms","start":"2026-08-22T03:38:49.786538Z","end":"2026-08-22T03:38:49.962189Z","steps":["trace[1819377804] 'process raft request' (duration: 175.395624ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:49.962382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.580871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"info","ts":"2026-08-22T03:38:49.962413Z","caller":"traceutil/trace.go:171","msg":"trace[33795151] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24666; }","duration":"151.667223ms","start":"2026-08-22T03:38:49.810738Z","end":"2026-08-22T03:38:49.962405Z","steps":["trace[33795151] 'agreement among raft nodes before linearized reading' (duration: 151.422518ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:50.067681Z","caller":"traceutil/trace.go:171","msg":"trace[512967196] transaction","detail":"{read_only:false; response_revision:24667; number_of_response:1; }","duration":"115.448214ms","start":"2026-08-22T03:38:49.952199Z","end":"2026-08-22T03:38:50.067648Z","steps":["trace[512967196] 'process raft request' (duration: 114.770341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:50.351584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.081685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T03:38:50.35166Z","caller":"traceutil/trace.go:171","msg":"trace[480898243] linearizableReadLoop","detail":"{readStateIndex:25467; appliedIndex:25466; }","duration":"253.947317ms","start":"2026-08-22T03:38:50.097701Z","end":"2026-08-22T03:38:50.351648Z","steps":["trace[480898243] 'read index received' (duration: 115.724319ms)","trace[480898243] 'applied index is now lower than readState.Index' (duration: 138.222088ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:38:50.351792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.09284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"info","ts":"2026-08-22T03:38:50.351807Z","caller":"traceutil/trace.go:171","msg":"trace[1543222673] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24669; }","duration":"254.11994ms","start":"2026-08-22T03:38:50.097681Z","end":"2026-08-22T03:38:50.351801Z","steps":["trace[1543222673] 'agreement among raft nodes before linearized reading' (duration: 254.027508ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:50.351765Z","caller":"traceutil/trace.go:171","msg":"trace[579207770] transaction","detail":"{read_only:false; response_revision:24669; number_of_response:1; }","duration":"262.465299ms","start":"2026-08-22T03:38:50.089256Z","end":"2026-08-22T03:38:50.351722Z","steps":["trace[579207770] 'process raft request' (duration: 124.16095ms)","trace[579207770] 'compare' (duration: 137.911992ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:38:50.351981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.755918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-896456fe-09aa-43c5-90fb-6164a0fb5498\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-08-22T03:38:50.351997Z","caller":"traceutil/trace.go:171","msg":"trace[89004190] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-896456fe-09aa-43c5-90fb-6164a0fb5498; range_end:; response_count:1; response_revision:24669; }","duration":"228.793329ms","start":"2026-08-22T03:38:50.123199Z","end":"2026-08-22T03:38:50.351992Z","steps":["trace[89004190] 'agreement among raft nodes before linearized reading' (duration: 228.739648ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:50.352204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.500819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T03:38:50.352218Z","caller":"traceutil/trace.go:171","msg":"trace[1207026094] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24669; }","duration":"172.54604ms","start":"2026-08-22T03:38:50.179668Z","end":"2026-08-22T03:38:50.352214Z","steps":["trace[1207026094] 'agreement among raft nodes before linearized reading' (duration: 172.517189ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:50.498803Z","caller":"traceutil/trace.go:171","msg":"trace[1857030032] transaction","detail":"{read_only:false; response_revision:24670; number_of_response:1; }","duration":"143.64904ms","start":"2026-08-22T03:38:50.355123Z","end":"2026-08-22T03:38:50.498772Z","steps":["trace[1857030032] 'process raft request' (duration: 143.300014ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:50.519224Z","caller":"traceutil/trace.go:171","msg":"trace[1146830392] linearizableReadLoop","detail":"{readStateIndex:25469; appliedIndex:25467; }","duration":"163.693762ms","start":"2026-08-22T03:38:50.355512Z","end":"2026-08-22T03:38:50.519206Z","steps":["trace[1146830392] 'read index received' (duration: 143.10146ms)","trace[1146830392] 'applied index is now lower than readState.Index' (duration: 20.591002ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:38:50.519532Z","caller":"traceutil/trace.go:171","msg":"trace[2113340249] transaction","detail":"{read_only:false; response_revision:24671; number_of_response:1; }","duration":"164.315604ms","start":"2026-08-22T03:38:50.355202Z","end":"2026-08-22T03:38:50.519517Z","steps":["trace[2113340249] 'process raft request' (duration: 163.872166ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:50.519728Z","caller":"traceutil/trace.go:171","msg":"trace[991051345] transaction","detail":"{read_only:false; response_revision:24672; number_of_response:1; }","duration":"161.924478ms","start":"2026-08-22T03:38:50.357788Z","end":"2026-08-22T03:38:50.519713Z","steps":["trace[991051345] 'process raft request' (duration: 161.371727ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:50.51996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.422346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T03:38:50.520016Z","caller":"traceutil/trace.go:171","msg":"trace[78124788] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24672; }","duration":"164.550658ms","start":"2026-08-22T03:38:50.355452Z","end":"2026-08-22T03:38:50.520002Z","steps":["trace[78124788] 'agreement among raft nodes before linearized reading' (duration: 164.443046ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:50.520313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.397116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18ce037acf797a94\" ","response":"range_response_count:1 size:736"} {"level":"info","ts":"2026-08-22T03:38:50.520362Z","caller":"traceutil/trace.go:171","msg":"trace[1637237946] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18ce037acf797a94; range_end:; response_count:1; response_revision:24672; }","duration":"164.473956ms","start":"2026-08-22T03:38:50.355873Z","end":"2026-08-22T03:38:50.520347Z","steps":["trace[1637237946] 'agreement among raft nodes before linearized reading' (duration: 164.322124ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:50.520758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.981228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1446"} {"level":"info","ts":"2026-08-22T03:38:50.52081Z","caller":"traceutil/trace.go:171","msg":"trace[1345321139] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24672; }","duration":"163.045399ms","start":"2026-08-22T03:38:50.35775Z","end":"2026-08-22T03:38:50.520795Z","steps":["trace[1345321139] 'agreement among raft nodes before linearized reading' (duration: 162.933027ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:50.727638Z","caller":"traceutil/trace.go:171","msg":"trace[677081715] linearizableReadLoop","detail":"{readStateIndex:25472; appliedIndex:25471; }","duration":"169.380929ms","start":"2026-08-22T03:38:50.558228Z","end":"2026-08-22T03:38:50.727609Z","steps":["trace[677081715] 'read index received' (duration: 169.186295ms)","trace[677081715] 'applied index is now lower than readState.Index' (duration: 193.444µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:38:50.727753Z","caller":"traceutil/trace.go:171","msg":"trace[748664745] transaction","detail":"{read_only:false; response_revision:24674; number_of_response:1; }","duration":"173.997927ms","start":"2026-08-22T03:38:50.553718Z","end":"2026-08-22T03:38:50.727716Z","steps":["trace[748664745] 'process raft request' (duration: 173.707951ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:50.727923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.670664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-08-22T03:38:50.727974Z","caller":"traceutil/trace.go:171","msg":"trace[359156998] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24674; }","duration":"169.748865ms","start":"2026-08-22T03:38:50.558211Z","end":"2026-08-22T03:38:50.72796Z","steps":["trace[359156998] 'agreement among raft nodes before linearized reading' (duration: 169.556832ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:50.728011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.092881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.181\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-22T03:38:50.728108Z","caller":"traceutil/trace.go:171","msg":"trace[1637621566] range","detail":"{range_begin:/registry/masterleases/199.19.213.181; range_end:; response_count:1; response_revision:24674; }","duration":"162.241654ms","start":"2026-08-22T03:38:50.565846Z","end":"2026-08-22T03:38:50.728088Z","steps":["trace[1637621566] 'agreement among raft nodes before linearized reading' (duration: 161.958428ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:50.900709Z","caller":"traceutil/trace.go:171","msg":"trace[1266924668] transaction","detail":"{read_only:false; response_revision:24675; number_of_response:1; }","duration":"157.959182ms","start":"2026-08-22T03:38:50.742729Z","end":"2026-08-22T03:38:50.900688Z","steps":["trace[1266924668] 'process raft request' (duration: 90.519891ms)","trace[1266924668] 'compare' (duration: 67.238108ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:38:53.12884Z","caller":"traceutil/trace.go:171","msg":"trace[693648255] transaction","detail":"{read_only:false; response_revision:24691; number_of_response:1; }","duration":"234.026368ms","start":"2026-08-22T03:38:52.894783Z","end":"2026-08-22T03:38:53.12881Z","steps":["trace[693648255] 'process raft request' (duration: 233.840694ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:53.128961Z","caller":"traceutil/trace.go:171","msg":"trace[1781127255] linearizableReadLoop","detail":"{readStateIndex:25490; appliedIndex:25490; }","duration":"211.470899ms","start":"2026-08-22T03:38:52.917382Z","end":"2026-08-22T03:38:53.128853Z","steps":["trace[1781127255] 'read index received' (duration: 211.430838ms)","trace[1781127255] 'applied index is now lower than readState.Index' (duration: 35.301µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:38:53.129715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.233613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-22T03:38:53.12978Z","caller":"traceutil/trace.go:171","msg":"trace[1744122386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24691; }","duration":"212.429238ms","start":"2026-08-22T03:38:52.917334Z","end":"2026-08-22T03:38:53.129763Z","steps":["trace[1744122386] 'agreement among raft nodes before linearized reading' (duration: 211.837706ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:53.181452Z","caller":"traceutil/trace.go:171","msg":"trace[2032259689] transaction","detail":"{read_only:false; response_revision:24692; number_of_response:1; }","duration":"261.383938ms","start":"2026-08-22T03:38:52.920041Z","end":"2026-08-22T03:38:53.181425Z","steps":["trace[2032259689] 'process raft request' (duration: 248.663826ms)","trace[2032259689] 'compare' (duration: 12.168782ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:38:53.181746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.334923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-22T03:38:53.181786Z","caller":"traceutil/trace.go:171","msg":"trace[1414709403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24693; }","duration":"264.423525ms","start":"2026-08-22T03:38:52.917353Z","end":"2026-08-22T03:38:53.181777Z","steps":["trace[1414709403] 'agreement among raft nodes before linearized reading' (duration: 264.252372ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:53.181857Z","caller":"traceutil/trace.go:171","msg":"trace[398952937] transaction","detail":"{read_only:false; response_revision:24693; number_of_response:1; }","duration":"113.566318ms","start":"2026-08-22T03:38:53.068284Z","end":"2026-08-22T03:38:53.18185Z","steps":["trace[398952937] 'process raft request' (duration: 113.092919ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:53.182032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.459117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-08-22T03:38:53.182056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.704116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T03:38:53.182082Z","caller":"traceutil/trace.go:171","msg":"trace[915645336] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:24694; }","duration":"168.730867ms","start":"2026-08-22T03:38:53.013342Z","end":"2026-08-22T03:38:53.182073Z","steps":["trace[915645336] 'agreement among raft nodes before linearized reading' (duration: 168.564024ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:53.182081Z","caller":"traceutil/trace.go:171","msg":"trace[674556956] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24694; }","duration":"263.528479ms","start":"2026-08-22T03:38:52.918542Z","end":"2026-08-22T03:38:53.18207Z","steps":["trace[674556956] 'agreement among raft nodes before linearized reading' (duration: 263.387046ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:53.182214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.889778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-22T03:38:53.182248Z","caller":"traceutil/trace.go:171","msg":"trace[13871685] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24694; }","duration":"260.940629ms","start":"2026-08-22T03:38:52.921298Z","end":"2026-08-22T03:38:53.182238Z","steps":["trace[13871685] 'agreement among raft nodes before linearized reading' (duration: 260.629343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:53.182315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.021961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-22T03:38:53.182466Z","caller":"traceutil/trace.go:171","msg":"trace[159391795] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24694; }","duration":"231.176893ms","start":"2026-08-22T03:38:52.951225Z","end":"2026-08-22T03:38:53.182402Z","steps":["trace[159391795] 'agreement among raft nodes before linearized reading' (duration: 230.689514ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:53.182704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.100002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-22T03:38:53.182961Z","caller":"traceutil/trace.go:171","msg":"trace[1656940578] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24694; }","duration":"260.171694ms","start":"2026-08-22T03:38:52.922565Z","end":"2026-08-22T03:38:53.182737Z","steps":["trace[1656940578] 'agreement among raft nodes before linearized reading' (duration: 259.355409ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:57.077009Z","caller":"traceutil/trace.go:171","msg":"trace[1739642173] transaction","detail":"{read_only:false; response_revision:24738; number_of_response:1; }","duration":"239.198627ms","start":"2026-08-22T03:38:56.837786Z","end":"2026-08-22T03:38:57.076985Z","steps":["trace[1739642173] 'process raft request' (duration: 239.009572ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:57.340274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.94979ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T03:38:57.340414Z","caller":"traceutil/trace.go:171","msg":"trace[2044679731] transaction","detail":"{read_only:false; response_revision:24739; number_of_response:1; }","duration":"416.274153ms","start":"2026-08-22T03:38:56.924106Z","end":"2026-08-22T03:38:57.34038Z","steps":["trace[2044679731] 'process raft request' (duration: 277.099107ms)","trace[2044679731] 'compare' (duration: 138.494642ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:38:57.340481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:56.924079Z","time spent":"416.370994ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T03:38:57.406045Z","caller":"traceutil/trace.go:171","msg":"trace[241617667] transaction","detail":"{read_only:false; response_revision:24740; number_of_response:1; }","duration":"320.623993ms","start":"2026-08-22T03:38:57.085399Z","end":"2026-08-22T03:38:57.406023Z","steps":["trace[241617667] 'process raft request' (duration: 320.43333ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:57.406167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:57.085369Z","time spent":"320.739846ms","remote":"127.0.0.1:34398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1413,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T03:38:57.406359Z","caller":"traceutil/trace.go:171","msg":"trace[1816632878] transaction","detail":"{read_only:false; response_revision:24741; number_of_response:1; }","duration":"320.815787ms","start":"2026-08-22T03:38:57.085513Z","end":"2026-08-22T03:38:57.406329Z","steps":["trace[1816632878] 'process raft request' (duration: 320.4451ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:57.406501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:57.085482Z","time spent":"320.94059ms","remote":"127.0.0.1:34258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":939,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T03:38:57.406674Z","caller":"traceutil/trace.go:171","msg":"trace[664697221] transaction","detail":"{read_only:false; response_revision:24742; number_of_response:1; }","duration":"320.98309ms","start":"2026-08-22T03:38:57.085621Z","end":"2026-08-22T03:38:57.406605Z","steps":["trace[664697221] 'process raft request' (duration: 320.549292ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:57.406802Z","caller":"traceutil/trace.go:171","msg":"trace[1587864392] transaction","detail":"{read_only:false; response_revision:24743; number_of_response:1; }","duration":"321.181893ms","start":"2026-08-22T03:38:57.08561Z","end":"2026-08-22T03:38:57.406792Z","steps":["trace[1587864392] 'process raft request' (duration: 320.918049ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:57.406855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:57.085581Z","time spent":"321.250525ms","remote":"127.0.0.1:34398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1488,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T03:38:57.406873Z","caller":"traceutil/trace.go:171","msg":"trace[402601167] linearizableReadLoop","detail":"{readStateIndex:25544; appliedIndex:25538; }","duration":"209.351849ms","start":"2026-08-22T03:38:57.197508Z","end":"2026-08-22T03:38:57.40686Z","steps":["trace[402601167] 'read index received' (duration: 3.747621ms)","trace[402601167] 'applied index is now lower than readState.Index' (duration: 205.603238ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:38:57.407097Z","caller":"traceutil/trace.go:171","msg":"trace[1050210374] transaction","detail":"{read_only:false; response_revision:24744; number_of_response:1; }","duration":"210.722995ms","start":"2026-08-22T03:38:57.196364Z","end":"2026-08-22T03:38:57.407087Z","steps":["trace[1050210374] 'process raft request' (duration: 210.332008ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:57.407316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.825332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-22T03:38:57.4074Z","caller":"traceutil/trace.go:171","msg":"trace[1171678494] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24744; }","duration":"205.972964ms","start":"2026-08-22T03:38:57.201405Z","end":"2026-08-22T03:38:57.407378Z","steps":["trace[1171678494] 'agreement among raft nodes before linearized reading' (duration: 205.697949ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:57.406957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:38:57.08561Z","time spent":"321.146364ms","remote":"127.0.0.1:34258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T03:38:57.407562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.716406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-22T03:38:57.40764Z","caller":"traceutil/trace.go:171","msg":"trace[605363436] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24744; }","duration":"200.808927ms","start":"2026-08-22T03:38:57.20682Z","end":"2026-08-22T03:38:57.407629Z","steps":["trace[605363436] 'agreement among raft nodes before linearized reading' (duration: 200.635314ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:57.407793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.97933ms","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":"warn","ts":"2026-08-22T03:38:57.407829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.330948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-22T03:38:57.407858Z","caller":"traceutil/trace.go:171","msg":"trace[1142433701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24744; }","duration":"210.398139ms","start":"2026-08-22T03:38:57.197451Z","end":"2026-08-22T03:38:57.407849Z","steps":["trace[1142433701] 'agreement among raft nodes before linearized reading' (duration: 210.312448ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:38:57.40797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.605582ms","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":"warn","ts":"2026-08-22T03:38:57.408028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.225506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-22T03:38:57.408035Z","caller":"traceutil/trace.go:171","msg":"trace[2517147] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24744; }","duration":"201.687643ms","start":"2026-08-22T03:38:57.206328Z","end":"2026-08-22T03:38:57.408015Z","steps":["trace[2517147] 'agreement among raft nodes before linearized reading' (duration: 201.555221ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:57.408068Z","caller":"traceutil/trace.go:171","msg":"trace[1425723004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24744; }","duration":"200.285147ms","start":"2026-08-22T03:38:57.207775Z","end":"2026-08-22T03:38:57.40806Z","steps":["trace[1425723004] 'agreement among raft nodes before linearized reading' (duration: 200.194335ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:38:57.407858Z","caller":"traceutil/trace.go:171","msg":"trace[1892220481] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24744; }","duration":"201.059152ms","start":"2026-08-22T03:38:57.20678Z","end":"2026-08-22T03:38:57.407839Z","steps":["trace[1892220481] 'agreement among raft nodes before linearized reading' (duration: 200.909399ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:39:02.236998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.299821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T03:39:02.237126Z","caller":"traceutil/trace.go:171","msg":"trace[1959953866] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:24784; }","duration":"256.477216ms","start":"2026-08-22T03:39:01.980626Z","end":"2026-08-22T03:39:02.237103Z","steps":["trace[1959953866] 'count revisions from in-memory index tree' (duration: 256.21866ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:02.237052Z","caller":"traceutil/trace.go:171","msg":"trace[1135541839] transaction","detail":"{read_only:false; response_revision:24785; number_of_response:1; }","duration":"128.150095ms","start":"2026-08-22T03:39:02.108879Z","end":"2026-08-22T03:39:02.237029Z","steps":["trace[1135541839] 'process raft request' (duration: 119.95931ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:02.238392Z","caller":"traceutil/trace.go:171","msg":"trace[1130881179] transaction","detail":"{read_only:false; response_revision:24786; number_of_response:1; }","duration":"126.953553ms","start":"2026-08-22T03:39:02.111391Z","end":"2026-08-22T03:39:02.238345Z","steps":["trace[1130881179] 'process raft request' (duration: 126.77198ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:03.961791Z","caller":"traceutil/trace.go:171","msg":"trace[1651500207] transaction","detail":"{read_only:false; response_revision:24799; number_of_response:1; }","duration":"101.468289ms","start":"2026-08-22T03:39:03.860258Z","end":"2026-08-22T03:39:03.961726Z","steps":["trace[1651500207] 'process raft request' (duration: 101.262345ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:07.81135Z","caller":"traceutil/trace.go:171","msg":"trace[437229500] transaction","detail":"{read_only:false; response_revision:24832; number_of_response:1; }","duration":"152.689932ms","start":"2026-08-22T03:39:07.658633Z","end":"2026-08-22T03:39:07.811323Z","steps":["trace[437229500] 'process raft request' (duration: 152.519959ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:39:10.704234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.356711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.181\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-22T03:39:10.704315Z","caller":"traceutil/trace.go:171","msg":"trace[1719171666] range","detail":"{range_begin:/registry/masterleases/199.19.213.181; range_end:; response_count:1; response_revision:24850; }","duration":"137.504394ms","start":"2026-08-22T03:39:10.566791Z","end":"2026-08-22T03:39:10.704295Z","steps":["trace[1719171666] 'range keys from in-memory index tree' (duration: 137.099516ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:10.752331Z","caller":"traceutil/trace.go:171","msg":"trace[1304004983] transaction","detail":"{read_only:false; response_revision:24851; number_of_response:1; }","duration":"122.130121ms","start":"2026-08-22T03:39:10.630166Z","end":"2026-08-22T03:39:10.752297Z","steps":["trace[1304004983] 'process raft request' (duration: 121.919337ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:10.786801Z","caller":"traceutil/trace.go:171","msg":"trace[307850553] transaction","detail":"{read_only:false; response_revision:24853; number_of_response:1; }","duration":"150.304598ms","start":"2026-08-22T03:39:10.636455Z","end":"2026-08-22T03:39:10.786759Z","steps":["trace[307850553] 'process raft request' (duration: 149.821938ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:10.787043Z","caller":"traceutil/trace.go:171","msg":"trace[1164565425] transaction","detail":"{read_only:false; response_revision:24852; number_of_response:1; }","duration":"155.300573ms","start":"2026-08-22T03:39:10.63169Z","end":"2026-08-22T03:39:10.786991Z","steps":["trace[1164565425] 'process raft request' (duration: 154.413325ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:19.787506Z","caller":"traceutil/trace.go:171","msg":"trace[692377053] linearizableReadLoop","detail":"{readStateIndex:25730; appliedIndex:25729; }","duration":"152.355676ms","start":"2026-08-22T03:39:19.63513Z","end":"2026-08-22T03:39:19.787486Z","steps":["trace[692377053] 'read index received' (duration: 152.233513ms)","trace[692377053] 'applied index is now lower than readState.Index' (duration: 120.973µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:39:19.787562Z","caller":"traceutil/trace.go:171","msg":"trace[971786921] transaction","detail":"{read_only:false; response_revision:24925; number_of_response:1; }","duration":"154.815333ms","start":"2026-08-22T03:39:19.632711Z","end":"2026-08-22T03:39:19.787527Z","steps":["trace[971786921] 'process raft request' (duration: 154.612799ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:39:19.787686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.53998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-22T03:39:19.787718Z","caller":"traceutil/trace.go:171","msg":"trace[1552211356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24925; }","duration":"152.603451ms","start":"2026-08-22T03:39:19.635107Z","end":"2026-08-22T03:39:19.78771Z","steps":["trace[1552211356] 'agreement among raft nodes before linearized reading' (duration: 152.478039ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:19.80173Z","caller":"traceutil/trace.go:171","msg":"trace[1292633737] transaction","detail":"{read_only:false; response_revision:24926; number_of_response:1; }","duration":"166.016166ms","start":"2026-08-22T03:39:19.635695Z","end":"2026-08-22T03:39:19.801711Z","steps":["trace[1292633737] 'process raft request' (duration: 165.684139ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:19.80175Z","caller":"traceutil/trace.go:171","msg":"trace[1417394063] transaction","detail":"{read_only:false; response_revision:24927; number_of_response:1; }","duration":"164.859444ms","start":"2026-08-22T03:39:19.636852Z","end":"2026-08-22T03:39:19.801711Z","steps":["trace[1417394063] 'process raft request' (duration: 164.736502ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:39:19.801921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.156669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-22T03:39:19.802365Z","caller":"traceutil/trace.go:171","msg":"trace[1322175334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24928; }","duration":"166.608847ms","start":"2026-08-22T03:39:19.635741Z","end":"2026-08-22T03:39:19.80235Z","steps":["trace[1322175334] 'agreement among raft nodes before linearized reading' (duration: 166.098648ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:19.801913Z","caller":"traceutil/trace.go:171","msg":"trace[335601860] transaction","detail":"{read_only:false; response_revision:24928; number_of_response:1; }","duration":"161.859436ms","start":"2026-08-22T03:39:19.640014Z","end":"2026-08-22T03:39:19.801874Z","steps":["trace[335601860] 'process raft request' (duration: 161.642072ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:39:19.80201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.001917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T03:39:19.802671Z","caller":"traceutil/trace.go:171","msg":"trace[1434955306] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24928; }","duration":"154.754381ms","start":"2026-08-22T03:39:19.647893Z","end":"2026-08-22T03:39:19.802647Z","steps":["trace[1434955306] 'agreement among raft nodes before linearized reading' (duration: 153.990696ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:23.99555Z","caller":"traceutil/trace.go:171","msg":"trace[2078831599] transaction","detail":"{read_only:false; response_revision:24956; number_of_response:1; }","duration":"118.716457ms","start":"2026-08-22T03:39:23.876805Z","end":"2026-08-22T03:39:23.995522Z","steps":["trace[2078831599] 'process raft request' (duration: 118.475493ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:24.037643Z","caller":"traceutil/trace.go:171","msg":"trace[332686569] transaction","detail":"{read_only:false; response_revision:24957; number_of_response:1; }","duration":"160.251997ms","start":"2026-08-22T03:39:23.877359Z","end":"2026-08-22T03:39:24.037611Z","steps":["trace[332686569] 'process raft request' (duration: 159.988662ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:24.037989Z","caller":"traceutil/trace.go:171","msg":"trace[2146486208] transaction","detail":"{read_only:false; response_revision:24958; number_of_response:1; }","duration":"160.551063ms","start":"2026-08-22T03:39:23.877425Z","end":"2026-08-22T03:39:24.037976Z","steps":["trace[2146486208] 'process raft request' (duration: 160.135275ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:24.038665Z","caller":"traceutil/trace.go:171","msg":"trace[2010913589] transaction","detail":"{read_only:false; response_revision:24959; number_of_response:1; }","duration":"161.178974ms","start":"2026-08-22T03:39:23.877451Z","end":"2026-08-22T03:39:24.03863Z","steps":["trace[2010913589] 'process raft request' (duration: 160.474521ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:24.038964Z","caller":"traceutil/trace.go:171","msg":"trace[247292402] transaction","detail":"{read_only:false; response_revision:24962; number_of_response:1; }","duration":"136.530126ms","start":"2026-08-22T03:39:23.902354Z","end":"2026-08-22T03:39:24.038884Z","steps":["trace[247292402] 'process raft request' (duration: 136.469135ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:24.038928Z","caller":"traceutil/trace.go:171","msg":"trace[810625551] transaction","detail":"{read_only:false; response_revision:24960; number_of_response:1; }","duration":"161.147252ms","start":"2026-08-22T03:39:23.877752Z","end":"2026-08-22T03:39:24.038899Z","steps":["trace[810625551] 'process raft request' (duration: 160.759415ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:24.038972Z","caller":"traceutil/trace.go:171","msg":"trace[520012099] transaction","detail":"{read_only:false; response_revision:24961; number_of_response:1; }","duration":"160.819827ms","start":"2026-08-22T03:39:23.878122Z","end":"2026-08-22T03:39:24.038942Z","steps":["trace[520012099] 'process raft request' (duration: 160.630643ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:39:34.452056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.389093ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T03:39:34.452799Z","caller":"traceutil/trace.go:171","msg":"trace[1370822980] transaction","detail":"{read_only:false; response_revision:25028; number_of_response:1; }","duration":"347.964675ms","start":"2026-08-22T03:39:34.104795Z","end":"2026-08-22T03:39:34.45276Z","steps":["trace[1370822980] 'process raft request' (duration: 161.713894ms)","trace[1370822980] 'compare' (duration: 184.545718ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:39:34.452979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:39:34.104776Z","time spent":"348.098807ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T03:39:34.852286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.467602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T03:39:34.852617Z","caller":"traceutil/trace.go:171","msg":"trace[1338926301] transaction","detail":"{read_only:false; response_revision:25029; number_of_response:1; }","duration":"747.249672ms","start":"2026-08-22T03:39:34.105329Z","end":"2026-08-22T03:39:34.852578Z","steps":["trace[1338926301] 'process raft request' (duration: 447.388263ms)","trace[1338926301] 'compare' (duration: 299.332629ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:39:34.852845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:39:34.105311Z","time spent":"747.383894ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T03:39:34.853401Z","caller":"traceutil/trace.go:171","msg":"trace[1016312605] transaction","detail":"{read_only:false; response_revision:25030; number_of_response:1; }","duration":"747.993287ms","start":"2026-08-22T03:39:34.105353Z","end":"2026-08-22T03:39:34.853346Z","steps":["trace[1016312605] 'process raft request' (duration: 747.073708ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:39:34.853519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:39:34.105326Z","time spent":"748.14885ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T03:39:34.853734Z","caller":"traceutil/trace.go:171","msg":"trace[276424212] transaction","detail":"{read_only:false; response_revision:25031; number_of_response:1; }","duration":"747.755472ms","start":"2026-08-22T03:39:34.105947Z","end":"2026-08-22T03:39:34.853703Z","steps":["trace[276424212] 'process raft request' (duration: 746.735242ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:39:34.853875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:39:34.105933Z","time spent":"747.863695ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T03:39:34.853897Z","caller":"traceutil/trace.go:171","msg":"trace[2141075681] transaction","detail":"{read_only:false; response_revision:25033; number_of_response:1; }","duration":"672.311079ms","start":"2026-08-22T03:39:34.181569Z","end":"2026-08-22T03:39:34.85388Z","steps":["trace[2141075681] 'process raft request' (duration: 672.187126ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:34.853888Z","caller":"traceutil/trace.go:171","msg":"trace[870552345] transaction","detail":"{read_only:false; response_revision:25032; number_of_response:1; }","duration":"747.495868ms","start":"2026-08-22T03:39:34.106339Z","end":"2026-08-22T03:39:34.853835Z","steps":["trace[870552345] 'process raft request' (duration: 747.233223ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:39:34.853971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:39:34.181548Z","time spent":"672.382109ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T03:39:34.853971Z","caller":"traceutil/trace.go:171","msg":"trace[934432098] transaction","detail":"{read_only:false; response_revision:25035; number_of_response:1; }","duration":"227.79418ms","start":"2026-08-22T03:39:34.626136Z","end":"2026-08-22T03:39:34.85393Z","steps":["trace[934432098] 'process raft request' (duration: 227.730839ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:34.854011Z","caller":"traceutil/trace.go:171","msg":"trace[1930295546] transaction","detail":"{read_only:false; response_revision:25034; number_of_response:1; }","duration":"622.89776ms","start":"2026-08-22T03:39:34.231066Z","end":"2026-08-22T03:39:34.853964Z","steps":["trace[1930295546] 'process raft request' (duration: 622.740497ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:39:34.854096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.552613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T03:39:34.853984Z","caller":"traceutil/trace.go:171","msg":"trace[556213121] linearizableReadLoop","detail":"{readStateIndex:25841; appliedIndex:25835; }","duration":"632.42581ms","start":"2026-08-22T03:39:34.221551Z","end":"2026-08-22T03:39:34.853977Z","steps":["trace[556213121] 'read index received' (duration: 45.053726ms)","trace[556213121] 'applied index is now lower than readState.Index' (duration: 587.370504ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:39:34.854126Z","caller":"traceutil/trace.go:171","msg":"trace[1272667287] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25035; }","duration":"632.618494ms","start":"2026-08-22T03:39:34.221497Z","end":"2026-08-22T03:39:34.854116Z","steps":["trace[1272667287] 'agreement among raft nodes before linearized reading' (duration: 632.500442ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:39:34.854152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:39:34.221484Z","time spent":"632.661995ms","remote":"127.0.0.1:35498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":29,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true "} {"level":"warn","ts":"2026-08-22T03:39:34.854146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:39:34.106324Z","time spent":"747.679111ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T03:39:34.854175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:39:34.231044Z","time spent":"623.051293ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T03:39:34.878122Z","caller":"traceutil/trace.go:171","msg":"trace[1636426627] transaction","detail":"{read_only:false; response_revision:25036; number_of_response:1; }","duration":"248.621934ms","start":"2026-08-22T03:39:34.629482Z","end":"2026-08-22T03:39:34.878104Z","steps":["trace[1636426627] 'process raft request' (duration: 248.468881ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:34.878292Z","caller":"traceutil/trace.go:171","msg":"trace[849223577] transaction","detail":"{read_only:false; response_revision:25037; number_of_response:1; }","duration":"147.356741ms","start":"2026-08-22T03:39:34.730924Z","end":"2026-08-22T03:39:34.87828Z","steps":["trace[849223577] 'process raft request' (duration: 147.133136ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:39:34.878499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.05691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T03:39:34.878578Z","caller":"traceutil/trace.go:171","msg":"trace[1552312477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25037; }","duration":"434.191573ms","start":"2026-08-22T03:39:34.444366Z","end":"2026-08-22T03:39:34.878558Z","steps":["trace[1552312477] 'agreement among raft nodes before linearized reading' (duration: 433.905466ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:39:34.878612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:39:34.444342Z","time spent":"434.263354ms","remote":"127.0.0.1:34070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T03:39:34.878624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.284353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T03:39:34.878873Z","caller":"traceutil/trace.go:171","msg":"trace[854398668] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25037; }","duration":"416.609499ms","start":"2026-08-22T03:39:34.462165Z","end":"2026-08-22T03:39:34.878775Z","steps":["trace[854398668] 'agreement among raft nodes before linearized reading' (duration: 416.20371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:39:34.879094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:39:34.462144Z","time spent":"416.921754ms","remote":"127.0.0.1:35608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true "} {"level":"info","ts":"2026-08-22T03:39:35.11531Z","caller":"traceutil/trace.go:171","msg":"trace[1033859099] linearizableReadLoop","detail":"{readStateIndex:25846; appliedIndex:25845; }","duration":"235.642079ms","start":"2026-08-22T03:39:34.879639Z","end":"2026-08-22T03:39:35.115281Z","steps":["trace[1033859099] 'read index received' (duration: 234.256983ms)","trace[1033859099] 'applied index is now lower than readState.Index' (duration: 1.383506ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:39:35.115428Z","caller":"traceutil/trace.go:171","msg":"trace[1916961308] transaction","detail":"{read_only:false; response_revision:25038; number_of_response:1; }","duration":"240.45558ms","start":"2026-08-22T03:39:34.874855Z","end":"2026-08-22T03:39:35.115311Z","steps":["trace[1916961308] 'process raft request' (duration: 239.022864ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:39:35.115535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.856063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T03:39:35.115626Z","caller":"traceutil/trace.go:171","msg":"trace[1442234748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25038; }","duration":"236.001895ms","start":"2026-08-22T03:39:34.879586Z","end":"2026-08-22T03:39:35.115587Z","steps":["trace[1442234748] 'agreement among raft nodes before linearized reading' (duration: 235.819112ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:35.195429Z","caller":"traceutil/trace.go:171","msg":"trace[1073807196] transaction","detail":"{read_only:false; response_revision:25039; number_of_response:1; }","duration":"275.822923ms","start":"2026-08-22T03:39:34.919568Z","end":"2026-08-22T03:39:35.19539Z","steps":["trace[1073807196] 'process raft request' (duration: 275.582178ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:35.195848Z","caller":"traceutil/trace.go:171","msg":"trace[1553286920] transaction","detail":"{read_only:false; response_revision:25040; number_of_response:1; }","duration":"225.958116ms","start":"2026-08-22T03:39:34.969847Z","end":"2026-08-22T03:39:35.195805Z","steps":["trace[1553286920] 'process raft request' (duration: 225.493217ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:39:35.196623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.310916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T03:39:35.196674Z","caller":"traceutil/trace.go:171","msg":"trace[1934188653] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25041; }","duration":"221.383557ms","start":"2026-08-22T03:39:34.975278Z","end":"2026-08-22T03:39:35.196662Z","steps":["trace[1934188653] 'agreement among raft nodes before linearized reading' (duration: 221.274385ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:35.197003Z","caller":"traceutil/trace.go:171","msg":"trace[71208355] transaction","detail":"{read_only:false; response_revision:25041; number_of_response:1; }","duration":"220.763026ms","start":"2026-08-22T03:39:34.976189Z","end":"2026-08-22T03:39:35.196952Z","steps":["trace[71208355] 'process raft request' (duration: 219.504942ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:41.186783Z","caller":"traceutil/trace.go:171","msg":"trace[1859139211] linearizableReadLoop","detail":"{readStateIndex:25897; appliedIndex:25895; }","duration":"305.784262ms","start":"2026-08-22T03:39:40.880972Z","end":"2026-08-22T03:39:41.186756Z","steps":["trace[1859139211] 'read index received' (duration: 90.418128ms)","trace[1859139211] 'applied index is now lower than readState.Index' (duration: 215.364444ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:39:41.187094Z","caller":"traceutil/trace.go:171","msg":"trace[702057738] transaction","detail":"{read_only:false; response_revision:25087; number_of_response:1; }","duration":"308.335651ms","start":"2026-08-22T03:39:40.878744Z","end":"2026-08-22T03:39:41.187079Z","steps":["trace[702057738] 'process raft request' (duration: 218.982263ms)","trace[702057738] 'compare' (duration: 88.562003ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:39:41.187221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:39:40.878716Z","time spent":"308.424963ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T03:39:41.187295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.424506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-22T03:39:41.187351Z","caller":"traceutil/trace.go:171","msg":"trace[326535565] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25088; }","duration":"304.524609ms","start":"2026-08-22T03:39:40.882814Z","end":"2026-08-22T03:39:41.187339Z","steps":["trace[326535565] 'agreement among raft nodes before linearized reading' (duration: 304.379226ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:39:41.187375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:39:40.8828Z","time spent":"304.570199ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T03:39:41.18752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.531527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-22T03:39:41.187589Z","caller":"traceutil/trace.go:171","msg":"trace[854332585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25088; }","duration":"306.69351ms","start":"2026-08-22T03:39:40.880883Z","end":"2026-08-22T03:39:41.187577Z","steps":["trace[854332585] 'agreement among raft nodes before linearized reading' (duration: 306.426985ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:39:41.187635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:39:40.88085Z","time spent":"306.777901ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T03:39:41.187629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.579264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-22T03:39:41.187674Z","caller":"traceutil/trace.go:171","msg":"trace[1523450400] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25088; }","duration":"300.668506ms","start":"2026-08-22T03:39:40.886992Z","end":"2026-08-22T03:39:41.18766Z","steps":["trace[1523450400] 'agreement among raft nodes before linearized reading' (duration: 300.525403ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:39:41.187713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:39:40.886969Z","time spent":"300.736857ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-22T03:39:41.187809Z","caller":"traceutil/trace.go:171","msg":"trace[15143481] transaction","detail":"{read_only:false; response_revision:25088; number_of_response:1; }","duration":"294.357625ms","start":"2026-08-22T03:39:40.89344Z","end":"2026-08-22T03:39:41.187798Z","steps":["trace[15143481] 'process raft request' (duration: 293.172442ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:39:41.187892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.755038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-22T03:39:41.187968Z","caller":"traceutil/trace.go:171","msg":"trace[409699230] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25088; }","duration":"300.856729ms","start":"2026-08-22T03:39:40.887097Z","end":"2026-08-22T03:39:41.187954Z","steps":["trace[409699230] 'agreement among raft nodes before linearized reading' (duration: 300.630645ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T03:39:41.188012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T03:39:40.88709Z","time spent":"300.91464ms","remote":"127.0.0.1:34382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-22T03:39:41.339838Z","caller":"traceutil/trace.go:171","msg":"trace[1662786481] transaction","detail":"{read_only:false; response_revision:25089; number_of_response:1; }","duration":"145.21474ms","start":"2026-08-22T03:39:41.194599Z","end":"2026-08-22T03:39:41.339814Z","steps":["trace[1662786481] 'process raft request' (duration: 122.774944ms)","trace[1662786481] 'compare' (duration: 22.339084ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T03:39:41.353787Z","caller":"traceutil/trace.go:171","msg":"trace[1671356503] transaction","detail":"{read_only:false; response_revision:25091; number_of_response:1; }","duration":"157.126127ms","start":"2026-08-22T03:39:41.196624Z","end":"2026-08-22T03:39:41.35375Z","steps":["trace[1671356503] 'process raft request' (duration: 157.019755ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:41.353833Z","caller":"traceutil/trace.go:171","msg":"trace[1278848915] transaction","detail":"{read_only:false; response_revision:25090; number_of_response:1; }","duration":"157.27417ms","start":"2026-08-22T03:39:41.196509Z","end":"2026-08-22T03:39:41.353783Z","steps":["trace[1278848915] 'process raft request' (duration: 156.918203ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:41.354331Z","caller":"traceutil/trace.go:171","msg":"trace[631003956] transaction","detail":"{read_only:false; response_revision:25093; number_of_response:1; }","duration":"132.951567ms","start":"2026-08-22T03:39:41.221364Z","end":"2026-08-22T03:39:41.354316Z","steps":["trace[631003956] 'process raft request' (duration: 132.752493ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:41.354302Z","caller":"traceutil/trace.go:171","msg":"trace[1924015675] transaction","detail":"{read_only:false; response_revision:25092; number_of_response:1; }","duration":"157.572355ms","start":"2026-08-22T03:39:41.19669Z","end":"2026-08-22T03:39:41.354262Z","steps":["trace[1924015675] 'process raft request' (duration: 156.999514ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:41.354482Z","caller":"traceutil/trace.go:171","msg":"trace[1114818250] transaction","detail":"{read_only:false; response_revision:25094; number_of_response:1; }","duration":"132.927376ms","start":"2026-08-22T03:39:41.221448Z","end":"2026-08-22T03:39:41.354375Z","steps":["trace[1114818250] 'process raft request' (duration: 132.795893ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:41.354482Z","caller":"traceutil/trace.go:171","msg":"trace[1294469959] transaction","detail":"{read_only:false; response_revision:25095; number_of_response:1; }","duration":"132.957967ms","start":"2026-08-22T03:39:41.221473Z","end":"2026-08-22T03:39:41.354431Z","steps":["trace[1294469959] 'process raft request' (duration: 132.831834ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:39:41.354733Z","caller":"traceutil/trace.go:171","msg":"trace[857840830] linearizableReadLoop","detail":"{readStateIndex:25905; appliedIndex:25898; }","duration":"120.341927ms","start":"2026-08-22T03:39:41.234374Z","end":"2026-08-22T03:39:41.354716Z","steps":["trace[857840830] 'read index received' (duration: 82.941507ms)","trace[857840830] 'applied index is now lower than readState.Index' (duration: 37.39812ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T03:39:41.354872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.460829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T03:39:41.35492Z","caller":"traceutil/trace.go:171","msg":"trace[985830621] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:25095; }","duration":"120.581812ms","start":"2026-08-22T03:39:41.234326Z","end":"2026-08-22T03:39:41.354908Z","steps":["trace[985830621] 'agreement among raft nodes before linearized reading' (duration: 120.465569ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T03:40:15.484663Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000066ea.wal"} {"level":"info","ts":"2026-08-22T03:41:47.576398Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23485} {"level":"info","ts":"2026-08-22T03:41:47.724553Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23485,"took":"144.582768ms","hash":1289247056,"current-db-size-bytes":61030400,"current-db-size":"61 MB","current-db-size-in-use-bytes":37249024,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-08-22T03:41:47.724944Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1289247056,"revision":23485,"compact-revision":20159} {"level":"info","ts":"2026-08-22T03:46:47.584924Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26347} {"level":"info","ts":"2026-08-22T03:46:47.760654Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26347,"took":"170.713643ms","hash":530442064,"current-db-size-bytes":61030400,"current-db-size":"61 MB","current-db-size-in-use-bytes":33599488,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-22T03:46:47.760728Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":530442064,"revision":26347,"compact-revision":23485} {"level":"info","ts":"2026-08-22T03:47:10.765088Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1a532052a1358225","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T03:47:10.77065Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-22T03:47:10.770783Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-22T03:51:47.59359Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28921} {"level":"info","ts":"2026-08-22T03:51:47.737605Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28921,"took":"140.068245ms","hash":1375558897,"current-db-size-bytes":61030400,"current-db-size":"61 MB","current-db-size-in-use-bytes":30392320,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-22T03:51:47.737707Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1375558897,"revision":28921,"compact-revision":26347} {"level":"info","ts":"2026-08-22T03:56:47.601882Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31136} {"level":"info","ts":"2026-08-22T03:56:47.72873Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31136,"took":"123.189899ms","hash":3303850098,"current-db-size-bytes":61030400,"current-db-size":"61 MB","current-db-size-in-use-bytes":30461952,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-22T03:56:47.728796Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3303850098,"revision":31136,"compact-revision":28921} {"level":"info","ts":"2026-08-22T04:01:47.612284Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33405} {"level":"info","ts":"2026-08-22T04:01:47.75829Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33405,"took":"138.095518ms","hash":2872363688,"current-db-size-bytes":61030400,"current-db-size":"61 MB","current-db-size-in-use-bytes":32604160,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-22T04:01:47.758362Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2872363688,"revision":33405,"compact-revision":31136} {"level":"info","ts":"2026-08-22T04:06:47.619648Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35758} {"level":"info","ts":"2026-08-22T04:06:47.766512Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35758,"took":"141.478277ms","hash":2159026402,"current-db-size-bytes":62296064,"current-db-size":"62 MB","current-db-size-in-use-bytes":32366592,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T04:06:47.766589Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2159026402,"revision":35758,"compact-revision":33405} {"level":"info","ts":"2026-08-22T04:08:29.161177Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1a532052a1358225","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T04:08:29.167954Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-08-22T04:08:29.168144Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-08-22T04:11:47.628958Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38022} {"level":"info","ts":"2026-08-22T04:11:47.748473Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38022,"took":"115.853578ms","hash":1825897255,"current-db-size-bytes":62296064,"current-db-size":"62 MB","current-db-size-in-use-bytes":31776768,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T04:11:47.748539Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1825897255,"revision":38022,"compact-revision":35758}