{"level":"warn","ts":"2026-07-17T03:41:37.958864Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T03:41:37.958981Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229: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-07-17T03:41:37.959071Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T03:41:37.95908Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-07-17T03:41:37.959109Z","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-07-17T03:41:37.959832Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]} {"level":"info","ts":"2026-07-17T03:41:37.959917Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229: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-07-17T03:41:37.964764Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.577921ms"} {"level":"info","ts":"2026-07-17T03:41:37.973075Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-07-17T03:41:37.973182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-07-17T03:41:37.973214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-07-17T03:41:37.97323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-17T03:41:37.973244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-07-17T03:41:37.973291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-07-17T03:41:37.984954Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-17T03:41:37.98678Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-17T03:41:37.987988Z","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-07-17T03:41:37.99018Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-17T03:41:37.990453Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-17T03:41:37.990515Z","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-07-17T03:41:37.990659Z","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-07-17T03:41:37.990698Z","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-07-17T03:41:37.993212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-07-17T03:41:37.993423Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-07-17T03:41:37.995689Z","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-07-17T03:41:37.996016Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-07-17T03:41:37.996086Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-07-17T03:41:37.996119Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-17T03:41:37.996168Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-17T03:41:38.074279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-07-17T03:41:38.074366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-07-17T03:41:38.074387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-07-17T03:41:38.074407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-07-17T03:41:38.074416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-07-17T03:41:38.074432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-07-17T03:41:38.074443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-07-17T03:41:38.075588Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T03:41:38.07672Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"} {"level":"info","ts":"2026-07-17T03:41:38.076774Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T03:41:38.076817Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T03:41:38.077101Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-17T03:41:38.077309Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-17T03:41:38.077194Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T03:41:38.077487Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T03:41:38.077549Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T03:41:38.081857Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"info","ts":"2026-07-17T03:41:38.083427Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-17T03:42:22.515214Z","caller":"traceutil/trace.go:171","msg":"trace[12022251] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"165.230377ms","start":"2026-07-17T03:42:22.349955Z","end":"2026-07-17T03:42:22.515185Z","steps":["trace[12022251] 'read index received' (duration: 164.429953ms)","trace[12022251] 'applied index is now lower than readState.Index' (duration: 799.134µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:42:22.515293Z","caller":"traceutil/trace.go:171","msg":"trace[1546623535] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"277.180011ms","start":"2026-07-17T03:42:22.238045Z","end":"2026-07-17T03:42:22.515225Z","steps":["trace[1546623535] 'process raft request' (duration: 276.547882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:42:22.515669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.263213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-17T03:42:22.515872Z","caller":"traceutil/trace.go:171","msg":"trace[31100413] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:638; }","duration":"125.430248ms","start":"2026-07-17T03:42:22.390318Z","end":"2026-07-17T03:42:22.515748Z","steps":["trace[31100413] 'agreement among raft nodes before linearized reading' (duration: 125.089238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:42:22.516223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.563238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vw88k\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-17T03:42:22.516312Z","caller":"traceutil/trace.go:171","msg":"trace[101457087] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vw88k; range_end:; response_count:1; response_revision:638; }","duration":"166.434683ms","start":"2026-07-17T03:42:22.349846Z","end":"2026-07-17T03:42:22.51628Z","steps":["trace[101457087] 'agreement among raft nodes before linearized reading' (duration: 165.511136ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:42:23.041034Z","caller":"traceutil/trace.go:171","msg":"trace[945303236] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"443.270344ms","start":"2026-07-17T03:42:22.597725Z","end":"2026-07-17T03:42:23.040995Z","steps":["trace[945303236] 'process raft request' (duration: 443.000216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:42:23.041814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:42:22.597695Z","time spent":"443.453019ms","remote":"127.0.0.1:40750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T03:43:51.216923Z","caller":"traceutil/trace.go:171","msg":"trace[40318952] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1937; }","duration":"307.465966ms","start":"2026-07-17T03:43:50.909437Z","end":"2026-07-17T03:43:51.216903Z","steps":["trace[40318952] 'read index received' (duration: 307.300901ms)","trace[40318952] 'applied index is now lower than readState.Index' (duration: 164.115µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:43:51.217038Z","caller":"traceutil/trace.go:171","msg":"trace[969531196] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"350.813147ms","start":"2026-07-17T03:43:50.866176Z","end":"2026-07-17T03:43:51.216989Z","steps":["trace[969531196] 'process raft request' (duration: 350.581361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:43:51.217294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.478651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-17T03:43:51.217336Z","caller":"traceutil/trace.go:171","msg":"trace[47980303] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1866; }","duration":"138.581295ms","start":"2026-07-17T03:43:51.078746Z","end":"2026-07-17T03:43:51.217327Z","steps":["trace[47980303] 'agreement among raft nodes before linearized reading' (duration: 138.4006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:43:51.217454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.322516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3e7a50bc-b8a1-4091-96f9-9da20159e040\" ","response":"range_response_count:1 size:3747"} {"level":"warn","ts":"2026-07-17T03:43:51.217522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:43:50.86615Z","time spent":"350.997163ms","remote":"127.0.0.1:40392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:43:51.217561Z","caller":"traceutil/trace.go:171","msg":"trace[973926321] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3e7a50bc-b8a1-4091-96f9-9da20159e040; range_end:; response_count:1; response_revision:1866; }","duration":"119.475991ms","start":"2026-07-17T03:43:51.098058Z","end":"2026-07-17T03:43:51.217534Z","steps":["trace[973926321] 'agreement among raft nodes before linearized reading' (duration: 119.232004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:43:51.217608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.668796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:43:51.217656Z","caller":"traceutil/trace.go:171","msg":"trace[1334691992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1866; }","duration":"209.753339ms","start":"2026-07-17T03:43:51.007893Z","end":"2026-07-17T03:43:51.217646Z","steps":["trace[1334691992] 'agreement among raft nodes before linearized reading' (duration: 209.205683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:43:51.217826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.370113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-17T03:43:51.217836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.090469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-17T03:43:51.218049Z","caller":"traceutil/trace.go:171","msg":"trace[1886041494] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1866; }","duration":"142.347407ms","start":"2026-07-17T03:43:51.075675Z","end":"2026-07-17T03:43:51.218022Z","steps":["trace[1886041494] 'agreement among raft nodes before linearized reading' (duration: 141.556893ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:43:51.2179Z","caller":"traceutil/trace.go:171","msg":"trace[662162939] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1866; }","duration":"308.519047ms","start":"2026-07-17T03:43:50.909366Z","end":"2026-07-17T03:43:51.217885Z","steps":["trace[662162939] 'agreement among raft nodes before linearized reading' (duration: 307.689222ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:43:51.21825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:43:50.909334Z","time spent":"308.897078ms","remote":"127.0.0.1:56224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-17T03:44:41.152488Z","caller":"traceutil/trace.go:171","msg":"trace[1736964935] linearizableReadLoop","detail":"{readStateIndex:2273; appliedIndex:2272; }","duration":"145.718284ms","start":"2026-07-17T03:44:41.006734Z","end":"2026-07-17T03:44:41.152452Z","steps":["trace[1736964935] 'read index received' (duration: 145.061873ms)","trace[1736964935] 'applied index is now lower than readState.Index' (duration: 654.731µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:44:41.152569Z","caller":"traceutil/trace.go:171","msg":"trace[1709939865] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"158.691862ms","start":"2026-07-17T03:44:40.993833Z","end":"2026-07-17T03:44:41.152524Z","steps":["trace[1709939865] 'process raft request' (duration: 158.366822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:44:41.152745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.971222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:44:41.152877Z","caller":"traceutil/trace.go:171","msg":"trace[1511924910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2191; }","duration":"146.147227ms","start":"2026-07-17T03:44:41.006704Z","end":"2026-07-17T03:44:41.152851Z","steps":["trace[1511924910] 'agreement among raft nodes before linearized reading' (duration: 145.91796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:44:41.92208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.00075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T03:44:41.922403Z","caller":"traceutil/trace.go:171","msg":"trace[1478517288] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"275.763894ms","start":"2026-07-17T03:44:41.646589Z","end":"2026-07-17T03:44:41.922353Z","steps":["trace[1478517288] 'process raft request' (duration: 145.099854ms)","trace[1478517288] 'compare' (duration: 129.69ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:44:41.922517Z","caller":"traceutil/trace.go:171","msg":"trace[550673990] linearizableReadLoop","detail":"{readStateIndex:2276; appliedIndex:2275; }","duration":"265.407538ms","start":"2026-07-17T03:44:41.657071Z","end":"2026-07-17T03:44:41.922479Z","steps":["trace[550673990] 'read index received' (duration: 134.634825ms)","trace[550673990] 'applied index is now lower than readState.Index' (duration: 130.767963ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:44:41.92256Z","caller":"traceutil/trace.go:171","msg":"trace[1525507093] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"263.24609ms","start":"2026-07-17T03:44:41.659266Z","end":"2026-07-17T03:44:41.922512Z","steps":["trace[1525507093] 'process raft request' (duration: 263.048754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:44:41.922901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.812141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-17T03:44:41.922975Z","caller":"traceutil/trace.go:171","msg":"trace[328500272] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2194; }","duration":"265.923284ms","start":"2026-07-17T03:44:41.657035Z","end":"2026-07-17T03:44:41.922958Z","steps":["trace[328500272] 'agreement among raft nodes before linearized reading' (duration: 265.573753ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:50:19.580903Z","caller":"traceutil/trace.go:171","msg":"trace[452032597] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"128.331106ms","start":"2026-07-17T03:50:19.45254Z","end":"2026-07-17T03:50:19.580871Z","steps":["trace[452032597] 'process raft request' (duration: 128.12285ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:50:24.733779Z","caller":"traceutil/trace.go:171","msg":"trace[1416902698] linearizableReadLoop","detail":"{readStateIndex:5317; appliedIndex:5316; }","duration":"216.366078ms","start":"2026-07-17T03:50:24.517385Z","end":"2026-07-17T03:50:24.733751Z","steps":["trace[1416902698] 'read index received' (duration: 216.160552ms)","trace[1416902698] 'applied index is now lower than readState.Index' (duration: 204.346µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:50:24.734047Z","caller":"traceutil/trace.go:171","msg":"trace[1188669673] transaction","detail":"{read_only:false; response_revision:5123; number_of_response:1; }","duration":"244.436609ms","start":"2026-07-17T03:50:24.489579Z","end":"2026-07-17T03:50:24.734015Z","steps":["trace[1188669673] 'process raft request' (duration: 243.992066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:50:24.734303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.889064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T03:50:24.734391Z","caller":"traceutil/trace.go:171","msg":"trace[657582049] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:5123; }","duration":"217.042198ms","start":"2026-07-17T03:50:24.517327Z","end":"2026-07-17T03:50:24.734369Z","steps":["trace[657582049] 'agreement among raft nodes before linearized reading' (duration: 216.821221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:50:24.740606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.442108ms","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-07-17T03:50:24.740698Z","caller":"traceutil/trace.go:171","msg":"trace[1529176706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5124; }","duration":"161.592963ms","start":"2026-07-17T03:50:24.579086Z","end":"2026-07-17T03:50:24.740679Z","steps":["trace[1529176706] 'agreement among raft nodes before linearized reading' (duration: 161.335215ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:50:26.266438Z","caller":"traceutil/trace.go:171","msg":"trace[1087416813] transaction","detail":"{read_only:false; response_revision:5161; number_of_response:1; }","duration":"129.68747ms","start":"2026-07-17T03:50:26.136711Z","end":"2026-07-17T03:50:26.266399Z","steps":["trace[1087416813] 'process raft request' (duration: 129.280897ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:50:26.267031Z","caller":"traceutil/trace.go:171","msg":"trace[1733164312] transaction","detail":"{read_only:false; response_revision:5162; number_of_response:1; }","duration":"116.768094ms","start":"2026-07-17T03:50:26.150245Z","end":"2026-07-17T03:50:26.267013Z","steps":["trace[1733164312] 'process raft request' (duration: 115.983629ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:50:26.266918Z","caller":"traceutil/trace.go:171","msg":"trace[1560614068] linearizableReadLoop","detail":"{readStateIndex:5356; appliedIndex:5354; }","duration":"123.257148ms","start":"2026-07-17T03:50:26.14363Z","end":"2026-07-17T03:50:26.266887Z","steps":["trace[1560614068] 'read index received' (duration: 78.676575ms)","trace[1560614068] 'applied index is now lower than readState.Index' (duration: 44.579393ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:50:26.267484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.831036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-20a994cd-79d4-45e4-b7c2-2168016261b1\" ","response":"range_response_count:1 size:3894"} {"level":"info","ts":"2026-07-17T03:50:26.267609Z","caller":"traceutil/trace.go:171","msg":"trace[550225814] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-20a994cd-79d4-45e4-b7c2-2168016261b1; range_end:; response_count:1; response_revision:5162; }","duration":"124.017732ms","start":"2026-07-17T03:50:26.143572Z","end":"2026-07-17T03:50:26.26759Z","steps":["trace[550225814] 'agreement among raft nodes before linearized reading' (duration: 123.64727ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:50:38.718171Z","caller":"traceutil/trace.go:171","msg":"trace[1970579250] transaction","detail":"{read_only:false; response_revision:5399; number_of_response:1; }","duration":"216.682601ms","start":"2026-07-17T03:50:38.501443Z","end":"2026-07-17T03:50:38.718126Z","steps":["trace[1970579250] 'process raft request' (duration: 216.349151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:50:48.106495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.51231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-17T03:50:48.106522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.684749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jjtr7\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-07-17T03:50:48.106569Z","caller":"traceutil/trace.go:171","msg":"trace[430829131] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5568; }","duration":"167.606363ms","start":"2026-07-17T03:50:47.938945Z","end":"2026-07-17T03:50:48.106551Z","steps":["trace[430829131] 'range keys from in-memory index tree' (duration: 167.420737ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:50:48.106634Z","caller":"traceutil/trace.go:171","msg":"trace[416341168] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jjtr7; range_end:; response_count:1; response_revision:5568; }","duration":"218.840113ms","start":"2026-07-17T03:50:47.887756Z","end":"2026-07-17T03:50:48.106596Z","steps":["trace[416341168] 'range keys from in-memory index tree' (duration: 218.38674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:50:48.106659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.386915ms","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-07-17T03:50:48.106704Z","caller":"traceutil/trace.go:171","msg":"trace[985795047] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5568; }","duration":"168.463178ms","start":"2026-07-17T03:50:47.938229Z","end":"2026-07-17T03:50:48.106692Z","steps":["trace[985795047] 'range keys from in-memory index tree' (duration: 167.950812ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:51:38.481661Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3020} {"level":"info","ts":"2026-07-17T03:51:38.572554Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3020,"took":"88.978064ms","hash":188117843,"current-db-size-bytes":57356288,"current-db-size":"57 MB","current-db-size-in-use-bytes":31625216,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-17T03:51:38.572632Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":188117843,"revision":3020,"compact-revision":-1} {"level":"info","ts":"2026-07-17T03:56:38.489273Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6073} {"level":"info","ts":"2026-07-17T03:56:38.591399Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6073,"took":"99.431121ms","hash":3711523672,"current-db-size-bytes":57356288,"current-db-size":"57 MB","current-db-size-in-use-bytes":21876736,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-07-17T03:56:38.591491Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3711523672,"revision":6073,"compact-revision":3020} {"level":"warn","ts":"2026-07-17T03:57:14.395078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.144254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-17T03:57:14.395226Z","caller":"traceutil/trace.go:171","msg":"trace[791845067] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:8510; }","duration":"126.35645ms","start":"2026-07-17T03:57:14.268838Z","end":"2026-07-17T03:57:14.395195Z","steps":["trace[791845067] 'range keys from in-memory index tree' (duration: 125.906547ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:57:14.425213Z","caller":"traceutil/trace.go:171","msg":"trace[1151169579] transaction","detail":"{read_only:false; response_revision:8511; number_of_response:1; }","duration":"146.328997ms","start":"2026-07-17T03:57:14.278837Z","end":"2026-07-17T03:57:14.425166Z","steps":["trace[1151169579] 'process raft request' (duration: 146.168982ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:57:14.679097Z","caller":"traceutil/trace.go:171","msg":"trace[736744945] transaction","detail":"{read_only:false; response_revision:8512; number_of_response:1; }","duration":"296.148608ms","start":"2026-07-17T03:57:14.382912Z","end":"2026-07-17T03:57:14.67906Z","steps":["trace[736744945] 'process raft request' (duration: 268.200007ms)","trace[736744945] 'compare' (duration: 27.405215ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:57:14.679241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.120546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-600d970f-174f-4633-9253-8cc45fffbb73\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-17T03:57:14.679321Z","caller":"traceutil/trace.go:171","msg":"trace[146826849] range","detail":"{range_begin:/registry/persistentvolumes/pvc-600d970f-174f-4633-9253-8cc45fffbb73; range_end:; response_count:1; response_revision:8512; }","duration":"280.208359ms","start":"2026-07-17T03:57:14.399095Z","end":"2026-07-17T03:57:14.679303Z","steps":["trace[146826849] 'agreement among raft nodes before linearized reading' (duration: 280.024474ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:57:14.679084Z","caller":"traceutil/trace.go:171","msg":"trace[1817586543] linearizableReadLoop","detail":"{readStateIndex:8817; appliedIndex:8815; }","duration":"279.931261ms","start":"2026-07-17T03:57:14.3991Z","end":"2026-07-17T03:57:14.679031Z","steps":["trace[1817586543] 'read index received' (duration: 25.863149ms)","trace[1817586543] 'applied index is now lower than readState.Index' (duration: 254.067252ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:57:15.455561Z","caller":"traceutil/trace.go:171","msg":"trace[908868873] transaction","detail":"{read_only:false; response_revision:8514; number_of_response:1; }","duration":"113.178964ms","start":"2026-07-17T03:57:15.342335Z","end":"2026-07-17T03:57:15.455514Z","steps":["trace[908868873] 'process raft request' (duration: 66.296537ms)","trace[908868873] 'compare' (duration: 46.572378ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:57:25.507862Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000022b1.wal"} {"level":"info","ts":"2026-07-17T03:57:44.636067Z","caller":"traceutil/trace.go:171","msg":"trace[402033712] transaction","detail":"{read_only:false; response_revision:8726; number_of_response:1; }","duration":"210.504663ms","start":"2026-07-17T03:57:44.425538Z","end":"2026-07-17T03:57:44.636042Z","steps":["trace[402033712] 'process raft request' (duration: 210.329468ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:57:44.666174Z","caller":"traceutil/trace.go:171","msg":"trace[1181541783] linearizableReadLoop","detail":"{readStateIndex:9040; appliedIndex:9038; }","duration":"235.577159ms","start":"2026-07-17T03:57:44.43056Z","end":"2026-07-17T03:57:44.666138Z","steps":["trace[1181541783] 'read index received' (duration: 205.30606ms)","trace[1181541783] 'applied index is now lower than readState.Index' (duration: 30.269949ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:57:44.666253Z","caller":"traceutil/trace.go:171","msg":"trace[640347327] transaction","detail":"{read_only:false; response_revision:8727; number_of_response:1; }","duration":"240.466652ms","start":"2026-07-17T03:57:44.425737Z","end":"2026-07-17T03:57:44.666203Z","steps":["trace[640347327] 'process raft request' (duration: 240.231225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:57:44.666579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.496311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-17T03:57:44.666576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.959451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-17T03:57:44.666686Z","caller":"traceutil/trace.go:171","msg":"trace[260273489] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8727; }","duration":"236.119235ms","start":"2026-07-17T03:57:44.430542Z","end":"2026-07-17T03:57:44.666661Z","steps":["trace[260273489] 'agreement among raft nodes before linearized reading' (duration: 235.762353ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:57:44.66662Z","caller":"traceutil/trace.go:171","msg":"trace[1545130126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8727; }","duration":"197.605524ms","start":"2026-07-17T03:57:44.469004Z","end":"2026-07-17T03:57:44.666609Z","steps":["trace[1545130126] 'agreement among raft nodes before linearized reading' (duration: 197.341196ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:57:44.832134Z","caller":"traceutil/trace.go:171","msg":"trace[11302153] linearizableReadLoop","detail":"{readStateIndex:9042; appliedIndex:9041; }","duration":"139.910969ms","start":"2026-07-17T03:57:44.692201Z","end":"2026-07-17T03:57:44.832112Z","steps":["trace[11302153] 'read index received' (duration: 139.702223ms)","trace[11302153] 'applied index is now lower than readState.Index' (duration: 208.326µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:57:44.832498Z","caller":"traceutil/trace.go:171","msg":"trace[1781081825] transaction","detail":"{read_only:false; response_revision:8729; number_of_response:1; }","duration":"143.330949ms","start":"2026-07-17T03:57:44.689134Z","end":"2026-07-17T03:57:44.832465Z","steps":["trace[1781081825] 'process raft request' (duration: 142.829244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:57:44.832707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.434754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T03:57:44.832777Z","caller":"traceutil/trace.go:171","msg":"trace[1161035591] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:8729; }","duration":"140.577959ms","start":"2026-07-17T03:57:44.692181Z","end":"2026-07-17T03:57:44.832759Z","steps":["trace[1161035591] 'agreement among raft nodes before linearized reading' (duration: 140.305481ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:57:44.833005Z","caller":"traceutil/trace.go:171","msg":"trace[1065253085] transaction","detail":"{read_only:false; response_revision:8730; number_of_response:1; }","duration":"138.518598ms","start":"2026-07-17T03:57:44.694473Z","end":"2026-07-17T03:57:44.832991Z","steps":["trace[1065253085] 'process raft request' (duration: 138.062255ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:57:48.258858Z","caller":"traceutil/trace.go:171","msg":"trace[357578500] transaction","detail":"{read_only:false; response_revision:8746; number_of_response:1; }","duration":"146.983867ms","start":"2026-07-17T03:57:48.111838Z","end":"2026-07-17T03:57:48.258821Z","steps":["trace[357578500] 'process raft request' (duration: 76.237949ms)","trace[357578500] 'compare' (duration: 70.1372ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:57:58.100605Z","caller":"traceutil/trace.go:171","msg":"trace[1695033117] transaction","detail":"{read_only:false; response_revision:8839; number_of_response:1; }","duration":"103.265573ms","start":"2026-07-17T03:57:57.997306Z","end":"2026-07-17T03:57:58.100572Z","steps":["trace[1695033117] 'process raft request' (duration: 103.077168ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:57:58.338301Z","caller":"traceutil/trace.go:171","msg":"trace[387824443] linearizableReadLoop","detail":"{readStateIndex:9160; appliedIndex:9159; }","duration":"160.819034ms","start":"2026-07-17T03:57:58.177454Z","end":"2026-07-17T03:57:58.338273Z","steps":["trace[387824443] 'read index received' (duration: 77.735333ms)","trace[387824443] 'applied index is now lower than readState.Index' (duration: 83.082501ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:57:58.338445Z","caller":"traceutil/trace.go:171","msg":"trace[1669566856] transaction","detail":"{read_only:false; response_revision:8840; number_of_response:1; }","duration":"329.308352ms","start":"2026-07-17T03:57:58.009075Z","end":"2026-07-17T03:57:58.338383Z","steps":["trace[1669566856] 'process raft request' (duration: 246.099038ms)","trace[1669566856] 'compare' (duration: 82.960677ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:57:58.338527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.130423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-xtq2q\" ","response":"range_response_count:1 size:4653"} {"level":"info","ts":"2026-07-17T03:57:58.338568Z","caller":"traceutil/trace.go:171","msg":"trace[496143653] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-xtq2q; range_end:; response_count:1; response_revision:8840; }","duration":"161.242976ms","start":"2026-07-17T03:57:58.177315Z","end":"2026-07-17T03:57:58.338558Z","steps":["trace[496143653] 'agreement among raft nodes before linearized reading' (duration: 161.084621ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:57:58.338695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T03:57:58.009037Z","time spent":"329.518528ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T03:57:58.340114Z","caller":"traceutil/trace.go:171","msg":"trace[1704236611] transaction","detail":"{read_only:false; response_revision:8842; number_of_response:1; }","duration":"151.991685ms","start":"2026-07-17T03:57:58.188108Z","end":"2026-07-17T03:57:58.3401Z","steps":["trace[1704236611] 'process raft request' (duration: 151.942152ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:57:58.340551Z","caller":"traceutil/trace.go:171","msg":"trace[1263632206] transaction","detail":"{read_only:false; response_revision:8841; number_of_response:1; }","duration":"162.969317ms","start":"2026-07-17T03:57:58.177575Z","end":"2026-07-17T03:57:58.340544Z","steps":["trace[1263632206] 'process raft request' (duration: 162.40434ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T03:57:58.900253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.591919ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T03:57:58.900351Z","caller":"traceutil/trace.go:171","msg":"trace[1966870014] linearizableReadLoop","detail":"{readStateIndex:9166; appliedIndex:9165; }","duration":"151.465048ms","start":"2026-07-17T03:57:58.748871Z","end":"2026-07-17T03:57:58.900336Z","steps":["trace[1966870014] 'read index received' (duration: 28.525569ms)","trace[1966870014] 'applied index is now lower than readState.Index' (duration: 122.938519ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T03:57:58.900486Z","caller":"traceutil/trace.go:171","msg":"trace[826126487] transaction","detail":"{read_only:false; response_revision:8846; number_of_response:1; }","duration":"157.201198ms","start":"2026-07-17T03:57:58.743224Z","end":"2026-07-17T03:57:58.900425Z","steps":["trace[826126487] 'process raft request' (duration: 34.35392ms)","trace[826126487] 'compare' (duration: 122.483746ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T03:57:58.900763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.607994ms","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-07-17T03:57:58.900814Z","caller":"traceutil/trace.go:171","msg":"trace[1766912835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8846; }","duration":"152.004215ms","start":"2026-07-17T03:57:58.748796Z","end":"2026-07-17T03:57:58.9008Z","steps":["trace[1766912835] 'agreement among raft nodes before linearized reading' (duration: 151.576241ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T03:59:21.959762Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3ba9ae53cc4335db","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-17T03:59:21.965792Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-17T03:59:21.965916Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-17T04:01:38.496351Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8141} {"level":"info","ts":"2026-07-17T04:01:38.563114Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8141,"took":"64.617953ms","hash":3046063757,"current-db-size-bytes":57356288,"current-db-size":"57 MB","current-db-size-in-use-bytes":28663808,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-17T04:01:38.563185Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3046063757,"revision":8141,"compact-revision":6073} {"level":"warn","ts":"2026-07-17T04:05:02.585959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.578382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-17T04:05:02.586079Z","caller":"traceutil/trace.go:171","msg":"trace[204548586] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12090; }","duration":"122.756868ms","start":"2026-07-17T04:05:02.463284Z","end":"2026-07-17T04:05:02.586041Z","steps":["trace[204548586] 'range keys from in-memory index tree' (duration: 122.455489ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:06:38.502662Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10554} {"level":"info","ts":"2026-07-17T04:06:38.582353Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10554,"took":"77.342292ms","hash":2525743512,"current-db-size-bytes":57356288,"current-db-size":"57 MB","current-db-size-in-use-bytes":26492928,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-17T04:06:38.582416Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2525743512,"revision":10554,"compact-revision":8141} {"level":"info","ts":"2026-07-17T04:11:29.451968Z","caller":"traceutil/trace.go:171","msg":"trace[1365568324] transaction","detail":"{read_only:false; response_revision:14691; number_of_response:1; }","duration":"108.726289ms","start":"2026-07-17T04:11:29.343217Z","end":"2026-07-17T04:11:29.451943Z","steps":["trace[1365568324] 'process raft request' (duration: 108.174873ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:11:38.509375Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12733} {"level":"info","ts":"2026-07-17T04:11:38.587894Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12733,"took":"75.799384ms","hash":1975377111,"current-db-size-bytes":57356288,"current-db-size":"57 MB","current-db-size-in-use-bytes":27881472,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-17T04:11:38.587988Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1975377111,"revision":12733,"compact-revision":10554} {"level":"info","ts":"2026-07-17T04:11:45.754997Z","caller":"traceutil/trace.go:171","msg":"trace[1919091181] transaction","detail":"{read_only:false; response_revision:14794; number_of_response:1; }","duration":"116.458347ms","start":"2026-07-17T04:11:45.638508Z","end":"2026-07-17T04:11:45.754966Z","steps":["trace[1919091181] 'process raft request' (duration: 116.312323ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:11:45.755244Z","caller":"traceutil/trace.go:171","msg":"trace[1572372854] transaction","detail":"{read_only:false; response_revision:14793; number_of_response:1; }","duration":"116.853699ms","start":"2026-07-17T04:11:45.638379Z","end":"2026-07-17T04:11:45.755233Z","steps":["trace[1572372854] 'process raft request' (duration: 71.234291ms)","trace[1572372854] 'compare' (duration: 44.976119ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:12:10.753421Z","caller":"traceutil/trace.go:171","msg":"trace[1315075933] linearizableReadLoop","detail":"{readStateIndex:15644; appliedIndex:15643; }","duration":"181.886367ms","start":"2026-07-17T04:12:10.571502Z","end":"2026-07-17T04:12:10.753388Z","steps":["trace[1315075933] 'read index received' (duration: 181.632349ms)","trace[1315075933] 'applied index is now lower than readState.Index' (duration: 252.638µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:12:10.753479Z","caller":"traceutil/trace.go:171","msg":"trace[607575297] transaction","detail":"{read_only:false; response_revision:15114; number_of_response:1; }","duration":"309.950725ms","start":"2026-07-17T04:12:10.44349Z","end":"2026-07-17T04:12:10.753441Z","steps":["trace[607575297] 'process raft request' (duration: 309.660757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:12:10.75368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:12:10.443452Z","time spent":"310.11877ms","remote":"127.0.0.1:40392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3742,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T04:12:10.753961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.285049ms","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:7"} {"level":"info","ts":"2026-07-17T04:12:10.754122Z","caller":"traceutil/trace.go:171","msg":"trace[1741538297] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:15114; }","duration":"182.658999ms","start":"2026-07-17T04:12:10.571439Z","end":"2026-07-17T04:12:10.754098Z","steps":["trace[1741538297] 'agreement among raft nodes before linearized reading' (duration: 182.137164ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:12:48.813294Z","caller":"traceutil/trace.go:171","msg":"trace[338804594] linearizableReadLoop","detail":"{readStateIndex:15936; appliedIndex:15935; }","duration":"415.104339ms","start":"2026-07-17T04:12:48.39816Z","end":"2026-07-17T04:12:48.813264Z","steps":["trace[338804594] 'read index received' (duration: 412.753649ms)","trace[338804594] 'applied index is now lower than readState.Index' (duration: 2.34946ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:12:48.813334Z","caller":"traceutil/trace.go:171","msg":"trace[795304888] transaction","detail":"{read_only:false; response_revision:15397; number_of_response:1; }","duration":"415.953035ms","start":"2026-07-17T04:12:48.397344Z","end":"2026-07-17T04:12:48.813297Z","steps":["trace[795304888] 'process raft request' (duration: 413.549714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:12:48.813541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:12:48.397323Z","time spent":"416.12574ms","remote":"127.0.0.1:40570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":575,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T04:12:48.813549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.366037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-17T04:12:48.813579Z","caller":"traceutil/trace.go:171","msg":"trace[1319827385] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15397; }","duration":"415.438098ms","start":"2026-07-17T04:12:48.398131Z","end":"2026-07-17T04:12:48.81357Z","steps":["trace[1319827385] 'agreement among raft nodes before linearized reading' (duration: 415.262363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:12:48.813602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:12:48.398123Z","time spent":"415.47289ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-17T04:12:48.814105Z","caller":"traceutil/trace.go:171","msg":"trace[291423711] transaction","detail":"{read_only:false; response_revision:15398; number_of_response:1; }","duration":"415.8249ms","start":"2026-07-17T04:12:48.398269Z","end":"2026-07-17T04:12:48.814094Z","steps":["trace[291423711] 'process raft request' (duration: 415.589993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:12:48.814163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:12:48.398262Z","time spent":"415.877021ms","remote":"127.0.0.1:40570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":577,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T04:12:48.814208Z","caller":"traceutil/trace.go:171","msg":"trace[2072003671] transaction","detail":"{read_only:false; response_revision:15399; number_of_response:1; }","duration":"415.82773ms","start":"2026-07-17T04:12:48.398331Z","end":"2026-07-17T04:12:48.814159Z","steps":["trace[2072003671] 'process raft request' (duration: 415.695576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:12:48.814393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.918934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T04:12:48.814405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:12:48.398326Z","time spent":"415.971395ms","remote":"127.0.0.1:40542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1040,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T04:12:48.814372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:12:48.398328Z","time spent":"416.027686ms","remote":"127.0.0.1:40276","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-17T04:12:48.814444Z","caller":"traceutil/trace.go:171","msg":"trace[723930204] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15399; }","duration":"414.995426ms","start":"2026-07-17T04:12:48.399438Z","end":"2026-07-17T04:12:48.814433Z","steps":["trace[723930204] 'agreement among raft nodes before linearized reading' (duration: 414.900713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:12:48.814489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:12:48.399428Z","time spent":"415.055708ms","remote":"127.0.0.1:40214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T04:12:48.814587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.826158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jjtr7\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-07-17T04:12:48.814681Z","caller":"traceutil/trace.go:171","msg":"trace[1947089963] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jjtr7; range_end:; response_count:1; response_revision:15399; }","duration":"388.989263ms","start":"2026-07-17T04:12:48.425669Z","end":"2026-07-17T04:12:48.814658Z","steps":["trace[1947089963] 'agreement among raft nodes before linearized reading' (duration: 388.759876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:12:48.814732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:12:48.425637Z","time spent":"389.086976ms","remote":"127.0.0.1:40392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6473,"request content":"key:\"/registry/pods/openstack/keepalived-jjtr7\" "} {"level":"warn","ts":"2026-07-17T04:12:48.814892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.998088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-07-17T04:12:48.814943Z","caller":"traceutil/trace.go:171","msg":"trace[1388303665] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:15399; }","duration":"413.059909ms","start":"2026-07-17T04:12:48.401869Z","end":"2026-07-17T04:12:48.814929Z","steps":["trace[1388303665] 'agreement among raft nodes before linearized reading' (duration: 412.889454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:12:48.814982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:12:48.401863Z","time spent":"413.11086ms","remote":"127.0.0.1:40400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":1307,"request content":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" "} {"level":"warn","ts":"2026-07-17T04:12:48.816177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.390256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2224963"} {"level":"info","ts":"2026-07-17T04:12:48.816349Z","caller":"traceutil/trace.go:171","msg":"trace[2068582287] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:15399; }","duration":"191.619182ms","start":"2026-07-17T04:12:48.6247Z","end":"2026-07-17T04:12:48.816319Z","steps":["trace[2068582287] 'agreement among raft nodes before linearized reading' (duration: 190.033955ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:13:39.218886Z","caller":"traceutil/trace.go:171","msg":"trace[965597734] linearizableReadLoop","detail":"{readStateIndex:16487; appliedIndex:16486; }","duration":"117.74203ms","start":"2026-07-17T04:13:39.101112Z","end":"2026-07-17T04:13:39.218854Z","steps":["trace[965597734] 'read index received' (duration: 117.483903ms)","trace[965597734] 'applied index is now lower than readState.Index' (duration: 256.437µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:13:39.219075Z","caller":"traceutil/trace.go:171","msg":"trace[1169918346] transaction","detail":"{read_only:false; response_revision:15935; number_of_response:1; }","duration":"125.499555ms","start":"2026-07-17T04:13:39.093536Z","end":"2026-07-17T04:13:39.219036Z","steps":["trace[1169918346] 'process raft request' (duration: 125.085714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:13:39.219179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.034519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-6m5wk\" ","response":"range_response_count:1 size:9712"} {"level":"info","ts":"2026-07-17T04:13:39.219233Z","caller":"traceutil/trace.go:171","msg":"trace[1224238454] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-6m5wk; range_end:; response_count:1; response_revision:15935; }","duration":"118.133341ms","start":"2026-07-17T04:13:39.101085Z","end":"2026-07-17T04:13:39.219219Z","steps":["trace[1224238454] 'agreement among raft nodes before linearized reading' (duration: 117.892784ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:15:42.702565Z","caller":"traceutil/trace.go:171","msg":"trace[1945721984] transaction","detail":"{read_only:false; response_revision:16771; number_of_response:1; }","duration":"227.561228ms","start":"2026-07-17T04:15:42.47497Z","end":"2026-07-17T04:15:42.702531Z","steps":["trace[1945721984] 'process raft request' (duration: 227.439464ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:15:44.246981Z","caller":"traceutil/trace.go:171","msg":"trace[2009300835] transaction","detail":"{read_only:false; response_revision:16784; number_of_response:1; }","duration":"164.704867ms","start":"2026-07-17T04:15:44.082214Z","end":"2026-07-17T04:15:44.246919Z","steps":["trace[2009300835] 'process raft request' (duration: 164.47615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:15:45.232603Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870799415829786,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T04:15:45.431703Z","caller":"traceutil/trace.go:171","msg":"trace[1843310975] transaction","detail":"{read_only:false; response_revision:16789; number_of_response:1; }","duration":"700.65926ms","start":"2026-07-17T04:15:44.731014Z","end":"2026-07-17T04:15:45.431673Z","steps":["trace[1843310975] 'process raft request' (duration: 699.297921ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:15:45.432023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:15:44.731001Z","time spent":"700.902896ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T04:15:45.435571Z","caller":"traceutil/trace.go:171","msg":"trace[13498916] linearizableReadLoop","detail":"{readStateIndex:17375; appliedIndex:17372; }","duration":"703.850862ms","start":"2026-07-17T04:15:44.731693Z","end":"2026-07-17T04:15:45.435544Z","steps":["trace[13498916] 'read index received' (duration: 698.63996ms)","trace[13498916] 'applied index is now lower than readState.Index' (duration: 5.210032ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:15:45.435652Z","caller":"traceutil/trace.go:171","msg":"trace[1408837865] transaction","detail":"{read_only:false; response_revision:16790; number_of_response:1; }","duration":"704.384558ms","start":"2026-07-17T04:15:44.73121Z","end":"2026-07-17T04:15:45.435595Z","steps":["trace[1408837865] 'process raft request' (duration: 704.154532ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:15:45.435683Z","caller":"traceutil/trace.go:171","msg":"trace[1455697488] transaction","detail":"{read_only:false; response_revision:16791; number_of_response:1; }","duration":"684.19623ms","start":"2026-07-17T04:15:44.751478Z","end":"2026-07-17T04:15:45.435674Z","steps":["trace[1455697488] 'process raft request' (duration: 684.025845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:15:45.435823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:15:44.731206Z","time spent":"704.480741ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T04:15:45.435834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:15:44.751438Z","time spent":"684.269442ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T04:15:45.435973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.275484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jjtr7\" ","response":"range_response_count:1 size:6452"} {"level":"info","ts":"2026-07-17T04:15:45.436007Z","caller":"traceutil/trace.go:171","msg":"trace[1476862755] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jjtr7; range_end:; response_count:1; response_revision:16791; }","duration":"704.318286ms","start":"2026-07-17T04:15:44.731682Z","end":"2026-07-17T04:15:45.436Z","steps":["trace[1476862755] 'agreement among raft nodes before linearized reading' (duration: 704.176462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:15:45.43603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:15:44.731678Z","time spent":"704.347447ms","remote":"127.0.0.1:40392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6474,"request content":"key:\"/registry/pods/openstack/keepalived-jjtr7\" "} {"level":"warn","ts":"2026-07-17T04:15:45.436136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.553853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-6m5wk\" ","response":"range_response_count:1 size:9713"} {"level":"info","ts":"2026-07-17T04:15:45.436235Z","caller":"traceutil/trace.go:171","msg":"trace[2033357675] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-6m5wk; range_end:; response_count:1; response_revision:16791; }","duration":"475.685866ms","start":"2026-07-17T04:15:44.960529Z","end":"2026-07-17T04:15:45.436215Z","steps":["trace[2033357675] 'agreement among raft nodes before linearized reading' (duration: 475.384378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:15:45.436249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.833971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T04:15:45.436276Z","caller":"traceutil/trace.go:171","msg":"trace[90106929] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:16791; }","duration":"256.895223ms","start":"2026-07-17T04:15:45.179372Z","end":"2026-07-17T04:15:45.436268Z","steps":["trace[90106929] 'agreement among raft nodes before linearized reading' (duration: 256.844751ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:15:45.436277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:15:44.960469Z","time spent":"475.79933ms","remote":"127.0.0.1:40392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9735,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-6m5wk\" "} {"level":"warn","ts":"2026-07-17T04:15:45.436445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.381851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-swqpg\" ","response":"range_response_count:1 size:20566"} {"level":"info","ts":"2026-07-17T04:15:45.436472Z","caller":"traceutil/trace.go:171","msg":"trace[601583888] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-swqpg; range_end:; response_count:1; response_revision:16791; }","duration":"241.439512ms","start":"2026-07-17T04:15:45.195027Z","end":"2026-07-17T04:15:45.436466Z","steps":["trace[601583888] 'agreement among raft nodes before linearized reading' (duration: 241.36126ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:15:45.436526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.154889ms","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-07-17T04:15:45.436561Z","caller":"traceutil/trace.go:171","msg":"trace[519212150] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:16791; }","duration":"431.235272ms","start":"2026-07-17T04:15:45.005313Z","end":"2026-07-17T04:15:45.436548Z","steps":["trace[519212150] 'agreement among raft nodes before linearized reading' (duration: 431.17575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:15:45.436588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:15:45.005287Z","time spent":"431.292173ms","remote":"127.0.0.1:53702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":3,"response size":30,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"warn","ts":"2026-07-17T04:15:45.436569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.934086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-17T04:15:45.436649Z","caller":"traceutil/trace.go:171","msg":"trace[1030974822] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16791; }","duration":"426.05696ms","start":"2026-07-17T04:15:45.010575Z","end":"2026-07-17T04:15:45.436632Z","steps":["trace[1030974822] 'agreement among raft nodes before linearized reading' (duration: 425.824414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:15:45.436684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:15:45.010565Z","time spent":"426.110111ms","remote":"127.0.0.1:40378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":689,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-07-17T04:15:45.436774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.34529ms","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-07-17T04:15:45.4368Z","caller":"traceutil/trace.go:171","msg":"trace[727757827] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16791; }","duration":"470.414982ms","start":"2026-07-17T04:15:44.966379Z","end":"2026-07-17T04:15:45.436794Z","steps":["trace[727757827] 'agreement among raft nodes before linearized reading' (duration: 470.3352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:15:45.43683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:15:44.966352Z","time spent":"470.470164ms","remote":"127.0.0.1:40466","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-07-17T04:15:45.436989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.198761ms","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-07-17T04:15:45.437019Z","caller":"traceutil/trace.go:171","msg":"trace[102706465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16791; }","duration":"361.297214ms","start":"2026-07-17T04:15:45.075714Z","end":"2026-07-17T04:15:45.437011Z","steps":["trace[102706465] 'agreement among raft nodes before linearized reading' (duration: 361.190341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:15:45.437069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:15:45.07569Z","time spent":"361.344186ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T04:15:45.437417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.634479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-17T04:15:45.43747Z","caller":"traceutil/trace.go:171","msg":"trace[2018218438] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:16791; }","duration":"182.741763ms","start":"2026-07-17T04:15:45.254716Z","end":"2026-07-17T04:15:45.437458Z","steps":["trace[2018218438] 'agreement among raft nodes before linearized reading' (duration: 181.60796ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:16:38.51665Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14740} {"level":"info","ts":"2026-07-17T04:16:38.608652Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14740,"took":"88.146728ms","hash":2802730504,"current-db-size-bytes":57356288,"current-db-size":"57 MB","current-db-size-in-use-bytes":34181120,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-17T04:16:38.60874Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2802730504,"revision":14740,"compact-revision":12733} {"level":"warn","ts":"2026-07-17T04:19:28.594201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.102299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-6m5wk\" ","response":"range_response_count:1 size:9713"} {"level":"info","ts":"2026-07-17T04:19:28.594308Z","caller":"traceutil/trace.go:171","msg":"trace[818571296] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-6m5wk; range_end:; response_count:1; response_revision:18500; }","duration":"143.229963ms","start":"2026-07-17T04:19:28.451065Z","end":"2026-07-17T04:19:28.594295Z","steps":["trace[818571296] 'range keys from in-memory index tree' (duration: 142.955305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:19:28.59448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.409592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T04:19:28.59469Z","caller":"traceutil/trace.go:171","msg":"trace[1557312437] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:18500; }","duration":"163.658659ms","start":"2026-07-17T04:19:28.430997Z","end":"2026-07-17T04:19:28.594655Z","steps":["trace[1557312437] 'count revisions from in-memory index tree' (duration: 163.277987ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:19:28.594965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.853409ms","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-07-17T04:19:28.595103Z","caller":"traceutil/trace.go:171","msg":"trace[333952382] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:18500; }","duration":"136.048934ms","start":"2026-07-17T04:19:28.459022Z","end":"2026-07-17T04:19:28.595071Z","steps":["trace[333952382] 'range keys from in-memory index tree' (duration: 135.760036ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:19:28.595111Z","caller":"traceutil/trace.go:171","msg":"trace[1003941110] transaction","detail":"{read_only:false; response_revision:18501; number_of_response:1; }","duration":"109.392698ms","start":"2026-07-17T04:19:28.485627Z","end":"2026-07-17T04:19:28.595019Z","steps":["trace[1003941110] 'process raft request' (duration: 54.269321ms)","trace[1003941110] 'compare' (duration: 54.132648ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:19:35.087652Z","caller":"traceutil/trace.go:171","msg":"trace[1310609694] transaction","detail":"{read_only:false; response_revision:18560; number_of_response:1; }","duration":"105.586737ms","start":"2026-07-17T04:19:34.982036Z","end":"2026-07-17T04:19:35.087623Z","steps":["trace[1310609694] 'process raft request' (duration: 105.071441ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:19:35.476464Z","caller":"traceutil/trace.go:171","msg":"trace[1674734748] linearizableReadLoop","detail":"{readStateIndex:19198; appliedIndex:19197; }","duration":"278.627208ms","start":"2026-07-17T04:19:35.197811Z","end":"2026-07-17T04:19:35.476438Z","steps":["trace[1674734748] 'read index received' (duration: 217.264561ms)","trace[1674734748] 'applied index is now lower than readState.Index' (duration: 61.361217ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:19:35.476563Z","caller":"traceutil/trace.go:171","msg":"trace[1614720143] transaction","detail":"{read_only:false; response_revision:18561; number_of_response:1; }","duration":"306.170881ms","start":"2026-07-17T04:19:35.170382Z","end":"2026-07-17T04:19:35.476553Z","steps":["trace[1614720143] 'process raft request' (duration: 244.69291ms)","trace[1614720143] 'compare' (duration: 61.162752ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T04:19:35.476648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:19:35.170356Z","time spent":"306.224742ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T04:19:35.476805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.165918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-07-17T04:19:35.476917Z","caller":"traceutil/trace.go:171","msg":"trace[817041996] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:18561; }","duration":"206.335302ms","start":"2026-07-17T04:19:35.270555Z","end":"2026-07-17T04:19:35.47689Z","steps":["trace[817041996] 'agreement among raft nodes before linearized reading' (duration: 206.056784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:19:35.476927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.122413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T04:19:35.476964Z","caller":"traceutil/trace.go:171","msg":"trace[970732304] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:18561; }","duration":"279.200946ms","start":"2026-07-17T04:19:35.197753Z","end":"2026-07-17T04:19:35.476954Z","steps":["trace[970732304] 'agreement among raft nodes before linearized reading' (duration: 279.124614ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:19:35.645015Z","caller":"traceutil/trace.go:171","msg":"trace[1980160522] transaction","detail":"{read_only:false; response_revision:18562; number_of_response:1; }","duration":"150.32403ms","start":"2026-07-17T04:19:35.494665Z","end":"2026-07-17T04:19:35.644989Z","steps":["trace[1980160522] 'process raft request' (duration: 150.096964ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:19:44.707352Z","caller":"traceutil/trace.go:171","msg":"trace[453156784] transaction","detail":"{read_only:false; response_revision:18731; number_of_response:1; }","duration":"100.356434ms","start":"2026-07-17T04:19:44.60694Z","end":"2026-07-17T04:19:44.707297Z","steps":["trace[453156784] 'process raft request' (duration: 100.089126ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:20:48.327384Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3ba9ae53cc4335db","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-17T04:20:48.333765Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-17T04:20:48.333865Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-17T04:21:38.522835Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17390} {"level":"info","ts":"2026-07-17T04:21:38.617999Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17390,"took":"91.080354ms","hash":917401422,"current-db-size-bytes":57356288,"current-db-size":"57 MB","current-db-size-in-use-bytes":34668544,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-17T04:21:38.618071Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":917401422,"revision":17390,"compact-revision":14740} {"level":"info","ts":"2026-07-17T04:25:39.076955Z","caller":"traceutil/trace.go:171","msg":"trace[735065254] transaction","detail":"{read_only:false; response_revision:21330; number_of_response:1; }","duration":"175.687927ms","start":"2026-07-17T04:25:38.901223Z","end":"2026-07-17T04:25:39.076911Z","steps":["trace[735065254] 'process raft request' (duration: 123.114047ms)","trace[735065254] 'compare' (duration: 52.407375ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:25:39.209195Z","caller":"traceutil/trace.go:171","msg":"trace[1770202321] transaction","detail":"{read_only:false; response_revision:21332; number_of_response:1; }","duration":"306.1584ms","start":"2026-07-17T04:25:38.902972Z","end":"2026-07-17T04:25:39.20913Z","steps":["trace[1770202321] 'process raft request' (duration: 306.000855ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:25:39.209225Z","caller":"traceutil/trace.go:171","msg":"trace[751187330] transaction","detail":"{read_only:false; response_revision:21331; number_of_response:1; }","duration":"306.837869ms","start":"2026-07-17T04:25:38.902325Z","end":"2026-07-17T04:25:39.209163Z","steps":["trace[751187330] 'process raft request' (duration: 276.019387ms)","trace[751187330] 'compare' (duration: 30.443232ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T04:25:39.209566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.899266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-17T04:25:39.209586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:25:38.90231Z","time spent":"307.145999ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T04:25:39.209548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:25:38.902939Z","time spent":"306.390287ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T04:25:39.209619Z","caller":"traceutil/trace.go:171","msg":"trace[1487109419] transaction","detail":"{read_only:false; response_revision:21333; number_of_response:1; }","duration":"264.85335ms","start":"2026-07-17T04:25:38.944331Z","end":"2026-07-17T04:25:39.209185Z","steps":["trace[1487109419] 'process raft request' (duration: 264.693524ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:25:39.209654Z","caller":"traceutil/trace.go:171","msg":"trace[1509492538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21333; }","duration":"201.03847ms","start":"2026-07-17T04:25:39.008589Z","end":"2026-07-17T04:25:39.209628Z","steps":["trace[1509492538] 'agreement among raft nodes before linearized reading' (duration: 200.818034ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:25:39.209119Z","caller":"traceutil/trace.go:171","msg":"trace[1078528713] linearizableReadLoop","detail":"{readStateIndex:22062; appliedIndex:22058; }","duration":"200.432281ms","start":"2026-07-17T04:25:39.008652Z","end":"2026-07-17T04:25:39.209085Z","steps":["trace[1078528713] 'read index received' (duration: 15.752451ms)","trace[1078528713] 'applied index is now lower than readState.Index' (duration: 184.67848ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:26:00.037346Z","caller":"traceutil/trace.go:171","msg":"trace[862312264] transaction","detail":"{read_only:false; response_revision:21436; number_of_response:1; }","duration":"122.353193ms","start":"2026-07-17T04:25:59.914967Z","end":"2026-07-17T04:26:00.03732Z","steps":["trace[862312264] 'process raft request' (duration: 122.167028ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:26:00.227939Z","caller":"traceutil/trace.go:171","msg":"trace[999427432] linearizableReadLoop","detail":"{readStateIndex:22170; appliedIndex:22168; }","duration":"220.613863ms","start":"2026-07-17T04:26:00.007298Z","end":"2026-07-17T04:26:00.227912Z","steps":["trace[999427432] 'read index received' (duration: 29.861544ms)","trace[999427432] 'applied index is now lower than readState.Index' (duration: 190.751218ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T04:26:00.2282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.889962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T04:26:00.228244Z","caller":"traceutil/trace.go:171","msg":"trace[2079351941] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21437; }","duration":"221.001095ms","start":"2026-07-17T04:26:00.007231Z","end":"2026-07-17T04:26:00.228232Z","steps":["trace[2079351941] 'agreement among raft nodes before linearized reading' (duration: 220.787029ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:26:00.22826Z","caller":"traceutil/trace.go:171","msg":"trace[1465411064] transaction","detail":"{read_only:false; response_revision:21437; number_of_response:1; }","duration":"311.315812ms","start":"2026-07-17T04:25:59.916723Z","end":"2026-07-17T04:26:00.228039Z","steps":["trace[1465411064] 'process raft request' (duration: 310.997602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:26:00.228501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:25:59.916697Z","time spent":"311.709173ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T04:26:00.505543Z","caller":"traceutil/trace.go:171","msg":"trace[1350406412] transaction","detail":"{read_only:false; response_revision:21438; number_of_response:1; }","duration":"152.187009ms","start":"2026-07-17T04:26:00.353316Z","end":"2026-07-17T04:26:00.505503Z","steps":["trace[1350406412] 'process raft request' (duration: 131.108951ms)","trace[1350406412] 'compare' (duration: 20.756008ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T04:26:00.746791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.440532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T04:26:00.747393Z","caller":"traceutil/trace.go:171","msg":"trace[1627013051] transaction","detail":"{read_only:false; response_revision:21439; number_of_response:1; }","duration":"387.761531ms","start":"2026-07-17T04:26:00.359592Z","end":"2026-07-17T04:26:00.747354Z","steps":["trace[1627013051] 'process raft request' (duration: 256.605948ms)","trace[1627013051] 'compare' (duration: 130.243846ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T04:26:00.747647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.636652ms","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-07-17T04:26:00.74771Z","caller":"traceutil/trace.go:171","msg":"trace[1977137480] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:21439; }","duration":"295.762736ms","start":"2026-07-17T04:26:00.451933Z","end":"2026-07-17T04:26:00.747696Z","steps":["trace[1977137480] 'agreement among raft nodes before linearized reading' (duration: 295.629702ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:26:00.7475Z","caller":"traceutil/trace.go:171","msg":"trace[647030471] linearizableReadLoop","detail":"{readStateIndex:22172; appliedIndex:22170; }","duration":"295.498498ms","start":"2026-07-17T04:26:00.451971Z","end":"2026-07-17T04:26:00.74747Z","steps":["trace[647030471] 'read index received' (duration: 32.470912ms)","trace[647030471] 'applied index is now lower than readState.Index' (duration: 263.024396ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T04:26:00.747975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:26:00.35957Z","time spent":"388.105631ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T04:26:01.199444Z","caller":"traceutil/trace.go:171","msg":"trace[1190752326] transaction","detail":"{read_only:false; response_revision:21442; number_of_response:1; }","duration":"202.303337ms","start":"2026-07-17T04:26:00.997081Z","end":"2026-07-17T04:26:01.199384Z","steps":["trace[1190752326] 'process raft request' (duration: 202.06889ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:26:01.428061Z","caller":"traceutil/trace.go:171","msg":"trace[191065509] transaction","detail":"{read_only:false; response_revision:21444; number_of_response:1; }","duration":"132.921855ms","start":"2026-07-17T04:26:01.295103Z","end":"2026-07-17T04:26:01.428025Z","steps":["trace[191065509] 'process raft request' (duration: 104.0456ms)","trace[191065509] 'compare' (duration: 28.671749ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:26:38.534446Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19724} {"level":"info","ts":"2026-07-17T04:26:38.650811Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19724,"took":"112.540493ms","hash":102441822,"current-db-size-bytes":57356288,"current-db-size":"57 MB","current-db-size-in-use-bytes":29310976,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-17T04:26:38.650886Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":102441822,"revision":19724,"compact-revision":17390} {"level":"info","ts":"2026-07-17T04:29:58.467055Z","caller":"traceutil/trace.go:171","msg":"trace[1427745786] transaction","detail":"{read_only:false; response_revision:23899; number_of_response:1; }","duration":"140.072846ms","start":"2026-07-17T04:29:58.326943Z","end":"2026-07-17T04:29:58.467016Z","steps":["trace[1427745786] 'process raft request' (duration: 139.814089ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:29:58.803812Z","caller":"traceutil/trace.go:171","msg":"trace[1189724012] transaction","detail":"{read_only:false; response_revision:23901; number_of_response:1; }","duration":"279.949497ms","start":"2026-07-17T04:29:58.523809Z","end":"2026-07-17T04:29:58.803759Z","steps":["trace[1189724012] 'process raft request' (duration: 214.876288ms)","trace[1189724012] 'compare' (duration: 64.641186ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:29:58.803862Z","caller":"traceutil/trace.go:171","msg":"trace[952116841] transaction","detail":"{read_only:false; response_revision:23902; number_of_response:1; }","duration":"280.015549ms","start":"2026-07-17T04:29:58.523809Z","end":"2026-07-17T04:29:58.803825Z","steps":["trace[952116841] 'process raft request' (duration: 279.866814ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:29:58.803861Z","caller":"traceutil/trace.go:171","msg":"trace[812750992] linearizableReadLoop","detail":"{readStateIndex:24710; appliedIndex:24708; }","duration":"217.516575ms","start":"2026-07-17T04:29:58.5863Z","end":"2026-07-17T04:29:58.803817Z","steps":["trace[812750992] 'read index received' (duration: 152.410116ms)","trace[812750992] 'applied index is now lower than readState.Index' (duration: 65.104869ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T04:29:58.804138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.807614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-07-17T04:29:58.804269Z","caller":"traceutil/trace.go:171","msg":"trace[751173505] range","detail":"{range_begin:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; range_end:; response_count:1; response_revision:23902; }","duration":"217.99187ms","start":"2026-07-17T04:29:58.586251Z","end":"2026-07-17T04:29:58.804242Z","steps":["trace[751173505] 'agreement among raft nodes before linearized reading' (duration: 217.688071ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:29:58.804216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.8597ms","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-07-17T04:29:58.80445Z","caller":"traceutil/trace.go:171","msg":"trace[931913516] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:23902; }","duration":"213.095926ms","start":"2026-07-17T04:29:58.591275Z","end":"2026-07-17T04:29:58.80437Z","steps":["trace[931913516] 'agreement among raft nodes before linearized reading' (duration: 212.86233ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:31:38.542249Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21880} {"level":"info","ts":"2026-07-17T04:31:38.654925Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21880,"took":"108.466734ms","hash":2807160379,"current-db-size-bytes":57356288,"current-db-size":"57 MB","current-db-size-in-use-bytes":43761664,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-07-17T04:31:38.655004Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2807160379,"revision":21880,"compact-revision":19724} {"level":"info","ts":"2026-07-17T04:33:00.681382Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000679f.wal"} {"level":"warn","ts":"2026-07-17T04:33:42.02542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:33:41.680556Z","time spent":"344.857399ms","remote":"127.0.0.1:40238","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-17T04:33:42.063472Z","caller":"traceutil/trace.go:171","msg":"trace[487488555] transaction","detail":"{read_only:false; response_revision:26120; number_of_response:1; }","duration":"287.909139ms","start":"2026-07-17T04:33:41.775528Z","end":"2026-07-17T04:33:42.063437Z","steps":["trace[487488555] 'process raft request' (duration: 287.800766ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:42.063722Z","caller":"traceutil/trace.go:171","msg":"trace[574670093] transaction","detail":"{read_only:false; response_revision:26119; number_of_response:1; }","duration":"314.927906ms","start":"2026-07-17T04:33:41.748781Z","end":"2026-07-17T04:33:42.063709Z","steps":["trace[574670093] 'process raft request' (duration: 314.276627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:42.063849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:33:41.748744Z","time spent":"315.011699ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T04:33:42.063905Z","caller":"traceutil/trace.go:171","msg":"trace[292464415] linearizableReadLoop","detail":"{readStateIndex:26987; appliedIndex:26983; }","duration":"145.308809ms","start":"2026-07-17T04:33:41.918558Z","end":"2026-07-17T04:33:42.063867Z","steps":["trace[292464415] 'read index received' (duration: 106.660391ms)","trace[292464415] 'applied index is now lower than readState.Index' (duration: 38.646908ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:33:42.064293Z","caller":"traceutil/trace.go:171","msg":"trace[803123946] transaction","detail":"{read_only:false; response_revision:26121; number_of_response:1; }","duration":"273.316613ms","start":"2026-07-17T04:33:41.790962Z","end":"2026-07-17T04:33:42.064279Z","steps":["trace[803123946] 'process raft request' (duration: 272.424677ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:42.064304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.755312ms","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-07-17T04:33:42.064345Z","caller":"traceutil/trace.go:171","msg":"trace[255768730] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26121; }","duration":"145.834524ms","start":"2026-07-17T04:33:41.918499Z","end":"2026-07-17T04:33:42.064334Z","steps":["trace[255768730] 'agreement among raft nodes before linearized reading' (duration: 145.69796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:42.070921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.644279ms","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-07-17T04:33:42.070976Z","caller":"traceutil/trace.go:171","msg":"trace[483536203] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26122; }","duration":"132.763883ms","start":"2026-07-17T04:33:41.938199Z","end":"2026-07-17T04:33:42.070963Z","steps":["trace[483536203] 'agreement among raft nodes before linearized reading' (duration: 132.576226ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:42.263054Z","caller":"traceutil/trace.go:171","msg":"trace[1218004337] transaction","detail":"{read_only:false; response_revision:26124; number_of_response:1; }","duration":"187.063637ms","start":"2026-07-17T04:33:42.075951Z","end":"2026-07-17T04:33:42.263015Z","steps":["trace[1218004337] 'process raft request' (duration: 157.322189ms)","trace[1218004337] 'compare' (duration: 29.46342ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:33:42.263505Z","caller":"traceutil/trace.go:171","msg":"trace[365174384] transaction","detail":"{read_only:false; response_revision:26125; number_of_response:1; }","duration":"187.410867ms","start":"2026-07-17T04:33:42.076051Z","end":"2026-07-17T04:33:42.263461Z","steps":["trace[365174384] 'process raft request' (duration: 186.910022ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:42.263971Z","caller":"traceutil/trace.go:171","msg":"trace[56485925] linearizableReadLoop","detail":"{readStateIndex:26992; appliedIndex:26989; }","duration":"175.799348ms","start":"2026-07-17T04:33:42.088111Z","end":"2026-07-17T04:33:42.263911Z","steps":["trace[56485925] 'read index received' (duration: 145.115033ms)","trace[56485925] 'applied index is now lower than readState.Index' (duration: 30.680445ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:33:42.264022Z","caller":"traceutil/trace.go:171","msg":"trace[1169309552] transaction","detail":"{read_only:false; response_revision:26126; number_of_response:1; }","duration":"187.677365ms","start":"2026-07-17T04:33:42.076293Z","end":"2026-07-17T04:33:42.26397Z","steps":["trace[1169309552] 'process raft request' (duration: 186.966564ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:42.264277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.514483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-17T04:33:42.264314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.124717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-07-17T04:33:42.264329Z","caller":"traceutil/trace.go:171","msg":"trace[791937966] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:26126; }","duration":"135.648507ms","start":"2026-07-17T04:33:42.128665Z","end":"2026-07-17T04:33:42.264313Z","steps":["trace[791937966] 'agreement among raft nodes before linearized reading' (duration: 135.496072ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:42.26438Z","caller":"traceutil/trace.go:171","msg":"trace[1803940075] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:26126; }","duration":"191.225339ms","start":"2026-07-17T04:33:42.073139Z","end":"2026-07-17T04:33:42.264364Z","steps":["trace[1803940075] 'agreement among raft nodes before linearized reading' (duration: 190.991041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:42.702602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.085113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T04:33:42.702729Z","caller":"traceutil/trace.go:171","msg":"trace[333429020] linearizableReadLoop","detail":"{readStateIndex:26995; appliedIndex:26994; }","duration":"181.610157ms","start":"2026-07-17T04:33:42.521099Z","end":"2026-07-17T04:33:42.702709Z","steps":["trace[333429020] 'read index received' (duration: 49.292518ms)","trace[333429020] 'applied index is now lower than readState.Index' (duration: 132.315609ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T04:33:42.702907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.816214ms","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-07-17T04:33:42.702943Z","caller":"traceutil/trace.go:171","msg":"trace[20867754] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26129; }","duration":"181.898216ms","start":"2026-07-17T04:33:42.521036Z","end":"2026-07-17T04:33:42.702934Z","steps":["trace[20867754] 'agreement among raft nodes before linearized reading' (duration: 181.727801ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:42.703235Z","caller":"traceutil/trace.go:171","msg":"trace[1732169178] transaction","detail":"{read_only:false; response_revision:26129; number_of_response:1; }","duration":"204.765193ms","start":"2026-07-17T04:33:42.498456Z","end":"2026-07-17T04:33:42.703221Z","steps":["trace[1732169178] 'process raft request' (duration: 71.950069ms)","trace[1732169178] 'compare' (duration: 131.447785ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:33:42.705314Z","caller":"traceutil/trace.go:171","msg":"trace[669563437] transaction","detail":"{read_only:false; response_revision:26130; number_of_response:1; }","duration":"159.257656ms","start":"2026-07-17T04:33:42.546015Z","end":"2026-07-17T04:33:42.705273Z","steps":["trace[669563437] 'process raft request' (duration: 159.017079ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:43.125607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.768003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T04:33:43.125713Z","caller":"traceutil/trace.go:171","msg":"trace[772234963] linearizableReadLoop","detail":"{readStateIndex:26997; appliedIndex:26996; }","duration":"117.981472ms","start":"2026-07-17T04:33:43.007714Z","end":"2026-07-17T04:33:43.125696Z","steps":["trace[772234963] 'read index received' (duration: 70.092µs)","trace[772234963] 'applied index is now lower than readState.Index' (duration: 117.91009ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T04:33:43.125781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.229759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T04:33:43.125813Z","caller":"traceutil/trace.go:171","msg":"trace[1146408885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26131; }","duration":"118.306891ms","start":"2026-07-17T04:33:43.007496Z","end":"2026-07-17T04:33:43.125803Z","steps":["trace[1146408885] 'agreement among raft nodes before linearized reading' (duration: 118.249549ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:43.125794Z","caller":"traceutil/trace.go:171","msg":"trace[414344153] transaction","detail":"{read_only:false; response_revision:26131; number_of_response:1; }","duration":"353.638656ms","start":"2026-07-17T04:33:42.772101Z","end":"2026-07-17T04:33:43.125739Z","steps":["trace[414344153] 'process raft request' (duration: 220.626286ms)","trace[414344153] 'compare' (duration: 132.65103ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T04:33:43.126047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:33:42.77208Z","time spent":"353.820792ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T04:33:43.443186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.282752ms","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-07-17T04:33:43.443315Z","caller":"traceutil/trace.go:171","msg":"trace[4017764] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26132; }","duration":"212.493288ms","start":"2026-07-17T04:33:43.230793Z","end":"2026-07-17T04:33:43.443286Z","steps":["trace[4017764] 'count revisions from in-memory index tree' (duration: 212.191499ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:44.327546Z","caller":"traceutil/trace.go:171","msg":"trace[58150542] transaction","detail":"{read_only:false; response_revision:26135; number_of_response:1; }","duration":"257.552453ms","start":"2026-07-17T04:33:44.069907Z","end":"2026-07-17T04:33:44.32746Z","steps":["trace[58150542] 'process raft request' (duration: 257.273735ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:44.367555Z","caller":"traceutil/trace.go:171","msg":"trace[465094266] transaction","detail":"{read_only:false; response_revision:26136; number_of_response:1; }","duration":"297.123698ms","start":"2026-07-17T04:33:44.070381Z","end":"2026-07-17T04:33:44.367505Z","steps":["trace[465094266] 'process raft request' (duration: 296.85701ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:44.367595Z","caller":"traceutil/trace.go:171","msg":"trace[1733040189] transaction","detail":"{read_only:false; response_revision:26137; number_of_response:1; }","duration":"296.088097ms","start":"2026-07-17T04:33:44.07149Z","end":"2026-07-17T04:33:44.367578Z","steps":["trace[1733040189] 'process raft request' (duration: 295.904802ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:44.367615Z","caller":"traceutil/trace.go:171","msg":"trace[789830833] linearizableReadLoop","detail":"{readStateIndex:27003; appliedIndex:27000; }","duration":"274.844928ms","start":"2026-07-17T04:33:44.092586Z","end":"2026-07-17T04:33:44.367431Z","steps":["trace[789830833] 'read index received' (duration: 234.678686ms)","trace[789830833] 'applied index is now lower than readState.Index' (duration: 40.165262ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T04:33:44.367666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.986791ms","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-07-17T04:33:44.367831Z","caller":"traceutil/trace.go:171","msg":"trace[1194857393] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26137; }","duration":"275.255779ms","start":"2026-07-17T04:33:44.09254Z","end":"2026-07-17T04:33:44.367796Z","steps":["trace[1194857393] 'agreement among raft nodes before linearized reading' (duration: 274.905469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:44.367908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.420361ms","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-07-17T04:33:44.367954Z","caller":"traceutil/trace.go:171","msg":"trace[2048209166] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26137; }","duration":"230.536245ms","start":"2026-07-17T04:33:44.137398Z","end":"2026-07-17T04:33:44.367934Z","steps":["trace[2048209166] 'agreement among raft nodes before linearized reading' (duration: 230.392361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:44.368043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.335736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-07-17T04:33:44.368108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.623194ms","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-07-17T04:33:44.368144Z","caller":"traceutil/trace.go:171","msg":"trace[595658106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26137; }","duration":"100.506603ms","start":"2026-07-17T04:33:44.267612Z","end":"2026-07-17T04:33:44.368119Z","steps":["trace[595658106] 'agreement among raft nodes before linearized reading' (duration: 100.208193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:44.368154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.528493ms","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-07-17T04:33:44.368167Z","caller":"traceutil/trace.go:171","msg":"trace[583604189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26137; }","duration":"100.736778ms","start":"2026-07-17T04:33:44.267413Z","end":"2026-07-17T04:33:44.36815Z","steps":["trace[583604189] 'agreement among raft nodes before linearized reading' (duration: 100.48394ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:44.368227Z","caller":"traceutil/trace.go:171","msg":"trace[1529101219] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26137; }","duration":"100.670857ms","start":"2026-07-17T04:33:44.267535Z","end":"2026-07-17T04:33:44.368206Z","steps":["trace[1529101219] 'agreement among raft nodes before linearized reading' (duration: 100.330556ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:44.622969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.657492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T04:33:44.623071Z","caller":"traceutil/trace.go:171","msg":"trace[25344446] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:26137; }","duration":"250.798726ms","start":"2026-07-17T04:33:44.372249Z","end":"2026-07-17T04:33:44.623048Z","steps":["trace[25344446] 'count revisions from in-memory index tree' (duration: 250.568319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:44.624332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.130039ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T04:33:44.624555Z","caller":"traceutil/trace.go:171","msg":"trace[898556368] transaction","detail":"{read_only:false; response_revision:26138; number_of_response:1; }","duration":"251.450126ms","start":"2026-07-17T04:33:44.373063Z","end":"2026-07-17T04:33:44.624513Z","steps":["trace[898556368] 'process raft request' (duration: 123.911965ms)","trace[898556368] 'compare' (duration: 125.596274ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:33:44.62637Z","caller":"traceutil/trace.go:171","msg":"trace[1217198617] transaction","detail":"{read_only:false; response_revision:26140; number_of_response:1; }","duration":"252.672641ms","start":"2026-07-17T04:33:44.373641Z","end":"2026-07-17T04:33:44.626314Z","steps":["trace[1217198617] 'process raft request' (duration: 252.460275ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:44.626589Z","caller":"traceutil/trace.go:171","msg":"trace[774436318] transaction","detail":"{read_only:false; response_revision:26139; number_of_response:1; }","duration":"252.96678ms","start":"2026-07-17T04:33:44.373584Z","end":"2026-07-17T04:33:44.62655Z","steps":["trace[774436318] 'process raft request' (duration: 252.293901ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:44.626668Z","caller":"traceutil/trace.go:171","msg":"trace[750021777] transaction","detail":"{read_only:false; response_revision:26141; number_of_response:1; }","duration":"252.985121ms","start":"2026-07-17T04:33:44.373652Z","end":"2026-07-17T04:33:44.626637Z","steps":["trace[750021777] 'process raft request' (duration: 252.499427ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:44.62661Z","caller":"traceutil/trace.go:171","msg":"trace[1056523600] transaction","detail":"{read_only:false; response_revision:26142; number_of_response:1; }","duration":"201.779006ms","start":"2026-07-17T04:33:44.4248Z","end":"2026-07-17T04:33:44.626579Z","steps":["trace[1056523600] 'process raft request' (duration: 201.411745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:44.977631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.716806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T04:33:44.977813Z","caller":"traceutil/trace.go:171","msg":"trace[271030305] transaction","detail":"{read_only:false; response_revision:26144; number_of_response:1; }","duration":"264.68148ms","start":"2026-07-17T04:33:44.713118Z","end":"2026-07-17T04:33:44.9778Z","steps":["trace[271030305] 'process raft request' (duration: 264.624159ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:44.977912Z","caller":"traceutil/trace.go:171","msg":"trace[1633232034] transaction","detail":"{read_only:false; response_revision:26143; number_of_response:1; }","duration":"266.064492ms","start":"2026-07-17T04:33:44.71182Z","end":"2026-07-17T04:33:44.977885Z","steps":["trace[1633232034] 'process raft request' (duration: 54.023006ms)","trace[1633232034] 'compare' (duration: 211.526351ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:33:45.037172Z","caller":"traceutil/trace.go:171","msg":"trace[624179491] transaction","detail":"{read_only:false; response_revision:26145; number_of_response:1; }","duration":"227.561048ms","start":"2026-07-17T04:33:44.80958Z","end":"2026-07-17T04:33:45.037142Z","steps":["trace[624179491] 'process raft request' (duration: 227.28269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:45.321306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.58387ms","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-07-17T04:33:45.321362Z","caller":"traceutil/trace.go:171","msg":"trace[101015120] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26145; }","duration":"190.685953ms","start":"2026-07-17T04:33:45.130662Z","end":"2026-07-17T04:33:45.321348Z","steps":["trace[101015120] 'range keys from in-memory index tree' (duration: 190.434306ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:45.454873Z","caller":"traceutil/trace.go:171","msg":"trace[659505926] transaction","detail":"{read_only:false; response_revision:26146; number_of_response:1; }","duration":"319.84809ms","start":"2026-07-17T04:33:45.134996Z","end":"2026-07-17T04:33:45.454844Z","steps":["trace[659505926] 'process raft request' (duration: 319.612964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:45.455068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:33:45.134967Z","time spent":"319.994205ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T04:33:45.472061Z","caller":"traceutil/trace.go:171","msg":"trace[989655702] transaction","detail":"{read_only:false; response_revision:26147; number_of_response:1; }","duration":"145.121233ms","start":"2026-07-17T04:33:45.326918Z","end":"2026-07-17T04:33:45.47204Z","steps":["trace[989655702] 'process raft request' (duration: 144.824904ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:51.306016Z","caller":"traceutil/trace.go:171","msg":"trace[115677332] transaction","detail":"{read_only:false; response_revision:26188; number_of_response:1; }","duration":"308.949123ms","start":"2026-07-17T04:33:50.997038Z","end":"2026-07-17T04:33:51.305987Z","steps":["trace[115677332] 'process raft request' (duration: 308.723846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:51.306192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:33:50.997011Z","time spent":"309.087467ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T04:33:51.330685Z","caller":"traceutil/trace.go:171","msg":"trace[185512653] linearizableReadLoop","detail":"{readStateIndex:27056; appliedIndex:27054; }","duration":"324.358852ms","start":"2026-07-17T04:33:51.006304Z","end":"2026-07-17T04:33:51.330663Z","steps":["trace[185512653] 'read index received' (duration: 299.462076ms)","trace[185512653] 'applied index is now lower than readState.Index' (duration: 24.895316ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:33:51.330986Z","caller":"traceutil/trace.go:171","msg":"trace[1895692392] transaction","detail":"{read_only:false; response_revision:26189; number_of_response:1; }","duration":"332.50969ms","start":"2026-07-17T04:33:50.99846Z","end":"2026-07-17T04:33:51.33097Z","steps":["trace[1895692392] 'process raft request' (duration: 332.078828ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:51.331112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:33:50.998449Z","time spent":"332.576922ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T04:33:51.33136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.050722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T04:33:51.331411Z","caller":"traceutil/trace.go:171","msg":"trace[1846414220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26189; }","duration":"325.133085ms","start":"2026-07-17T04:33:51.006262Z","end":"2026-07-17T04:33:51.331395Z","steps":["trace[1846414220] 'agreement among raft nodes before linearized reading' (duration: 325.060502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:51.331442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:33:51.006245Z","time spent":"325.189556ms","remote":"127.0.0.1:40214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T04:33:56.842797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.40671ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T04:33:56.843127Z","caller":"traceutil/trace.go:171","msg":"trace[830083522] linearizableReadLoop","detail":"{readStateIndex:27092; appliedIndex:27090; }","duration":"396.310681ms","start":"2026-07-17T04:33:56.446796Z","end":"2026-07-17T04:33:56.843107Z","steps":["trace[830083522] 'read index received' (duration: 176.461688ms)","trace[830083522] 'applied index is now lower than readState.Index' (duration: 219.847423ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:33:56.843126Z","caller":"traceutil/trace.go:171","msg":"trace[1260197888] transaction","detail":"{read_only:false; response_revision:26224; number_of_response:1; }","duration":"433.871217ms","start":"2026-07-17T04:33:56.409226Z","end":"2026-07-17T04:33:56.843097Z","steps":["trace[1260197888] 'process raft request' (duration: 433.701742ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:56.843141Z","caller":"traceutil/trace.go:171","msg":"trace[177397804] transaction","detail":"{read_only:false; response_revision:26223; number_of_response:1; }","duration":"435.679369ms","start":"2026-07-17T04:33:56.407428Z","end":"2026-07-17T04:33:56.843107Z","steps":["trace[177397804] 'process raft request' (duration: 215.828816ms)","trace[177397804] 'compare' (duration: 218.869564ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:33:56.843188Z","caller":"traceutil/trace.go:171","msg":"trace[252900712] transaction","detail":"{read_only:false; response_revision:26225; number_of_response:1; }","duration":"171.756681ms","start":"2026-07-17T04:33:56.67142Z","end":"2026-07-17T04:33:56.843177Z","steps":["trace[252900712] 'process raft request' (duration: 171.654328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:56.843287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:33:56.407387Z","time spent":"435.812314ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T04:33:56.843287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:33:56.409193Z","time spent":"434.000651ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T04:33:56.843325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.523267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11714"} {"level":"info","ts":"2026-07-17T04:33:56.843366Z","caller":"traceutil/trace.go:171","msg":"trace[1689304967] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:26225; }","duration":"396.59404ms","start":"2026-07-17T04:33:56.44676Z","end":"2026-07-17T04:33:56.843354Z","steps":["trace[1689304967] 'agreement among raft nodes before linearized reading' (duration: 396.419334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:56.843393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:33:56.446745Z","time spent":"396.64312ms","remote":"127.0.0.1:40676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11736,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"info","ts":"2026-07-17T04:33:57.177514Z","caller":"traceutil/trace.go:171","msg":"trace[726923994] linearizableReadLoop","detail":"{readStateIndex:27094; appliedIndex:27093; }","duration":"334.342483ms","start":"2026-07-17T04:33:56.843151Z","end":"2026-07-17T04:33:57.177493Z","steps":["trace[726923994] 'read index received' (duration: 334.182858ms)","trace[726923994] 'applied index is now lower than readState.Index' (duration: 158.875µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T04:33:57.177789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.556752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-17T04:33:57.177831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.621925ms","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-07-17T04:33:57.177825Z","caller":"traceutil/trace.go:171","msg":"trace[1107669069] transaction","detail":"{read_only:false; response_revision:26226; number_of_response:1; }","duration":"483.827844ms","start":"2026-07-17T04:33:56.693959Z","end":"2026-07-17T04:33:57.177787Z","steps":["trace[1107669069] 'process raft request' (duration: 483.406732ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:57.177871Z","caller":"traceutil/trace.go:171","msg":"trace[1574448559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26226; }","duration":"487.696437ms","start":"2026-07-17T04:33:56.690156Z","end":"2026-07-17T04:33:57.177853Z","steps":["trace[1574448559] 'agreement among raft nodes before linearized reading' (duration: 487.418169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:57.177896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.792739ms","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-07-17T04:33:57.177912Z","caller":"traceutil/trace.go:171","msg":"trace[2022327623] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26226; }","duration":"487.751008ms","start":"2026-07-17T04:33:56.690116Z","end":"2026-07-17T04:33:57.177867Z","steps":["trace[2022327623] 'agreement among raft nodes before linearized reading' (duration: 487.47154ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:57.178018Z","caller":"traceutil/trace.go:171","msg":"trace[723183569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26226; }","duration":"487.937914ms","start":"2026-07-17T04:33:56.690056Z","end":"2026-07-17T04:33:57.177994Z","steps":["trace[723183569] 'agreement among raft nodes before linearized reading' (duration: 487.633625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:57.178049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:33:56.693945Z","time spent":"484.008569ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T04:33:57.17796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:33:56.690118Z","time spent":"487.79403ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T04:33:57.178062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.948382ms","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-07-17T04:33:57.178095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:33:56.690046Z","time spent":"488.030317ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-17T04:33:57.178141Z","caller":"traceutil/trace.go:171","msg":"trace[505636777] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26226; }","duration":"491.072065ms","start":"2026-07-17T04:33:56.687049Z","end":"2026-07-17T04:33:57.178121Z","steps":["trace[505636777] 'agreement among raft nodes before linearized reading' (duration: 490.758946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:57.178118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:33:56.690092Z","time spent":"488.011866ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T04:33:57.178199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:33:56.687032Z","time spent":"491.156958ms","remote":"127.0.0.1:40466","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-07-17T04:33:57.210529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.546318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T04:33:57.210592Z","caller":"traceutil/trace.go:171","msg":"trace[305187320] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26226; }","duration":"202.650992ms","start":"2026-07-17T04:33:57.007925Z","end":"2026-07-17T04:33:57.210576Z","steps":["trace[305187320] 'agreement among raft nodes before linearized reading' (duration: 202.536349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:57.210996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.546848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11714"} {"level":"info","ts":"2026-07-17T04:33:57.211082Z","caller":"traceutil/trace.go:171","msg":"trace[968515378] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:26226; }","duration":"360.990351ms","start":"2026-07-17T04:33:56.850067Z","end":"2026-07-17T04:33:57.211057Z","steps":["trace[968515378] 'agreement among raft nodes before linearized reading' (duration: 360.380513ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:57.211139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:33:56.850043Z","time spent":"361.087424ms","remote":"127.0.0.1:40676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11736,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-07-17T04:33:57.823871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.952512ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T04:33:57.823987Z","caller":"traceutil/trace.go:171","msg":"trace[1140598991] transaction","detail":"{read_only:false; response_revision:26231; number_of_response:1; }","duration":"499.037288ms","start":"2026-07-17T04:33:57.324929Z","end":"2026-07-17T04:33:57.823966Z","steps":["trace[1140598991] 'process raft request' (duration: 237.870169ms)","trace[1140598991] 'compare' (duration: 260.804678ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T04:33:57.824054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:33:57.324906Z","time spent":"499.11841ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T04:33:58.336189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.307388ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T04:33:58.336442Z","caller":"traceutil/trace.go:171","msg":"trace[933234009] linearizableReadLoop","detail":"{readStateIndex:27103; appliedIndex:27099; }","duration":"814.588773ms","start":"2026-07-17T04:33:57.521835Z","end":"2026-07-17T04:33:58.336424Z","steps":["trace[933234009] 'read index received' (duration: 41.12554ms)","trace[933234009] 'applied index is now lower than readState.Index' (duration: 773.462273ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T04:33:58.33664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.81037ms","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-07-17T04:33:58.336617Z","caller":"traceutil/trace.go:171","msg":"trace[26006857] transaction","detail":"{read_only:false; response_revision:26234; number_of_response:1; }","duration":"821.763692ms","start":"2026-07-17T04:33:57.51479Z","end":"2026-07-17T04:33:58.336554Z","steps":["trace[26006857] 'process raft request' (duration: 821.592908ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:58.336767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.356661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T04:33:58.336777Z","caller":"traceutil/trace.go:171","msg":"trace[1698755455] transaction","detail":"{read_only:false; response_revision:26232; number_of_response:1; }","duration":"988.955179ms","start":"2026-07-17T04:33:57.347786Z","end":"2026-07-17T04:33:58.336741Z","steps":["trace[1698755455] 'process raft request' (duration: 695.992592ms)","trace[1698755455] 'compare' (duration: 292.165244ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:33:58.336817Z","caller":"traceutil/trace.go:171","msg":"trace[407865173] transaction","detail":"{read_only:false; response_revision:26233; number_of_response:1; }","duration":"831.031523ms","start":"2026-07-17T04:33:57.505755Z","end":"2026-07-17T04:33:58.336786Z","steps":["trace[407865173] 'process raft request' (duration: 830.539569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:58.336934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.609135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-17T04:33:58.336972Z","caller":"traceutil/trace.go:171","msg":"trace[1733302802] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26234; }","duration":"281.684787ms","start":"2026-07-17T04:33:58.055279Z","end":"2026-07-17T04:33:58.336964Z","steps":["trace[1733302802] 'agreement among raft nodes before linearized reading' (duration: 281.575814ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:58.336969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:33:57.347752Z","time spent":"989.123774ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T04:33:58.336821Z","caller":"traceutil/trace.go:171","msg":"trace[2067844027] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:26234; }","duration":"724.452294ms","start":"2026-07-17T04:33:57.612358Z","end":"2026-07-17T04:33:58.33681Z","steps":["trace[2067844027] 'agreement among raft nodes before linearized reading' (duration: 724.352091ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:58.336682Z","caller":"traceutil/trace.go:171","msg":"trace[1791736867] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26234; }","duration":"814.885162ms","start":"2026-07-17T04:33:57.521784Z","end":"2026-07-17T04:33:58.336669Z","steps":["trace[1791736867] 'agreement among raft nodes before linearized reading' (duration: 814.751118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:58.337096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:33:57.612335Z","time spent":"724.717262ms","remote":"127.0.0.1:40750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":6,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-17T04:33:58.337185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:33:57.521769Z","time spent":"815.382206ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T04:33:58.337212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:33:57.505727Z","time spent":"831.242218ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T04:33:58.337139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:33:57.514764Z","time spent":"822.00635ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T04:33:58.694232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.357189ms","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-07-17T04:33:58.694311Z","caller":"traceutil/trace.go:171","msg":"trace[1028324350] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26235; }","duration":"254.493213ms","start":"2026-07-17T04:33:58.439796Z","end":"2026-07-17T04:33:58.69429Z","steps":["trace[1028324350] 'range keys from in-memory index tree' (duration: 254.175704ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:58.694955Z","caller":"traceutil/trace.go:171","msg":"trace[1999579558] linearizableReadLoop","detail":"{readStateIndex:27105; appliedIndex:27104; }","duration":"205.546817ms","start":"2026-07-17T04:33:58.489392Z","end":"2026-07-17T04:33:58.694938Z","steps":["trace[1999579558] 'read index received' (duration: 203.626001ms)","trace[1999579558] 'applied index is now lower than readState.Index' (duration: 1.919946ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T04:33:58.695033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.631949ms","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-07-17T04:33:58.695065Z","caller":"traceutil/trace.go:171","msg":"trace[1659470104] 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:26236; }","duration":"205.706662ms","start":"2026-07-17T04:33:58.489349Z","end":"2026-07-17T04:33:58.695056Z","steps":["trace[1659470104] 'agreement among raft nodes before linearized reading' (duration: 205.64248ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:58.695123Z","caller":"traceutil/trace.go:171","msg":"trace[192932000] transaction","detail":"{read_only:false; response_revision:26236; number_of_response:1; }","duration":"253.757583ms","start":"2026-07-17T04:33:58.441338Z","end":"2026-07-17T04:33:58.695095Z","steps":["trace[192932000] 'process raft request' (duration: 251.671382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:58.959004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.788732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T04:33:58.959085Z","caller":"traceutil/trace.go:171","msg":"trace[278603724] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:26237; }","duration":"135.931476ms","start":"2026-07-17T04:33:58.823135Z","end":"2026-07-17T04:33:58.959066Z","steps":["trace[278603724] 'count revisions from in-memory index tree' (duration: 135.464061ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:58.959015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.497568ms","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":"info","ts":"2026-07-17T04:33:58.959162Z","caller":"traceutil/trace.go:171","msg":"trace[688071330] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:26237; }","duration":"117.661562ms","start":"2026-07-17T04:33:58.841483Z","end":"2026-07-17T04:33:58.959145Z","steps":["trace[688071330] 'count revisions from in-memory index tree' (duration: 117.354173ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:58.959372Z","caller":"traceutil/trace.go:171","msg":"trace[441875903] transaction","detail":"{read_only:false; response_revision:26238; number_of_response:1; }","duration":"108.98358ms","start":"2026-07-17T04:33:58.850347Z","end":"2026-07-17T04:33:58.959331Z","steps":["trace[441875903] 'process raft request' (duration: 95.101965ms)","trace[441875903] 'compare' (duration: 13.518374ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:33:58.959431Z","caller":"traceutil/trace.go:171","msg":"trace[1347729587] transaction","detail":"{read_only:false; response_revision:26239; number_of_response:1; }","duration":"108.940618ms","start":"2026-07-17T04:33:58.85048Z","end":"2026-07-17T04:33:58.959421Z","steps":["trace[1347729587] 'process raft request' (duration: 108.65933ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:58.959577Z","caller":"traceutil/trace.go:171","msg":"trace[1006734347] transaction","detail":"{read_only:false; response_revision:26240; number_of_response:1; }","duration":"109.065912ms","start":"2026-07-17T04:33:58.850493Z","end":"2026-07-17T04:33:58.959559Z","steps":["trace[1006734347] 'process raft request' (duration: 108.755813ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:59.474077Z","caller":"traceutil/trace.go:171","msg":"trace[245874150] transaction","detail":"{read_only:false; response_revision:26241; number_of_response:1; }","duration":"161.821411ms","start":"2026-07-17T04:33:59.312234Z","end":"2026-07-17T04:33:59.474056Z","steps":["trace[245874150] 'process raft request' (duration: 161.653406ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:59.561179Z","caller":"traceutil/trace.go:171","msg":"trace[61886697] transaction","detail":"{read_only:false; response_revision:26242; number_of_response:1; }","duration":"248.189311ms","start":"2026-07-17T04:33:59.312962Z","end":"2026-07-17T04:33:59.561151Z","steps":["trace[61886697] 'process raft request' (duration: 247.894382ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:59.561402Z","caller":"traceutil/trace.go:171","msg":"trace[514879264] linearizableReadLoop","detail":"{readStateIndex:27113; appliedIndex:27109; }","duration":"205.953219ms","start":"2026-07-17T04:33:59.355412Z","end":"2026-07-17T04:33:59.561366Z","steps":["trace[514879264] 'read index received' (duration: 118.525399ms)","trace[514879264] 'applied index is now lower than readState.Index' (duration: 87.42668ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:33:59.561581Z","caller":"traceutil/trace.go:171","msg":"trace[1286661320] transaction","detail":"{read_only:false; response_revision:26244; number_of_response:1; }","duration":"248.531721ms","start":"2026-07-17T04:33:59.313024Z","end":"2026-07-17T04:33:59.561556Z","steps":["trace[1286661320] 'process raft request' (duration: 248.052267ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:59.561696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.212786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T04:33:59.561127Z","caller":"traceutil/trace.go:171","msg":"trace[1962955462] transaction","detail":"{read_only:false; response_revision:26243; number_of_response:1; }","duration":"248.078157ms","start":"2026-07-17T04:33:59.313019Z","end":"2026-07-17T04:33:59.561097Z","steps":["trace[1962955462] 'process raft request' (duration: 247.995425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:33:59.561835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.428403ms","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-07-17T04:33:59.561794Z","caller":"traceutil/trace.go:171","msg":"trace[31542237] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:26244; }","duration":"206.411222ms","start":"2026-07-17T04:33:59.35537Z","end":"2026-07-17T04:33:59.561781Z","steps":["trace[31542237] 'agreement among raft nodes before linearized reading' (duration: 206.194106ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:33:59.562004Z","caller":"traceutil/trace.go:171","msg":"trace[2076531747] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:26244; }","duration":"180.65628ms","start":"2026-07-17T04:33:59.381323Z","end":"2026-07-17T04:33:59.56198Z","steps":["trace[2076531747] 'agreement among raft nodes before linearized reading' (duration: 180.336751ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:07.731462Z","caller":"traceutil/trace.go:171","msg":"trace[648511277] linearizableReadLoop","detail":"{readStateIndex:27172; appliedIndex:27171; }","duration":"122.128602ms","start":"2026-07-17T04:34:07.609308Z","end":"2026-07-17T04:34:07.731437Z","steps":["trace[648511277] 'read index received' (duration: 121.892766ms)","trace[648511277] 'applied index is now lower than readState.Index' (duration: 234.976µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:34:07.731857Z","caller":"traceutil/trace.go:171","msg":"trace[1922622507] transaction","detail":"{read_only:false; response_revision:26301; number_of_response:1; }","duration":"188.889621ms","start":"2026-07-17T04:34:07.542949Z","end":"2026-07-17T04:34:07.731839Z","steps":["trace[1922622507] 'process raft request' (duration: 188.332094ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:34:07.732152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.870454ms","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-07-17T04:34:07.732197Z","caller":"traceutil/trace.go:171","msg":"trace[1450507208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26301; }","duration":"123.017369ms","start":"2026-07-17T04:34:07.609169Z","end":"2026-07-17T04:34:07.732187Z","steps":["trace[1450507208] 'agreement among raft nodes before linearized reading' (duration: 122.875595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:34:07.732403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.29851ms","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-07-17T04:34:07.732485Z","caller":"traceutil/trace.go:171","msg":"trace[1866896805] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26301; }","duration":"118.452456ms","start":"2026-07-17T04:34:07.614012Z","end":"2026-07-17T04:34:07.732464Z","steps":["trace[1866896805] 'agreement among raft nodes before linearized reading' (duration: 118.202159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:34:07.732463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.516519ms","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-07-17T04:34:07.732545Z","caller":"traceutil/trace.go:171","msg":"trace[1770786541] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26301; }","duration":"116.652233ms","start":"2026-07-17T04:34:07.615874Z","end":"2026-07-17T04:34:07.732527Z","steps":["trace[1770786541] 'agreement among raft nodes before linearized reading' (duration: 116.368164ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:08.043964Z","caller":"traceutil/trace.go:171","msg":"trace[472828628] linearizableReadLoop","detail":"{readStateIndex:27176; appliedIndex:27175; }","duration":"113.45739ms","start":"2026-07-17T04:34:07.93049Z","end":"2026-07-17T04:34:08.043948Z","steps":["trace[472828628] 'read index received' (duration: 113.295266ms)","trace[472828628] 'applied index is now lower than readState.Index' (duration: 159.674µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:34:08.044194Z","caller":"traceutil/trace.go:171","msg":"trace[1025335909] transaction","detail":"{read_only:false; response_revision:26305; number_of_response:1; }","duration":"187.833229ms","start":"2026-07-17T04:34:07.856351Z","end":"2026-07-17T04:34:08.044184Z","steps":["trace[1025335909] 'process raft request' (duration: 187.490099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:34:08.044373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.864232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T04:34:08.044409Z","caller":"traceutil/trace.go:171","msg":"trace[507920369] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:26305; }","duration":"113.946664ms","start":"2026-07-17T04:34:07.930454Z","end":"2026-07-17T04:34:08.044401Z","steps":["trace[507920369] 'agreement among raft nodes before linearized reading' (duration: 113.873902ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:08.485828Z","caller":"traceutil/trace.go:171","msg":"trace[1931367488] transaction","detail":"{read_only:false; response_revision:26306; number_of_response:1; }","duration":"111.095521ms","start":"2026-07-17T04:34:08.374701Z","end":"2026-07-17T04:34:08.485796Z","steps":["trace[1931367488] 'process raft request' (duration: 110.928446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:34:08.831271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.252995ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-17T04:34:08.831604Z","caller":"traceutil/trace.go:171","msg":"trace[2019569178] transaction","detail":"{read_only:false; response_revision:26307; number_of_response:1; }","duration":"456.857727ms","start":"2026-07-17T04:34:08.374709Z","end":"2026-07-17T04:34:08.831567Z","steps":["trace[2019569178] 'process raft request' (duration: 223.960323ms)","trace[2019569178] 'compare' (duration: 231.767392ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:34:08.831659Z","caller":"traceutil/trace.go:171","msg":"trace[2012177022] linearizableReadLoop","detail":"{readStateIndex:27179; appliedIndex:27176; }","duration":"363.35832ms","start":"2026-07-17T04:34:08.468278Z","end":"2026-07-17T04:34:08.831637Z","steps":["trace[2012177022] 'read index received' (duration: 17.417649ms)","trace[2012177022] 'applied index is now lower than readState.Index' (duration: 345.939471ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T04:34:08.831786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:34:08.374675Z","time spent":"457.021672ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T04:34:08.831881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.593567ms","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-07-17T04:34:08.831925Z","caller":"traceutil/trace.go:171","msg":"trace[720138717] transaction","detail":"{read_only:false; response_revision:26308; number_of_response:1; }","duration":"453.388166ms","start":"2026-07-17T04:34:08.378357Z","end":"2026-07-17T04:34:08.831745Z","steps":["trace[720138717] 'process raft request' (duration: 453.198561ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:08.831963Z","caller":"traceutil/trace.go:171","msg":"trace[126181711] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26308; }","duration":"363.725361ms","start":"2026-07-17T04:34:08.468217Z","end":"2026-07-17T04:34:08.831942Z","steps":["trace[126181711] 'agreement among raft nodes before linearized reading' (duration: 363.546386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:34:08.832011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:34:08.468192Z","time spent":"363.807164ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T04:34:08.83202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T04:34:08.378339Z","time spent":"453.625533ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T04:34:08.866758Z","caller":"traceutil/trace.go:171","msg":"trace[144862104] transaction","detail":"{read_only:false; response_revision:26309; number_of_response:1; }","duration":"115.725496ms","start":"2026-07-17T04:34:08.750999Z","end":"2026-07-17T04:34:08.866725Z","steps":["trace[144862104] 'process raft request' (duration: 115.564522ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:09.02416Z","caller":"traceutil/trace.go:171","msg":"trace[248018729] transaction","detail":"{read_only:false; response_revision:26310; number_of_response:1; }","duration":"230.37521ms","start":"2026-07-17T04:34:08.793749Z","end":"2026-07-17T04:34:09.024124Z","steps":["trace[248018729] 'process raft request' (duration: 228.773553ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:09.024274Z","caller":"traceutil/trace.go:171","msg":"trace[1564133695] linearizableReadLoop","detail":"{readStateIndex:27181; appliedIndex:27179; }","duration":"192.548437ms","start":"2026-07-17T04:34:08.831701Z","end":"2026-07-17T04:34:09.024249Z","steps":["trace[1564133695] 'read index received' (duration: 34.833947ms)","trace[1564133695] 'applied index is now lower than readState.Index' (duration: 157.71346ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:34:09.024316Z","caller":"traceutil/trace.go:171","msg":"trace[1143245528] transaction","detail":"{read_only:false; response_revision:26311; number_of_response:1; }","duration":"185.583983ms","start":"2026-07-17T04:34:08.838706Z","end":"2026-07-17T04:34:09.02429Z","steps":["trace[1143245528] 'process raft request' (duration: 185.369347ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:34:09.024442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.679623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T04:34:09.024478Z","caller":"traceutil/trace.go:171","msg":"trace[1967304732] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:26312; }","duration":"261.759795ms","start":"2026-07-17T04:34:08.762706Z","end":"2026-07-17T04:34:09.024466Z","steps":["trace[1967304732] 'agreement among raft nodes before linearized reading' (duration: 261.676153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:34:09.024547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.357786ms","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-07-17T04:34:09.024657Z","caller":"traceutil/trace.go:171","msg":"trace[1432195029] 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:26312; }","duration":"223.569492ms","start":"2026-07-17T04:34:08.801057Z","end":"2026-07-17T04:34:09.024626Z","steps":["trace[1432195029] 'agreement among raft nodes before linearized reading' (duration: 223.235032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:34:09.024562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.701439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-17T04:34:09.024773Z","caller":"traceutil/trace.go:171","msg":"trace[574254925] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26312; }","duration":"218.964017ms","start":"2026-07-17T04:34:08.805795Z","end":"2026-07-17T04:34:09.024759Z","steps":["trace[574254925] 'agreement among raft nodes before linearized reading' (duration: 218.459432ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:16.696339Z","caller":"traceutil/trace.go:171","msg":"trace[1157977705] transaction","detail":"{read_only:false; response_revision:26425; number_of_response:1; }","duration":"143.527958ms","start":"2026-07-17T04:34:16.552757Z","end":"2026-07-17T04:34:16.696285Z","steps":["trace[1157977705] 'process raft request' (duration: 86.207355ms)","trace[1157977705] 'compare' (duration: 56.92656ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:34:16.978859Z","caller":"traceutil/trace.go:171","msg":"trace[1131252869] transaction","detail":"{read_only:false; response_revision:26426; number_of_response:1; }","duration":"120.30828ms","start":"2026-07-17T04:34:16.85851Z","end":"2026-07-17T04:34:16.978818Z","steps":["trace[1131252869] 'process raft request' (duration: 71.642601ms)","trace[1131252869] 'compare' (duration: 48.493464ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:34:16.994056Z","caller":"traceutil/trace.go:171","msg":"trace[1507980045] transaction","detail":"{read_only:false; response_revision:26427; number_of_response:1; }","duration":"128.808267ms","start":"2026-07-17T04:34:16.865199Z","end":"2026-07-17T04:34:16.994007Z","steps":["trace[1507980045] 'process raft request' (duration: 128.55428ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:17.247153Z","caller":"traceutil/trace.go:171","msg":"trace[882604483] transaction","detail":"{read_only:false; response_revision:26430; number_of_response:1; }","duration":"189.945821ms","start":"2026-07-17T04:34:17.057183Z","end":"2026-07-17T04:34:17.247129Z","steps":["trace[882604483] 'process raft request' (duration: 189.619412ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:17.247249Z","caller":"traceutil/trace.go:171","msg":"trace[1941233542] transaction","detail":"{read_only:false; response_revision:26431; number_of_response:1; }","duration":"189.020783ms","start":"2026-07-17T04:34:17.058183Z","end":"2026-07-17T04:34:17.247204Z","steps":["trace[1941233542] 'process raft request' (duration: 188.796607ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:17.247366Z","caller":"traceutil/trace.go:171","msg":"trace[1254961734] linearizableReadLoop","detail":"{readStateIndex:27304; appliedIndex:27301; }","duration":"185.966114ms","start":"2026-07-17T04:34:17.061099Z","end":"2026-07-17T04:34:17.247065Z","steps":["trace[1254961734] 'read index received' (duration: 13.740041ms)","trace[1254961734] 'applied index is now lower than readState.Index' (duration: 172.224853ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T04:34:17.247547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.410538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-17T04:34:17.247634Z","caller":"traceutil/trace.go:171","msg":"trace[1011480690] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26431; }","duration":"186.566642ms","start":"2026-07-17T04:34:17.061043Z","end":"2026-07-17T04:34:17.247609Z","steps":["trace[1011480690] 'agreement among raft nodes before linearized reading' (duration: 186.279144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:34:17.316394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.656715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b5f3805c-8bb7-4446-9b9f-71f52016a63d\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-07-17T04:34:17.316501Z","caller":"traceutil/trace.go:171","msg":"trace[1209080629] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b5f3805c-8bb7-4446-9b9f-71f52016a63d; range_end:; response_count:1; response_revision:26431; }","duration":"175.814419ms","start":"2026-07-17T04:34:17.140661Z","end":"2026-07-17T04:34:17.316476Z","steps":["trace[1209080629] 'agreement among raft nodes before linearized reading' (duration: 175.588193ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:19.157593Z","caller":"traceutil/trace.go:171","msg":"trace[1388023270] transaction","detail":"{read_only:false; response_revision:26442; number_of_response:1; }","duration":"158.902126ms","start":"2026-07-17T04:34:18.998663Z","end":"2026-07-17T04:34:19.157565Z","steps":["trace[1388023270] 'process raft request' (duration: 158.681959ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:19.157844Z","caller":"traceutil/trace.go:171","msg":"trace[1055608310] transaction","detail":"{read_only:false; response_revision:26443; number_of_response:1; }","duration":"156.687371ms","start":"2026-07-17T04:34:19.001147Z","end":"2026-07-17T04:34:19.157834Z","steps":["trace[1055608310] 'process raft request' (duration: 156.365691ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:19.158068Z","caller":"traceutil/trace.go:171","msg":"trace[1779339373] linearizableReadLoop","detail":"{readStateIndex:27318; appliedIndex:27315; }","duration":"101.554982ms","start":"2026-07-17T04:34:19.056497Z","end":"2026-07-17T04:34:19.158052Z","steps":["trace[1779339373] 'read index received' (duration: 100.967515ms)","trace[1779339373] 'applied index is now lower than readState.Index' (duration: 586.197µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:34:19.158198Z","caller":"traceutil/trace.go:171","msg":"trace[392553534] transaction","detail":"{read_only:false; response_revision:26444; number_of_response:1; }","duration":"121.046061ms","start":"2026-07-17T04:34:19.037103Z","end":"2026-07-17T04:34:19.158149Z","steps":["trace[392553534] 'process raft request' (duration: 120.6881ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:34:19.158264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.751938ms","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-07-17T04:34:19.15831Z","caller":"traceutil/trace.go:171","msg":"trace[223849290] 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:26444; }","duration":"101.891972ms","start":"2026-07-17T04:34:19.056404Z","end":"2026-07-17T04:34:19.158296Z","steps":["trace[223849290] 'agreement among raft nodes before linearized reading' (duration: 101.700506ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:22.086487Z","caller":"traceutil/trace.go:171","msg":"trace[1134051140] linearizableReadLoop","detail":"{readStateIndex:27358; appliedIndex:27357; }","duration":"127.316135ms","start":"2026-07-17T04:34:21.959145Z","end":"2026-07-17T04:34:22.086461Z","steps":["trace[1134051140] 'read index received' (duration: 127.131229ms)","trace[1134051140] 'applied index is now lower than readState.Index' (duration: 184.126µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:34:22.086578Z","caller":"traceutil/trace.go:171","msg":"trace[1496477611] transaction","detail":"{read_only:false; response_revision:26482; number_of_response:1; }","duration":"136.230974ms","start":"2026-07-17T04:34:21.950325Z","end":"2026-07-17T04:34:22.086556Z","steps":["trace[1496477611] 'process raft request' (duration: 135.993388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:34:22.086692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.49927ms","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-07-17T04:34:22.087052Z","caller":"traceutil/trace.go:171","msg":"trace[917638113] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:26482; }","duration":"127.878911ms","start":"2026-07-17T04:34:21.959112Z","end":"2026-07-17T04:34:22.086991Z","steps":["trace[917638113] 'agreement among raft nodes before linearized reading' (duration: 127.475919ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:25.61811Z","caller":"traceutil/trace.go:171","msg":"trace[1253727221] transaction","detail":"{read_only:false; response_revision:26520; number_of_response:1; }","duration":"110.780731ms","start":"2026-07-17T04:34:25.50731Z","end":"2026-07-17T04:34:25.618091Z","steps":["trace[1253727221] 'process raft request' (duration: 110.38938ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:30.723101Z","caller":"traceutil/trace.go:171","msg":"trace[1267714527] transaction","detail":"{read_only:false; response_revision:26557; number_of_response:1; }","duration":"121.843135ms","start":"2026-07-17T04:34:30.60123Z","end":"2026-07-17T04:34:30.723073Z","steps":["trace[1267714527] 'process raft request' (duration: 75.847083ms)","trace[1267714527] 'compare' (duration: 45.782446ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:34:30.723381Z","caller":"traceutil/trace.go:171","msg":"trace[1085809199] transaction","detail":"{read_only:false; response_revision:26558; number_of_response:1; }","duration":"102.11852ms","start":"2026-07-17T04:34:30.621038Z","end":"2026-07-17T04:34:30.723157Z","steps":["trace[1085809199] 'process raft request' (duration: 101.973545ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:39.365804Z","caller":"traceutil/trace.go:171","msg":"trace[1583190500] linearizableReadLoop","detail":"{readStateIndex:27501; appliedIndex:27500; }","duration":"103.725456ms","start":"2026-07-17T04:34:39.262044Z","end":"2026-07-17T04:34:39.365769Z","steps":["trace[1583190500] 'read index received' (duration: 74.317357ms)","trace[1583190500] 'applied index is now lower than readState.Index' (duration: 29.406889ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:34:39.365948Z","caller":"traceutil/trace.go:171","msg":"trace[89775573] transaction","detail":"{read_only:false; response_revision:26622; number_of_response:1; }","duration":"144.325271ms","start":"2026-07-17T04:34:39.22152Z","end":"2026-07-17T04:34:39.365845Z","steps":["trace[89775573] 'process raft request' (duration: 114.83254ms)","trace[89775573] 'compare' (duration: 29.079179ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T04:34:39.36604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.973413ms","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-07-17T04:34:39.366075Z","caller":"traceutil/trace.go:171","msg":"trace[809743137] 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:26622; }","duration":"104.069186ms","start":"2026-07-17T04:34:39.261995Z","end":"2026-07-17T04:34:39.366064Z","steps":["trace[809743137] 'agreement among raft nodes before linearized reading' (duration: 103.967923ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:39.577652Z","caller":"traceutil/trace.go:171","msg":"trace[1545989306] transaction","detail":"{read_only:false; response_revision:26624; number_of_response:1; }","duration":"234.017305ms","start":"2026-07-17T04:34:39.343583Z","end":"2026-07-17T04:34:39.5776Z","steps":["trace[1545989306] 'process raft request' (duration: 204.454404ms)","trace[1545989306] 'compare' (duration: 29.272523ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:34:39.577687Z","caller":"traceutil/trace.go:171","msg":"trace[2094815878] linearizableReadLoop","detail":"{readStateIndex:27504; appliedIndex:27501; }","duration":"211.797378ms","start":"2026-07-17T04:34:39.365866Z","end":"2026-07-17T04:34:39.577663Z","steps":["trace[2094815878] 'read index received' (duration: 52.622976ms)","trace[2094815878] 'applied index is now lower than readState.Index' (duration: 159.173392ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:34:39.577707Z","caller":"traceutil/trace.go:171","msg":"trace[1072611857] transaction","detail":"{read_only:false; response_revision:26625; number_of_response:1; }","duration":"226.656821ms","start":"2026-07-17T04:34:39.351015Z","end":"2026-07-17T04:34:39.577672Z","steps":["trace[1072611857] 'process raft request' (duration: 226.481166ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:39.577811Z","caller":"traceutil/trace.go:171","msg":"trace[316331002] transaction","detail":"{read_only:false; response_revision:26626; number_of_response:1; }","duration":"201.866658ms","start":"2026-07-17T04:34:39.375933Z","end":"2026-07-17T04:34:39.5778Z","steps":["trace[316331002] 'process raft request' (duration: 201.652042ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:39.577999Z","caller":"traceutil/trace.go:171","msg":"trace[1502546533] transaction","detail":"{read_only:false; response_revision:26627; number_of_response:1; }","duration":"200.647174ms","start":"2026-07-17T04:34:39.377343Z","end":"2026-07-17T04:34:39.57799Z","steps":["trace[1502546533] 'process raft request' (duration: 200.275252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:34:39.578233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.149479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-17T04:34:39.578279Z","caller":"traceutil/trace.go:171","msg":"trace[1273967420] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:26627; }","duration":"224.261661ms","start":"2026-07-17T04:34:39.354004Z","end":"2026-07-17T04:34:39.578266Z","steps":["trace[1273967420] 'agreement among raft nodes before linearized reading' (duration: 224.076936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:34:39.578653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.045031ms","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-07-17T04:34:39.578708Z","caller":"traceutil/trace.go:171","msg":"trace[378317246] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26627; }","duration":"158.148644ms","start":"2026-07-17T04:34:39.420544Z","end":"2026-07-17T04:34:39.578692Z","steps":["trace[378317246] 'agreement among raft nodes before linearized reading' (duration: 157.982709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:34:39.57873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.657065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T04:34:39.578816Z","caller":"traceutil/trace.go:171","msg":"trace[311944224] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:26627; }","duration":"173.813501ms","start":"2026-07-17T04:34:39.404981Z","end":"2026-07-17T04:34:39.578795Z","steps":["trace[311944224] 'agreement among raft nodes before linearized reading' (duration: 173.616015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:34:39.578732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.966658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T04:34:39.578992Z","caller":"traceutil/trace.go:171","msg":"trace[1425719261] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26627; }","duration":"207.230925ms","start":"2026-07-17T04:34:39.371715Z","end":"2026-07-17T04:34:39.578946Z","steps":["trace[1425719261] 'agreement among raft nodes before linearized reading' (duration: 206.758291ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:40.116076Z","caller":"traceutil/trace.go:171","msg":"trace[40384615] linearizableReadLoop","detail":"{readStateIndex:27508; appliedIndex:27507; }","duration":"108.427413ms","start":"2026-07-17T04:34:40.007626Z","end":"2026-07-17T04:34:40.116053Z","steps":["trace[40384615] 'read index received' (duration: 108.249228ms)","trace[40384615] 'applied index is now lower than readState.Index' (duration: 177.205µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:34:40.116146Z","caller":"traceutil/trace.go:171","msg":"trace[2063242286] transaction","detail":"{read_only:false; response_revision:26629; number_of_response:1; }","duration":"194.535335ms","start":"2026-07-17T04:34:39.921573Z","end":"2026-07-17T04:34:40.116108Z","steps":["trace[2063242286] 'process raft request' (duration: 194.324449ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:34:40.116311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.67566ms","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-07-17T04:34:40.116353Z","caller":"traceutil/trace.go:171","msg":"trace[883444965] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26629; }","duration":"108.776223ms","start":"2026-07-17T04:34:40.007567Z","end":"2026-07-17T04:34:40.116343Z","steps":["trace[883444965] 'agreement among raft nodes before linearized reading' (duration: 108.619019ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:34:40.116443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.750936ms","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-07-17T04:34:40.11655Z","caller":"traceutil/trace.go:171","msg":"trace[1958779235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26629; }","duration":"103.86362ms","start":"2026-07-17T04:34:40.012651Z","end":"2026-07-17T04:34:40.116515Z","steps":["trace[1958779235] 'agreement among raft nodes before linearized reading' (duration: 103.641834ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:34:40.116614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.417523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-17T04:34:40.11675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.93449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T04:34:40.116786Z","caller":"traceutil/trace.go:171","msg":"trace[1731305464] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26629; }","duration":"108.030832ms","start":"2026-07-17T04:34:40.008744Z","end":"2026-07-17T04:34:40.116775Z","steps":["trace[1731305464] 'agreement among raft nodes before linearized reading' (duration: 107.97566ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:40.116795Z","caller":"traceutil/trace.go:171","msg":"trace[1357667162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26629; }","duration":"108.613938ms","start":"2026-07-17T04:34:40.008113Z","end":"2026-07-17T04:34:40.116727Z","steps":["trace[1357667162] 'agreement among raft nodes before linearized reading' (duration: 108.134854ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:40.364835Z","caller":"traceutil/trace.go:171","msg":"trace[778647013] linearizableReadLoop","detail":"{readStateIndex:27511; appliedIndex:27508; }","duration":"110.698309ms","start":"2026-07-17T04:34:40.254116Z","end":"2026-07-17T04:34:40.364815Z","steps":["trace[778647013] 'read index received' (duration: 36.999769ms)","trace[778647013] 'applied index is now lower than readState.Index' (duration: 73.69735ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:34:40.364851Z","caller":"traceutil/trace.go:171","msg":"trace[1681182390] transaction","detail":"{read_only:false; response_revision:26631; number_of_response:1; }","duration":"242.411701ms","start":"2026-07-17T04:34:40.122406Z","end":"2026-07-17T04:34:40.364817Z","steps":["trace[1681182390] 'process raft request' (duration: 242.255656ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:40.364853Z","caller":"traceutil/trace.go:171","msg":"trace[253936952] transaction","detail":"{read_only:false; response_revision:26630; number_of_response:1; }","duration":"242.428622ms","start":"2026-07-17T04:34:40.122384Z","end":"2026-07-17T04:34:40.364813Z","steps":["trace[253936952] 'process raft request' (duration: 168.754573ms)","trace[253936952] 'compare' (duration: 73.280257ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:34:40.364918Z","caller":"traceutil/trace.go:171","msg":"trace[1690126933] transaction","detail":"{read_only:false; response_revision:26632; number_of_response:1; }","duration":"242.168924ms","start":"2026-07-17T04:34:40.122723Z","end":"2026-07-17T04:34:40.364892Z","steps":["trace[1690126933] 'process raft request' (duration: 242.03392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:34:40.365018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.885404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-07-17T04:34:40.365052Z","caller":"traceutil/trace.go:171","msg":"trace[889062994] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:26633; }","duration":"111.262745ms","start":"2026-07-17T04:34:40.253778Z","end":"2026-07-17T04:34:40.36504Z","steps":["trace[889062994] 'agreement among raft nodes before linearized reading' (duration: 111.113961ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:41.786453Z","caller":"traceutil/trace.go:171","msg":"trace[1710760166] transaction","detail":"{read_only:false; response_revision:26642; number_of_response:1; }","duration":"136.862813ms","start":"2026-07-17T04:34:41.649547Z","end":"2026-07-17T04:34:41.78641Z","steps":["trace[1710760166] 'process raft request' (duration: 59.30757ms)","trace[1710760166] 'compare' (duration: 77.366927ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T04:34:41.786819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.869813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-17T04:34:41.786877Z","caller":"traceutil/trace.go:171","msg":"trace[1858159448] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:26642; }","duration":"113.995466ms","start":"2026-07-17T04:34:41.67287Z","end":"2026-07-17T04:34:41.786866Z","steps":["trace[1858159448] 'agreement among raft nodes before linearized reading' (duration: 113.80469ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:41.786629Z","caller":"traceutil/trace.go:171","msg":"trace[843820344] linearizableReadLoop","detail":"{readStateIndex:27521; appliedIndex:27520; }","duration":"113.396448ms","start":"2026-07-17T04:34:41.672939Z","end":"2026-07-17T04:34:41.786335Z","steps":["trace[843820344] 'read index received' (duration: 35.824195ms)","trace[843820344] 'applied index is now lower than readState.Index' (duration: 77.571193ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T04:34:44.478469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.931447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T04:34:44.478565Z","caller":"traceutil/trace.go:171","msg":"trace[864739698] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:26675; }","duration":"147.081881ms","start":"2026-07-17T04:34:44.331461Z","end":"2026-07-17T04:34:44.478542Z","steps":["trace[864739698] 'count revisions from in-memory index tree' (duration: 146.833544ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:46.671727Z","caller":"traceutil/trace.go:171","msg":"trace[162855738] transaction","detail":"{read_only:false; response_revision:26694; number_of_response:1; }","duration":"126.8109ms","start":"2026-07-17T04:34:46.54489Z","end":"2026-07-17T04:34:46.671701Z","steps":["trace[162855738] 'process raft request' (duration: 126.702337ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:46.67174Z","caller":"traceutil/trace.go:171","msg":"trace[193074734] transaction","detail":"{read_only:false; response_revision:26693; number_of_response:1; }","duration":"127.488079ms","start":"2026-07-17T04:34:46.544225Z","end":"2026-07-17T04:34:46.671713Z","steps":["trace[193074734] 'process raft request' (duration: 92.844908ms)","trace[193074734] 'compare' (duration: 34.282931ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:34:52.987302Z","caller":"traceutil/trace.go:171","msg":"trace[1860103442] transaction","detail":"{read_only:false; response_revision:26738; number_of_response:1; }","duration":"104.853858ms","start":"2026-07-17T04:34:52.882417Z","end":"2026-07-17T04:34:52.987271Z","steps":["trace[1860103442] 'process raft request' (duration: 104.538738ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:55.694969Z","caller":"traceutil/trace.go:171","msg":"trace[1684451227] transaction","detail":"{read_only:false; response_revision:26761; number_of_response:1; }","duration":"104.611063ms","start":"2026-07-17T04:34:55.590329Z","end":"2026-07-17T04:34:55.69494Z","steps":["trace[1684451227] 'process raft request' (duration: 104.421507ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:55.83389Z","caller":"traceutil/trace.go:171","msg":"trace[430501721] transaction","detail":"{read_only:false; response_revision:26762; number_of_response:1; }","duration":"195.560983ms","start":"2026-07-17T04:34:55.6383Z","end":"2026-07-17T04:34:55.833861Z","steps":["trace[430501721] 'process raft request' (duration: 126.624283ms)","trace[430501721] 'compare' (duration: 68.436266ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:34:55.833961Z","caller":"traceutil/trace.go:171","msg":"trace[553049503] transaction","detail":"{read_only:false; response_revision:26763; number_of_response:1; }","duration":"190.790246ms","start":"2026-07-17T04:34:55.643152Z","end":"2026-07-17T04:34:55.833942Z","steps":["trace[553049503] 'process raft request' (duration: 190.495687ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:55.834191Z","caller":"traceutil/trace.go:171","msg":"trace[643133141] transaction","detail":"{read_only:false; response_revision:26765; number_of_response:1; }","duration":"179.670332ms","start":"2026-07-17T04:34:55.654507Z","end":"2026-07-17T04:34:55.834177Z","steps":["trace[643133141] 'process raft request' (duration: 179.481786ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:34:55.834088Z","caller":"traceutil/trace.go:171","msg":"trace[2046785568] transaction","detail":"{read_only:false; response_revision:26764; number_of_response:1; }","duration":"188.623502ms","start":"2026-07-17T04:34:55.645412Z","end":"2026-07-17T04:34:55.834036Z","steps":["trace[2046785568] 'process raft request' (duration: 188.410386ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:35:03.973029Z","caller":"traceutil/trace.go:171","msg":"trace[263781497] transaction","detail":"{read_only:false; response_revision:26840; number_of_response:1; }","duration":"109.039671ms","start":"2026-07-17T04:35:03.86396Z","end":"2026-07-17T04:35:03.973Z","steps":["trace[263781497] 'process raft request' (duration: 32.419637ms)","trace[263781497] 'compare' (duration: 76.410758ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:35:03.973354Z","caller":"traceutil/trace.go:171","msg":"trace[972316409] transaction","detail":"{read_only:false; response_revision:26841; number_of_response:1; }","duration":"109.235426ms","start":"2026-07-17T04:35:03.863956Z","end":"2026-07-17T04:35:03.973191Z","steps":["trace[972316409] 'process raft request' (duration: 108.992399ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:35:04.00022Z","caller":"traceutil/trace.go:171","msg":"trace[544228713] linearizableReadLoop","detail":"{readStateIndex:27727; appliedIndex:27723; }","duration":"111.054348ms","start":"2026-07-17T04:35:03.889145Z","end":"2026-07-17T04:35:04.0002Z","steps":["trace[544228713] 'read index received' (duration: 7.255519ms)","trace[544228713] 'applied index is now lower than readState.Index' (duration: 103.797949ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T04:35:04.000285Z","caller":"traceutil/trace.go:171","msg":"trace[1548763035] transaction","detail":"{read_only:false; response_revision:26843; number_of_response:1; }","duration":"132.872919ms","start":"2026-07-17T04:35:03.867387Z","end":"2026-07-17T04:35:04.00026Z","steps":["trace[1548763035] 'process raft request' (duration: 132.750655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:35:04.000669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.44489ms","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-07-17T04:35:04.000787Z","caller":"traceutil/trace.go:171","msg":"trace[1028476160] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26843; }","duration":"111.660286ms","start":"2026-07-17T04:35:03.889109Z","end":"2026-07-17T04:35:04.00077Z","steps":["trace[1028476160] 'agreement among raft nodes before linearized reading' (duration: 111.172661ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:35:04.001504Z","caller":"traceutil/trace.go:171","msg":"trace[1894115150] transaction","detail":"{read_only:false; response_revision:26842; number_of_response:1; }","duration":"135.801804ms","start":"2026-07-17T04:35:03.865511Z","end":"2026-07-17T04:35:04.001313Z","steps":["trace[1894115150] 'process raft request' (duration: 134.456164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T04:35:04.177565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.469681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-17T04:35:04.177641Z","caller":"traceutil/trace.go:171","msg":"trace[2080644412] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:26844; }","duration":"101.609346ms","start":"2026-07-17T04:35:04.076014Z","end":"2026-07-17T04:35:04.177623Z","steps":["trace[2080644412] 'count revisions from in-memory index tree' (duration: 101.368398ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:36:38.549331Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24949} {"level":"info","ts":"2026-07-17T04:36:38.696904Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24949,"took":"143.056023ms","hash":1201263022,"current-db-size-bytes":59072512,"current-db-size":"59 MB","current-db-size-in-use-bytes":36118528,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-07-17T04:36:38.69698Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1201263022,"revision":24949,"compact-revision":21880} {"level":"info","ts":"2026-07-17T04:38:36.610404Z","caller":"traceutil/trace.go:171","msg":"trace[1683010911] transaction","detail":"{read_only:false; response_revision:28932; number_of_response:1; }","duration":"111.678807ms","start":"2026-07-17T04:38:36.498708Z","end":"2026-07-17T04:38:36.610387Z","steps":["trace[1683010911] 'process raft request' (duration: 111.561883ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T04:38:46.432657Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3ba9ae53cc4335db","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-17T04:38:46.437954Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-17T04:38:46.438147Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-17T04:41:38.55557Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27789} {"level":"info","ts":"2026-07-17T04:41:38.696904Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27789,"took":"136.475673ms","hash":2361951718,"current-db-size-bytes":59072512,"current-db-size":"59 MB","current-db-size-in-use-bytes":34045952,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-17T04:41:38.697051Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2361951718,"revision":27789,"compact-revision":24949} {"level":"info","ts":"2026-07-17T04:46:38.563015Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30426} {"level":"info","ts":"2026-07-17T04:46:38.720444Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30426,"took":"153.234635ms","hash":2948311437,"current-db-size-bytes":59072512,"current-db-size":"59 MB","current-db-size-in-use-bytes":30777344,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-17T04:46:38.720536Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2948311437,"revision":30426,"compact-revision":27789} {"level":"info","ts":"2026-07-17T04:51:38.572099Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32685} {"level":"info","ts":"2026-07-17T04:51:38.713595Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32685,"took":"136.617891ms","hash":2523431095,"current-db-size-bytes":59072512,"current-db-size":"59 MB","current-db-size-in-use-bytes":30322688,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-17T04:51:38.713676Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2523431095,"revision":32685,"compact-revision":30426} {"level":"info","ts":"2026-07-17T04:56:38.578778Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34918} {"level":"info","ts":"2026-07-17T04:56:38.717269Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34918,"took":"134.512199ms","hash":2429582657,"current-db-size-bytes":59072512,"current-db-size":"59 MB","current-db-size-in-use-bytes":31170560,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-17T04:56:38.717358Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2429582657,"revision":34918,"compact-revision":32685} {"level":"info","ts":"2026-07-17T05:00:01.93948Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3ba9ae53cc4335db","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-17T05:00:01.94873Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-17T05:00:01.948836Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-17T05:01:38.586171Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37213} {"level":"info","ts":"2026-07-17T05:01:38.708537Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37213,"took":"118.207876ms","hash":1388522599,"current-db-size-bytes":59494400,"current-db-size":"60 MB","current-db-size-in-use-bytes":32763904,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-17T05:01:38.7086Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1388522599,"revision":37213,"compact-revision":34918} {"level":"info","ts":"2026-07-17T05:06:38.592805Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39558} {"level":"info","ts":"2026-07-17T05:06:38.715814Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39558,"took":"119.683879ms","hash":2915198095,"current-db-size-bytes":59916288,"current-db-size":"60 MB","current-db-size-in-use-bytes":31428608,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-17T05:06:38.715879Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2915198095,"revision":39558,"compact-revision":37213}