{"level":"warn","ts":"2026-08-11T11:15:51.701864Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-11T11:15:51.702031Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38: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.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-11T11:15:51.702159Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-11T11:15:51.702184Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-08-11T11:15:51.702221Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-11T11:15:51.703479Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-08-11T11:15:51.70362Z","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.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-11T11:15:51.706855Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.821959ms"} {"level":"info","ts":"2026-08-11T11:15:51.713117Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-08-11T11:15:51.713228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-08-11T11:15:51.713257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-08-11T11:15:51.713275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-11T11:15:51.71329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-08-11T11:15:51.713353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-08-11T11:15:51.718252Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-11T11:15:51.719972Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-11T11:15:51.721443Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-11T11:15:51.724136Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-11T11:15:51.724378Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-11T11:15:51.725044Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-11T11:15:51.72523Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-11T11:15:51.725242Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-11T11:15:51.725777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-08-11T11:15:51.725963Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-08-11T11:15:51.729001Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-11T11:15:51.729085Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-08-11T11:15:51.729157Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-08-11T11:15:51.730338Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-11T11:15:51.730379Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-11T11:15:52.014591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-08-11T11:15:52.014652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-11T11:15:52.014673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-08-11T11:15:52.014689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-08-11T11:15:52.014699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-08-11T11:15:52.014711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-08-11T11:15:52.014722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-08-11T11:15:52.015732Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-08-11T11:15:52.015755Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T11:15:52.015802Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T11:15:52.015848Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T11:15:52.016077Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-11T11:15:52.016158Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-11T11:15:52.016939Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T11:15:52.017083Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T11:15:52.017136Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T11:15:52.020399Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"info","ts":"2026-08-11T11:15:52.02044Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-11T11:16:33.292339Z","caller":"traceutil/trace.go:171","msg":"trace[1990250773] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"100.862824ms","start":"2026-08-11T11:16:33.191448Z","end":"2026-08-11T11:16:33.292311Z","steps":["trace[1990250773] 'process raft request' (duration: 91.523609ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:33.293057Z","caller":"traceutil/trace.go:171","msg":"trace[1851355246] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"101.403846ms","start":"2026-08-11T11:16:33.191622Z","end":"2026-08-11T11:16:33.293026Z","steps":["trace[1851355246] 'process raft request' (duration: 101.226332ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:38.975961Z","caller":"traceutil/trace.go:171","msg":"trace[1190494348] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"142.73033ms","start":"2026-08-11T11:16:38.833203Z","end":"2026-08-11T11:16:38.975933Z","steps":["trace[1190494348] 'process raft request' (duration: 114.711307ms)","trace[1190494348] 'compare' (duration: 27.850898ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:16:39.90935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.424978ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:16:39.909476Z","caller":"traceutil/trace.go:171","msg":"trace[1615509011] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"236.173617ms","start":"2026-08-11T11:16:39.673285Z","end":"2026-08-11T11:16:39.909458Z","steps":["trace[1615509011] 'read index received' (duration: 57.302448ms)","trace[1615509011] 'applied index is now lower than readState.Index' (duration: 178.869559ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:16:39.909588Z","caller":"traceutil/trace.go:171","msg":"trace[55372786] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"422.095754ms","start":"2026-08-11T11:16:39.487422Z","end":"2026-08-11T11:16:39.909518Z","steps":["trace[55372786] 'process raft request' (duration: 243.132904ms)","trace[55372786] 'compare' (duration: 178.262634ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:16:39.909743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.434103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-11T11:16:39.909785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.58785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mbsvk\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-11T11:16:39.909834Z","caller":"traceutil/trace.go:171","msg":"trace[1752452132] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mbsvk; range_end:; response_count:1; response_revision:599; }","duration":"210.658603ms","start":"2026-08-11T11:16:39.699164Z","end":"2026-08-11T11:16:39.909823Z","steps":["trace[1752452132] 'agreement among raft nodes before linearized reading' (duration: 210.54684ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:16:39.909842Z","caller":"traceutil/trace.go:171","msg":"trace[211392209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:599; }","duration":"236.567515ms","start":"2026-08-11T11:16:39.67325Z","end":"2026-08-11T11:16:39.909818Z","steps":["trace[211392209] 'agreement among raft nodes before linearized reading' (duration: 236.277379ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:39.909683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.017262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-11T11:16:39.909992Z","caller":"traceutil/trace.go:171","msg":"trace[1721146313] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"216.334649ms","start":"2026-08-11T11:16:39.693619Z","end":"2026-08-11T11:16:39.909954Z","steps":["trace[1721146313] 'agreement among raft nodes before linearized reading' (duration: 215.932049ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:16:39.909893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:16:39.487391Z","time spent":"422.359721ms","remote":"127.0.0.1:46342","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-08-11T11:17:22.766675Z","caller":"traceutil/trace.go:171","msg":"trace[799242318] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"203.078671ms","start":"2026-08-11T11:17:22.563577Z","end":"2026-08-11T11:17:22.766656Z","steps":["trace[799242318] 'process raft request' (duration: 202.933537ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:18:08.534181Z","caller":"traceutil/trace.go:171","msg":"trace[1503208801] linearizableReadLoop","detail":"{readStateIndex:1904; appliedIndex:1902; }","duration":"143.437326ms","start":"2026-08-11T11:18:08.390726Z","end":"2026-08-11T11:18:08.534163Z","steps":["trace[1503208801] 'read index received' (duration: 127.496809ms)","trace[1503208801] 'applied index is now lower than readState.Index' (duration: 15.939607ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:18:08.534189Z","caller":"traceutil/trace.go:171","msg":"trace[242216609] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"192.515356ms","start":"2026-08-11T11:18:08.341652Z","end":"2026-08-11T11:18:08.534167Z","steps":["trace[242216609] 'process raft request' (duration: 176.652851ms)","trace[242216609] 'compare' (duration: 15.433285ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:18:08.534239Z","caller":"traceutil/trace.go:171","msg":"trace[2046908987] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"189.508382ms","start":"2026-08-11T11:18:08.344725Z","end":"2026-08-11T11:18:08.534234Z","steps":["trace[2046908987] 'process raft request' (duration: 189.353369ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:18:08.534504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.775244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-11T11:18:08.534603Z","caller":"traceutil/trace.go:171","msg":"trace[712031842] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1834; }","duration":"143.918797ms","start":"2026-08-11T11:18:08.390647Z","end":"2026-08-11T11:18:08.534565Z","steps":["trace[712031842] 'agreement among raft nodes before linearized reading' (duration: 143.676782ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:18:24.281216Z","caller":"traceutil/trace.go:171","msg":"trace[1115968678] transaction","detail":"{read_only:false; response_revision:1957; number_of_response:1; }","duration":"129.024357ms","start":"2026-08-11T11:18:24.15216Z","end":"2026-08-11T11:18:24.281185Z","steps":["trace[1115968678] 'process raft request' (duration: 121.464244ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:19:04.130581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.871468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-11T11:19:04.130779Z","caller":"traceutil/trace.go:171","msg":"trace[680765021] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2182; }","duration":"142.126845ms","start":"2026-08-11T11:19:03.988636Z","end":"2026-08-11T11:19:04.130763Z","steps":["trace[680765021] 'range keys from in-memory index tree' (duration: 141.5282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:19:04.130651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.249442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-11T11:19:04.130993Z","caller":"traceutil/trace.go:171","msg":"trace[1876917769] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2182; }","duration":"207.500558ms","start":"2026-08-11T11:19:03.923376Z","end":"2026-08-11T11:19:04.130876Z","steps":["trace[1876917769] 'range keys from in-memory index tree' (duration: 207.080408ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:19:04.250086Z","caller":"traceutil/trace.go:171","msg":"trace[1366593885] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"114.591006ms","start":"2026-08-11T11:19:04.135454Z","end":"2026-08-11T11:19:04.250045Z","steps":["trace[1366593885] 'process raft request' (duration: 114.356771ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:24:10.451361Z","caller":"traceutil/trace.go:171","msg":"trace[861059345] linearizableReadLoop","detail":"{readStateIndex:5005; appliedIndex:5004; }","duration":"142.401702ms","start":"2026-08-11T11:24:10.308937Z","end":"2026-08-11T11:24:10.451339Z","steps":["trace[861059345] 'read index received' (duration: 142.051714ms)","trace[861059345] 'applied index is now lower than readState.Index' (duration: 348.778µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:24:10.451423Z","caller":"traceutil/trace.go:171","msg":"trace[990298088] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"194.967203ms","start":"2026-08-11T11:24:10.256423Z","end":"2026-08-11T11:24:10.45139Z","steps":["trace[990298088] 'process raft request' (duration: 194.671556ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:24:10.451548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.593537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:24:10.451655Z","caller":"traceutil/trace.go:171","msg":"trace[561717487] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4824; }","duration":"142.751541ms","start":"2026-08-11T11:24:10.308893Z","end":"2026-08-11T11:24:10.451645Z","steps":["trace[561717487] 'agreement among raft nodes before linearized reading' (duration: 142.596848ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:24:10.488525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.779147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-11T11:24:10.488618Z","caller":"traceutil/trace.go:171","msg":"trace[1838036361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4825; }","duration":"102.92587ms","start":"2026-08-11T11:24:10.385674Z","end":"2026-08-11T11:24:10.4886Z","steps":["trace[1838036361] 'agreement among raft nodes before linearized reading' (duration: 102.667944ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:24:15.391926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.897362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:24:15.392039Z","caller":"traceutil/trace.go:171","msg":"trace[1568866042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4986; }","duration":"172.060065ms","start":"2026-08-11T11:24:15.219958Z","end":"2026-08-11T11:24:15.392018Z","steps":["trace[1568866042] 'range keys from in-memory index tree' (duration: 171.822009ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:24:27.140197Z","caller":"traceutil/trace.go:171","msg":"trace[1135917289] transaction","detail":"{read_only:false; response_revision:5215; number_of_response:1; }","duration":"135.185026ms","start":"2026-08-11T11:24:27.004981Z","end":"2026-08-11T11:24:27.140166Z","steps":["trace[1135917289] 'process raft request' (duration: 81.517208ms)","trace[1135917289] 'compare' (duration: 53.491414ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:24:27.710749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.794953ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:24:27.710905Z","caller":"traceutil/trace.go:171","msg":"trace[2014502124] transaction","detail":"{read_only:false; response_revision:5221; number_of_response:1; }","duration":"406.438362ms","start":"2026-08-11T11:24:27.304445Z","end":"2026-08-11T11:24:27.710883Z","steps":["trace[2014502124] 'process raft request' (duration: 258.201187ms)","trace[2014502124] 'compare' (duration: 147.65432ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:24:27.710984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:24:27.304421Z","time spent":"406.519484ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1067,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:24:27.714382Z","caller":"traceutil/trace.go:171","msg":"trace[253239742] transaction","detail":"{read_only:false; response_revision:5222; number_of_response:1; }","duration":"409.630119ms","start":"2026-08-11T11:24:27.304721Z","end":"2026-08-11T11:24:27.714351Z","steps":["trace[253239742] 'process raft request' (duration: 409.447144ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:24:27.714542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:24:27.30469Z","time spent":"409.790573ms","remote":"127.0.0.1:46108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1038,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:24:27.714788Z","caller":"traceutil/trace.go:171","msg":"trace[2014200989] transaction","detail":"{read_only:false; response_revision:5223; number_of_response:1; }","duration":"409.322202ms","start":"2026-08-11T11:24:27.305438Z","end":"2026-08-11T11:24:27.71476Z","steps":["trace[2014200989] 'process raft request' (duration: 408.85202ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:24:27.714941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:24:27.305428Z","time spent":"409.443744ms","remote":"127.0.0.1:46022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":681,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:24:27.715076Z","caller":"traceutil/trace.go:171","msg":"trace[1360825875] transaction","detail":"{read_only:false; response_revision:5226; number_of_response:1; }","duration":"242.493524ms","start":"2026-08-11T11:24:27.472572Z","end":"2026-08-11T11:24:27.715066Z","steps":["trace[1360825875] 'process raft request' (duration: 242.460023ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:24:27.715209Z","caller":"traceutil/trace.go:171","msg":"trace[1691192797] transaction","detail":"{read_only:false; response_revision:5225; number_of_response:1; }","duration":"401.976502ms","start":"2026-08-11T11:24:27.313226Z","end":"2026-08-11T11:24:27.715202Z","steps":["trace[1691192797] 'process raft request' (duration: 401.760597ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:24:27.7152Z","caller":"traceutil/trace.go:171","msg":"trace[1173012106] transaction","detail":"{read_only:false; response_revision:5224; number_of_response:1; }","duration":"409.703951ms","start":"2026-08-11T11:24:27.305476Z","end":"2026-08-11T11:24:27.71518Z","steps":["trace[1173012106] 'process raft request' (duration: 409.177248ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:24:27.715264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:24:27.313202Z","time spent":"402.028363ms","remote":"127.0.0.1:46036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:24:27.71531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:24:27.305455Z","time spent":"409.795582ms","remote":"127.0.0.1:46022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":529,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:24:35.999572Z","caller":"traceutil/trace.go:171","msg":"trace[749605555] linearizableReadLoop","detail":"{readStateIndex:5618; appliedIndex:5616; }","duration":"197.414723ms","start":"2026-08-11T11:24:35.802138Z","end":"2026-08-11T11:24:35.999553Z","steps":["trace[749605555] 'read index received' (duration: 20.662203ms)","trace[749605555] 'applied index is now lower than readState.Index' (duration: 176.75155ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:24:35.999659Z","caller":"traceutil/trace.go:171","msg":"trace[402557752] transaction","detail":"{read_only:false; response_revision:5419; number_of_response:1; }","duration":"253.743567ms","start":"2026-08-11T11:24:35.745892Z","end":"2026-08-11T11:24:35.999635Z","steps":["trace[402557752] 'process raft request' (duration: 253.504432ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:24:35.999857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.68738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-964aae45-53da-4eef-8db9-b1ec4d8e4ef3\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-08-11T11:24:35.999966Z","caller":"traceutil/trace.go:171","msg":"trace[749848147] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-964aae45-53da-4eef-8db9-b1ec4d8e4ef3; range_end:; response_count:1; response_revision:5419; }","duration":"197.856884ms","start":"2026-08-11T11:24:35.802088Z","end":"2026-08-11T11:24:35.999945Z","steps":["trace[749848147] 'agreement among raft nodes before linearized reading' (duration: 197.570387ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:25:27.550504Z","caller":"traceutil/trace.go:171","msg":"trace[979284060] transaction","detail":"{read_only:false; response_revision:5883; number_of_response:1; }","duration":"138.793424ms","start":"2026-08-11T11:25:27.411683Z","end":"2026-08-11T11:25:27.550476Z","steps":["trace[979284060] 'process raft request' (duration: 110.890153ms)","trace[979284060] 'compare' (duration: 27.729537ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:25:27.566469Z","caller":"traceutil/trace.go:171","msg":"trace[99310433] linearizableReadLoop","detail":"{readStateIndex:6101; appliedIndex:6094; }","duration":"116.117211ms","start":"2026-08-11T11:25:27.450307Z","end":"2026-08-11T11:25:27.566425Z","steps":["trace[99310433] 'read index received' (duration: 72.339143ms)","trace[99310433] 'applied index is now lower than readState.Index' (duration: 43.777388ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:25:27.566805Z","caller":"traceutil/trace.go:171","msg":"trace[803230467] transaction","detail":"{read_only:false; response_revision:5884; number_of_response:1; }","duration":"153.964375ms","start":"2026-08-11T11:25:27.412832Z","end":"2026-08-11T11:25:27.566796Z","steps":["trace[803230467] 'process raft request' (duration: 153.094454ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:25:27.566942Z","caller":"traceutil/trace.go:171","msg":"trace[463583335] transaction","detail":"{read_only:false; response_revision:5885; number_of_response:1; }","duration":"154.022146ms","start":"2026-08-11T11:25:27.412914Z","end":"2026-08-11T11:25:27.566936Z","steps":["trace[463583335] 'process raft request' (duration: 153.32843ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:25:27.567134Z","caller":"traceutil/trace.go:171","msg":"trace[1735325355] transaction","detail":"{read_only:false; response_revision:5886; number_of_response:1; }","duration":"154.060887ms","start":"2026-08-11T11:25:27.413067Z","end":"2026-08-11T11:25:27.567128Z","steps":["trace[1735325355] 'process raft request' (duration: 153.204006ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:25:27.567232Z","caller":"traceutil/trace.go:171","msg":"trace[1152937170] transaction","detail":"{read_only:false; response_revision:5887; number_of_response:1; }","duration":"154.160269ms","start":"2026-08-11T11:25:27.413065Z","end":"2026-08-11T11:25:27.567225Z","steps":["trace[1152937170] 'process raft request' (duration: 153.275208ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:25:27.567379Z","caller":"traceutil/trace.go:171","msg":"trace[1743844248] transaction","detail":"{read_only:false; response_revision:5888; number_of_response:1; }","duration":"154.154259ms","start":"2026-08-11T11:25:27.413183Z","end":"2026-08-11T11:25:27.567338Z","steps":["trace[1743844248] 'process raft request' (duration: 153.181356ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:25:27.567868Z","caller":"traceutil/trace.go:171","msg":"trace[627324435] transaction","detail":"{read_only:false; response_revision:5889; number_of_response:1; }","duration":"151.453674ms","start":"2026-08-11T11:25:27.416405Z","end":"2026-08-11T11:25:27.567859Z","steps":["trace[627324435] 'process raft request' (duration: 149.983018ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:25:27.568032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.859927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-08-11T11:25:27.568091Z","caller":"traceutil/trace.go:171","msg":"trace[37867426] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5889; }","duration":"113.95783ms","start":"2026-08-11T11:25:27.454119Z","end":"2026-08-11T11:25:27.568077Z","steps":["trace[37867426] 'agreement among raft nodes before linearized reading' (duration: 113.791486ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:25:27.568839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.512819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225401"} {"level":"info","ts":"2026-08-11T11:25:27.569729Z","caller":"traceutil/trace.go:171","msg":"trace[402158489] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5889; }","duration":"119.446312ms","start":"2026-08-11T11:25:27.450257Z","end":"2026-08-11T11:25:27.569703Z","steps":["trace[402158489] 'agreement among raft nodes before linearized reading' (duration: 117.678439ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:25:52.324747Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2971} {"level":"info","ts":"2026-08-11T11:25:52.404839Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2971,"took":"79.060684ms","hash":558155591,"current-db-size-bytes":58245120,"current-db-size":"58 MB","current-db-size-in-use-bytes":34234368,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-11T11:25:52.404946Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":558155591,"revision":2971,"compact-revision":-1} {"level":"info","ts":"2026-08-11T11:30:49.839882Z","caller":"traceutil/trace.go:171","msg":"trace[1318024731] transaction","detail":"{read_only:false; response_revision:8257; number_of_response:1; }","duration":"241.299475ms","start":"2026-08-11T11:30:49.598558Z","end":"2026-08-11T11:30:49.839858Z","steps":["trace[1318024731] 'process raft request' (duration: 241.08736ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:30:52.331847Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6079} {"level":"info","ts":"2026-08-11T11:30:52.423488Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6079,"took":"90.317912ms","hash":3969899872,"current-db-size-bytes":58245120,"current-db-size":"58 MB","current-db-size-in-use-bytes":23908352,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-08-11T11:30:52.423567Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3969899872,"revision":6079,"compact-revision":2971} {"level":"info","ts":"2026-08-11T11:31:01.394654Z","caller":"traceutil/trace.go:171","msg":"trace[1467642299] linearizableReadLoop","detail":"{readStateIndex:8612; appliedIndex:8611; }","duration":"174.142842ms","start":"2026-08-11T11:31:01.220475Z","end":"2026-08-11T11:31:01.394618Z","steps":["trace[1467642299] 'read index received' (duration: 174.018019ms)","trace[1467642299] 'applied index is now lower than readState.Index' (duration: 123.943µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:31:01.394754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.274696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:31:01.394781Z","caller":"traceutil/trace.go:171","msg":"trace[1234474668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8318; }","duration":"174.351518ms","start":"2026-08-11T11:31:01.220419Z","end":"2026-08-11T11:31:01.39477Z","steps":["trace[1234474668] 'agreement among raft nodes before linearized reading' (duration: 174.294667ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:31:01.394787Z","caller":"traceutil/trace.go:171","msg":"trace[825044270] transaction","detail":"{read_only:false; response_revision:8318; number_of_response:1; }","duration":"279.482134ms","start":"2026-08-11T11:31:01.115234Z","end":"2026-08-11T11:31:01.394716Z","steps":["trace[825044270] 'process raft request' (duration: 279.244578ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:31:01.55652Z","caller":"traceutil/trace.go:171","msg":"trace[939447069] transaction","detail":"{read_only:false; response_revision:8319; number_of_response:1; }","duration":"304.235733ms","start":"2026-08-11T11:31:01.252254Z","end":"2026-08-11T11:31:01.55649Z","steps":["trace[939447069] 'process raft request' (duration: 251.776393ms)","trace[939447069] 'compare' (duration: 52.07163ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:31:01.556537Z","caller":"traceutil/trace.go:171","msg":"trace[984434469] linearizableReadLoop","detail":"{readStateIndex:8613; appliedIndex:8612; }","duration":"160.588341ms","start":"2026-08-11T11:31:01.395928Z","end":"2026-08-11T11:31:01.556516Z","steps":["trace[984434469] 'read index received' (duration: 108.293184ms)","trace[984434469] 'applied index is now lower than readState.Index' (duration: 52.294437ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:31:01.556654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:31:01.252231Z","time spent":"304.350366ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:31:01.557208Z","caller":"traceutil/trace.go:171","msg":"trace[1800910513] transaction","detail":"{read_only:false; response_revision:8320; number_of_response:1; }","duration":"139.549813ms","start":"2026-08-11T11:31:01.417614Z","end":"2026-08-11T11:31:01.557164Z","steps":["trace[1800910513] 'process raft request' (duration: 138.831805ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:31:01.557292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.363039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:31:01.557333Z","caller":"traceutil/trace.go:171","msg":"trace[1785317538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8320; }","duration":"161.434021ms","start":"2026-08-11T11:31:01.395889Z","end":"2026-08-11T11:31:01.557323Z","steps":["trace[1785317538] 'agreement among raft nodes before linearized reading' (duration: 160.737144ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:31:01.557592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.271642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:31:01.557646Z","caller":"traceutil/trace.go:171","msg":"trace[1550262802] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:8321; }","duration":"112.385214ms","start":"2026-08-11T11:31:01.445241Z","end":"2026-08-11T11:31:01.557626Z","steps":["trace[1550262802] 'agreement among raft nodes before linearized reading' (duration: 112.23712ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:31:01.557629Z","caller":"traceutil/trace.go:171","msg":"trace[1732242184] transaction","detail":"{read_only:false; response_revision:8321; number_of_response:1; }","duration":"138.102806ms","start":"2026-08-11T11:31:01.419496Z","end":"2026-08-11T11:31:01.557599Z","steps":["trace[1732242184] 'process raft request' (duration: 137.552513ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:31:02.386907Z","caller":"traceutil/trace.go:171","msg":"trace[375461293] linearizableReadLoop","detail":"{readStateIndex:8617; appliedIndex:8616; }","duration":"164.580048ms","start":"2026-08-11T11:31:02.222301Z","end":"2026-08-11T11:31:02.386881Z","steps":["trace[375461293] 'read index received' (duration: 126.209904ms)","trace[375461293] 'applied index is now lower than readState.Index' (duration: 38.369224ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:31:02.387058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.750472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:31:02.387093Z","caller":"traceutil/trace.go:171","msg":"trace[10407504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8323; }","duration":"164.831955ms","start":"2026-08-11T11:31:02.222248Z","end":"2026-08-11T11:31:02.38708Z","steps":["trace[10407504] 'agreement among raft nodes before linearized reading' (duration: 164.764293ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:31:02.387856Z","caller":"traceutil/trace.go:171","msg":"trace[771605587] transaction","detail":"{read_only:false; response_revision:8323; number_of_response:1; }","duration":"273.474195ms","start":"2026-08-11T11:31:02.11433Z","end":"2026-08-11T11:31:02.387804Z","steps":["trace[771605587] 'process raft request' (duration: 234.198769ms)","trace[771605587] 'compare' (duration: 38.20657ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:31:03.127722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.971665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-11T11:31:03.127794Z","caller":"traceutil/trace.go:171","msg":"trace[382940564] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8326; }","duration":"146.107058ms","start":"2026-08-11T11:31:02.981673Z","end":"2026-08-11T11:31:03.12778Z","steps":["trace[382940564] 'range keys from in-memory index tree' (duration: 145.798631ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:31:26.05638Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002254.wal"} {"level":"info","ts":"2026-08-11T11:31:29.910912Z","caller":"traceutil/trace.go:171","msg":"trace[1064300871] transaction","detail":"{read_only:false; response_revision:8523; number_of_response:1; }","duration":"344.065496ms","start":"2026-08-11T11:31:29.566824Z","end":"2026-08-11T11:31:29.91089Z","steps":["trace[1064300871] 'process raft request' (duration: 343.926282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:31:29.911072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:31:29.566776Z","time spent":"344.232519ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:31:29.911485Z","caller":"traceutil/trace.go:171","msg":"trace[804001253] linearizableReadLoop","detail":"{readStateIndex:8824; appliedIndex:8824; }","duration":"316.303195ms","start":"2026-08-11T11:31:29.595154Z","end":"2026-08-11T11:31:29.911457Z","steps":["trace[804001253] 'read index received' (duration: 316.293735ms)","trace[804001253] 'applied index is now lower than readState.Index' (duration: 7.99µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:31:29.911599Z","caller":"traceutil/trace.go:171","msg":"trace[480466308] transaction","detail":"{read_only:false; response_revision:8526; number_of_response:1; }","duration":"242.872056ms","start":"2026-08-11T11:31:29.668719Z","end":"2026-08-11T11:31:29.911591Z","steps":["trace[480466308] 'process raft request' (duration: 242.839646ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:31:29.911725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.556262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-08-11T11:31:29.911766Z","caller":"traceutil/trace.go:171","msg":"trace[33112275] transaction","detail":"{read_only:false; response_revision:8524; number_of_response:1; }","duration":"243.361848ms","start":"2026-08-11T11:31:29.668396Z","end":"2026-08-11T11:31:29.911758Z","steps":["trace[33112275] 'process raft request' (duration: 243.097412ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:31:29.911771Z","caller":"traceutil/trace.go:171","msg":"trace[1094389971] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:8526; }","duration":"316.653765ms","start":"2026-08-11T11:31:29.595106Z","end":"2026-08-11T11:31:29.911759Z","steps":["trace[1094389971] 'agreement among raft nodes before linearized reading' (duration: 316.495941ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:31:29.911811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:31:29.595088Z","time spent":"316.715256ms","remote":"127.0.0.1:46010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1655,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"info","ts":"2026-08-11T11:31:29.911881Z","caller":"traceutil/trace.go:171","msg":"trace[225524039] transaction","detail":"{read_only:false; response_revision:8525; number_of_response:1; }","duration":"243.400389ms","start":"2026-08-11T11:31:29.668473Z","end":"2026-08-11T11:31:29.911873Z","steps":["trace[225524039] 'process raft request' (duration: 243.061181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:31:29.912126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.177196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:31:29.912168Z","caller":"traceutil/trace.go:171","msg":"trace[1463950759] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:8526; }","duration":"310.239577ms","start":"2026-08-11T11:31:29.601917Z","end":"2026-08-11T11:31:29.912156Z","steps":["trace[1463950759] 'agreement among raft nodes before linearized reading' (duration: 310.176096ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:31:29.912198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:31:29.601907Z","time spent":"310.283118ms","remote":"127.0.0.1:46294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-08-11T11:31:34.065299Z","caller":"traceutil/trace.go:171","msg":"trace[1701667751] transaction","detail":"{read_only:false; response_revision:8545; number_of_response:1; }","duration":"140.005338ms","start":"2026-08-11T11:31:33.925274Z","end":"2026-08-11T11:31:34.06528Z","steps":["trace[1701667751] 'process raft request' (duration: 139.827644ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:31:34.065473Z","caller":"traceutil/trace.go:171","msg":"trace[588430572] transaction","detail":"{read_only:false; response_revision:8546; number_of_response:1; }","duration":"138.698685ms","start":"2026-08-11T11:31:33.926742Z","end":"2026-08-11T11:31:34.06544Z","steps":["trace[588430572] 'process raft request' (duration: 138.509061ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:31:43.696913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.188876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:31:43.697037Z","caller":"traceutil/trace.go:171","msg":"trace[2132308114] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:8638; }","duration":"134.36143ms","start":"2026-08-11T11:31:43.56265Z","end":"2026-08-11T11:31:43.697012Z","steps":["trace[2132308114] 'count revisions from in-memory index tree' (duration: 134.088783ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:33:01.502981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.165797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9dx7t\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-08-11T11:33:01.503068Z","caller":"traceutil/trace.go:171","msg":"trace[539490338] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9dx7t; range_end:; response_count:1; response_revision:9399; }","duration":"226.300361ms","start":"2026-08-11T11:33:01.276749Z","end":"2026-08-11T11:33:01.503049Z","steps":["trace[539490338] 'range keys from in-memory index tree' (duration: 225.873281ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:33:37.516741Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-11T11:33:37.52289Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-11T11:33:37.523005Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-11T11:35:52.337837Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8270} {"level":"info","ts":"2026-08-11T11:35:52.410916Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8270,"took":"71.452369ms","hash":2824818792,"current-db-size-bytes":58245120,"current-db-size":"58 MB","current-db-size-in-use-bytes":25071616,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-08-11T11:35:52.410979Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2824818792,"revision":8270,"compact-revision":6079} {"level":"info","ts":"2026-08-11T11:38:19.324235Z","caller":"traceutil/trace.go:171","msg":"trace[470427800] linearizableReadLoop","detail":"{readStateIndex:12119; appliedIndex:12118; }","duration":"104.889992ms","start":"2026-08-11T11:38:19.219321Z","end":"2026-08-11T11:38:19.324211Z","steps":["trace[470427800] 'read index received' (duration: 91.910316ms)","trace[470427800] 'applied index is now lower than readState.Index' (duration: 12.978606ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:38:19.324302Z","caller":"traceutil/trace.go:171","msg":"trace[1889078767] transaction","detail":"{read_only:false; response_revision:11717; number_of_response:1; }","duration":"195.26977ms","start":"2026-08-11T11:38:19.128981Z","end":"2026-08-11T11:38:19.32425Z","steps":["trace[1889078767] 'process raft request' (duration: 182.12547ms)","trace[1889078767] 'compare' (duration: 12.997467ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:38:19.324349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.014015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:38:19.324382Z","caller":"traceutil/trace.go:171","msg":"trace[767351537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11717; }","duration":"105.091867ms","start":"2026-08-11T11:38:19.219281Z","end":"2026-08-11T11:38:19.324373Z","steps":["trace[767351537] 'agreement among raft nodes before linearized reading' (duration: 105.019115ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:38:41.532079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.971741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:38:41.532185Z","caller":"traceutil/trace.go:171","msg":"trace[2047532164] linearizableReadLoop","detail":"{readStateIndex:12246; appliedIndex:12245; }","duration":"105.946728ms","start":"2026-08-11T11:38:41.426219Z","end":"2026-08-11T11:38:41.532166Z","steps":["trace[2047532164] 'read index received' (duration: 36.081µs)","trace[2047532164] 'applied index is now lower than readState.Index' (duration: 105.909437ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:38:41.53232Z","caller":"traceutil/trace.go:171","msg":"trace[797500709] transaction","detail":"{read_only:false; response_revision:11839; number_of_response:1; }","duration":"114.137829ms","start":"2026-08-11T11:38:41.418171Z","end":"2026-08-11T11:38:41.532309Z","steps":["trace[797500709] 'compare' (duration: 110.858668ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:38:41.532573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.337697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-11T11:38:41.53266Z","caller":"traceutil/trace.go:171","msg":"trace[69377830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11839; }","duration":"106.467251ms","start":"2026-08-11T11:38:41.426178Z","end":"2026-08-11T11:38:41.532645Z","steps":["trace[69377830] 'agreement among raft nodes before linearized reading' (duration: 106.228555ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:38:41.880111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.363648ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:38:41.880413Z","caller":"traceutil/trace.go:171","msg":"trace[202210576] transaction","detail":"{read_only:false; response_revision:11843; number_of_response:1; }","duration":"210.00037ms","start":"2026-08-11T11:38:41.670394Z","end":"2026-08-11T11:38:41.880394Z","steps":["trace[202210576] 'process raft request' (duration: 209.964519ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:38:41.880636Z","caller":"traceutil/trace.go:171","msg":"trace[1240205399] transaction","detail":"{read_only:false; response_revision:11842; number_of_response:1; }","duration":"324.288002ms","start":"2026-08-11T11:38:41.556312Z","end":"2026-08-11T11:38:41.8806Z","steps":["trace[1240205399] 'process raft request' (duration: 323.978345ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:38:41.880522Z","caller":"traceutil/trace.go:171","msg":"trace[1491267806] transaction","detail":"{read_only:false; response_revision:11841; number_of_response:1; }","duration":"342.834995ms","start":"2026-08-11T11:38:41.537664Z","end":"2026-08-11T11:38:41.880499Z","steps":["trace[1491267806] 'process raft request' (duration: 193.009495ms)","trace[1491267806] 'compare' (duration: 149.192434ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:38:41.880915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:38:41.556295Z","time spent":"324.431076ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:38:41.881074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:38:41.537655Z","time spent":"343.306407ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:38:41.880592Z","caller":"traceutil/trace.go:171","msg":"trace[710673509] linearizableReadLoop","detail":"{readStateIndex:12248; appliedIndex:12246; }","duration":"337.916065ms","start":"2026-08-11T11:38:41.542667Z","end":"2026-08-11T11:38:41.880583Z","steps":["trace[710673509] 'read index received' (duration: 39.492505ms)","trace[710673509] 'applied index is now lower than readState.Index' (duration: 298.42254ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:38:41.88065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.979486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:38:41.881425Z","caller":"traceutil/trace.go:171","msg":"trace[1696873540] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:11843; }","duration":"338.778746ms","start":"2026-08-11T11:38:41.542631Z","end":"2026-08-11T11:38:41.88141Z","steps":["trace[1696873540] 'agreement among raft nodes before linearized reading' (duration: 337.979357ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:38:41.881517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:38:41.542611Z","time spent":"338.890479ms","remote":"127.0.0.1:46264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-08-11T11:38:41.885054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.683788ms","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-08-11T11:38:41.88626Z","caller":"traceutil/trace.go:171","msg":"trace[1509157128] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:11844; }","duration":"279.95058ms","start":"2026-08-11T11:38:41.60628Z","end":"2026-08-11T11:38:41.886231Z","steps":["trace[1509157128] 'agreement among raft nodes before linearized reading' (duration: 278.623218ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:40:52.343332Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10497} {"level":"info","ts":"2026-08-11T11:40:52.420285Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10497,"took":"74.572172ms","hash":2481721740,"current-db-size-bytes":58245120,"current-db-size":"58 MB","current-db-size-in-use-bytes":26017792,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-11T11:40:52.420358Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2481721740,"revision":10497,"compact-revision":8270} {"level":"info","ts":"2026-08-11T11:44:32.666809Z","caller":"traceutil/trace.go:171","msg":"trace[41359548] transaction","detail":"{read_only:false; response_revision:14237; number_of_response:1; }","duration":"110.941459ms","start":"2026-08-11T11:44:32.555841Z","end":"2026-08-11T11:44:32.666783Z","steps":["trace[41359548] 'process raft request' (duration: 110.744194ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:44:54.648071Z","caller":"traceutil/trace.go:171","msg":"trace[408445546] transaction","detail":"{read_only:false; response_revision:14354; number_of_response:1; }","duration":"122.406045ms","start":"2026-08-11T11:44:54.525644Z","end":"2026-08-11T11:44:54.64805Z","steps":["trace[408445546] 'process raft request' (duration: 122.272712ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:45:11.188748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.498594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-11T11:45:11.188931Z","caller":"traceutil/trace.go:171","msg":"trace[1559134205] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14453; }","duration":"335.75088ms","start":"2026-08-11T11:45:10.853147Z","end":"2026-08-11T11:45:11.188898Z","steps":["trace[1559134205] 'range keys from in-memory index tree' (duration: 335.259578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:45:11.18899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:45:10.853124Z","time spent":"335.852263ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-11T11:45:11.191885Z","caller":"traceutil/trace.go:171","msg":"trace[987409534] transaction","detail":"{read_only:false; response_revision:14454; number_of_response:1; }","duration":"193.504012ms","start":"2026-08-11T11:45:10.998361Z","end":"2026-08-11T11:45:11.191865Z","steps":["trace[987409534] 'process raft request' (duration: 193.382729ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:45:11.191918Z","caller":"traceutil/trace.go:171","msg":"trace[1131096918] linearizableReadLoop","detail":"{readStateIndex:14953; appliedIndex:14952; }","duration":"314.278007ms","start":"2026-08-11T11:45:10.877593Z","end":"2026-08-11T11:45:11.191871Z","steps":["trace[1131096918] 'read index received' (duration: 302.046623ms)","trace[1131096918] 'applied index is now lower than readState.Index' (duration: 12.225463ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:45:11.192109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.472292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:45:11.192365Z","caller":"traceutil/trace.go:171","msg":"trace[1461293232] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:14454; }","duration":"314.78231ms","start":"2026-08-11T11:45:10.877554Z","end":"2026-08-11T11:45:11.192336Z","steps":["trace[1461293232] 'agreement among raft nodes before linearized reading' (duration: 314.466492ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:45:11.192433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:45:10.877537Z","time spent":"314.878572ms","remote":"127.0.0.1:46192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-08-11T11:45:52.354005Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12727} {"level":"info","ts":"2026-08-11T11:45:52.428742Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12727,"took":"72.961804ms","hash":1073304856,"current-db-size-bytes":58245120,"current-db-size":"58 MB","current-db-size-in-use-bytes":28844032,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-11T11:45:52.428802Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1073304856,"revision":12727,"compact-revision":10497} {"level":"info","ts":"2026-08-11T11:47:00.264482Z","caller":"traceutil/trace.go:171","msg":"trace[35532978] transaction","detail":"{read_only:false; response_revision:15708; number_of_response:1; }","duration":"242.900244ms","start":"2026-08-11T11:47:00.021552Z","end":"2026-08-11T11:47:00.264452Z","steps":["trace[35532978] 'process raft request' (duration: 242.760191ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:47:00.264528Z","caller":"traceutil/trace.go:171","msg":"trace[655366164] linearizableReadLoop","detail":"{readStateIndex:16245; appliedIndex:16245; }","duration":"101.368774ms","start":"2026-08-11T11:47:00.163129Z","end":"2026-08-11T11:47:00.264497Z","steps":["trace[655366164] 'read index received' (duration: 101.357764ms)","trace[655366164] 'applied index is now lower than readState.Index' (duration: 9.01µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:47:00.264989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.839875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-w5crc\" ","response":"range_response_count:1 size:22010"} {"level":"info","ts":"2026-08-11T11:47:00.265075Z","caller":"traceutil/trace.go:171","msg":"trace[173273843] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-w5crc; range_end:; response_count:1; response_revision:15708; }","duration":"101.963398ms","start":"2026-08-11T11:47:00.163086Z","end":"2026-08-11T11:47:00.265049Z","steps":["trace[173273843] 'agreement among raft nodes before linearized reading' (duration: 101.584099ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:50:52.359652Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15024} {"level":"info","ts":"2026-08-11T11:50:52.469929Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15024,"took":"107.946267ms","hash":1817961422,"current-db-size-bytes":58245120,"current-db-size":"58 MB","current-db-size-in-use-bytes":31121408,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-11T11:50:52.469982Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1817961422,"revision":15024,"compact-revision":12727} {"level":"info","ts":"2026-08-11T11:52:43.822732Z","caller":"traceutil/trace.go:171","msg":"trace[737584190] linearizableReadLoop","detail":"{readStateIndex:18965; appliedIndex:18964; }","duration":"142.063899ms","start":"2026-08-11T11:52:43.680596Z","end":"2026-08-11T11:52:43.82266Z","steps":["trace[737584190] 'read index received' (duration: 141.7175ms)","trace[737584190] 'applied index is now lower than readState.Index' (duration: 345.319µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:52:43.82278Z","caller":"traceutil/trace.go:171","msg":"trace[1380691136] transaction","detail":"{read_only:false; response_revision:18342; number_of_response:1; }","duration":"168.208879ms","start":"2026-08-11T11:52:43.654539Z","end":"2026-08-11T11:52:43.822748Z","steps":["trace[1380691136] 'process raft request' (duration: 167.800959ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:52:43.82298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.351666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-11T11:52:43.823039Z","caller":"traceutil/trace.go:171","msg":"trace[1302468407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18342; }","duration":"142.444538ms","start":"2026-08-11T11:52:43.680579Z","end":"2026-08-11T11:52:43.823024Z","steps":["trace[1302468407] 'agreement among raft nodes before linearized reading' (duration: 142.259503ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:52:44.034218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.530655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-11T11:52:44.034617Z","caller":"traceutil/trace.go:171","msg":"trace[1091375705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18343; }","duration":"121.917884ms","start":"2026-08-11T11:52:43.912638Z","end":"2026-08-11T11:52:44.034556Z","steps":["trace[1091375705] 'range keys from in-memory index tree' (duration: 121.414692ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:52:44.641712Z","caller":"traceutil/trace.go:171","msg":"trace[313516522] linearizableReadLoop","detail":"{readStateIndex:18969; appliedIndex:18968; }","duration":"230.446572ms","start":"2026-08-11T11:52:44.411243Z","end":"2026-08-11T11:52:44.64169Z","steps":["trace[313516522] 'read index received' (duration: 230.162535ms)","trace[313516522] 'applied index is now lower than readState.Index' (duration: 283.167µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:52:44.641924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.668987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-w5crc\" ","response":"range_response_count:1 size:22011"} {"level":"info","ts":"2026-08-11T11:52:44.641964Z","caller":"traceutil/trace.go:171","msg":"trace[825453561] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-w5crc; range_end:; response_count:1; response_revision:18346; }","duration":"230.765609ms","start":"2026-08-11T11:52:44.411187Z","end":"2026-08-11T11:52:44.641952Z","steps":["trace[825453561] 'agreement among raft nodes before linearized reading' (duration: 230.590965ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:52:44.64239Z","caller":"traceutil/trace.go:171","msg":"trace[1315659765] transaction","detail":"{read_only:false; response_revision:18346; number_of_response:1; }","duration":"285.099709ms","start":"2026-08-11T11:52:44.357275Z","end":"2026-08-11T11:52:44.642375Z","steps":["trace[1315659765] 'process raft request' (duration: 284.213568ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:54:32.649493Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-11T11:54:32.654262Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-11T11:54:32.654333Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-11T11:55:52.367363Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17491} {"level":"info","ts":"2026-08-11T11:55:52.456877Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17491,"took":"86.251142ms","hash":1318044035,"current-db-size-bytes":58245120,"current-db-size":"58 MB","current-db-size-in-use-bytes":34885632,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-11T11:55:52.456938Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1318044035,"revision":17491,"compact-revision":15024} {"level":"info","ts":"2026-08-11T11:57:56.669558Z","caller":"traceutil/trace.go:171","msg":"trace[1083460939] transaction","detail":"{read_only:false; response_revision:20763; number_of_response:1; }","duration":"113.461059ms","start":"2026-08-11T11:57:56.55607Z","end":"2026-08-11T11:57:56.669531Z","steps":["trace[1083460939] 'process raft request' (duration: 113.11403ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:13.076835Z","caller":"traceutil/trace.go:171","msg":"trace[1729431544] linearizableReadLoop","detail":"{readStateIndex:21549; appliedIndex:21548; }","duration":"245.80403ms","start":"2026-08-11T11:58:12.831009Z","end":"2026-08-11T11:58:13.076813Z","steps":["trace[1729431544] 'read index received' (duration: 245.679316ms)","trace[1729431544] 'applied index is now lower than readState.Index' (duration: 123.853µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:58:13.077125Z","caller":"traceutil/trace.go:171","msg":"trace[1577934497] transaction","detail":"{read_only:false; response_revision:20843; number_of_response:1; }","duration":"330.284778ms","start":"2026-08-11T11:58:12.746831Z","end":"2026-08-11T11:58:13.077116Z","steps":["trace[1577934497] 'process raft request' (duration: 329.839387ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:13.077212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:58:12.746819Z","time spent":"330.33387ms","remote":"127.0.0.1:46106","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-08-11T11:58:13.077352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.341472ms","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-08-11T11:58:13.077384Z","caller":"traceutil/trace.go:171","msg":"trace[565062077] 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:20843; }","duration":"246.405704ms","start":"2026-08-11T11:58:12.830969Z","end":"2026-08-11T11:58:13.077374Z","steps":["trace[565062077] 'agreement among raft nodes before linearized reading' (duration: 246.359803ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:13.077561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.836454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-11T11:58:13.077586Z","caller":"traceutil/trace.go:171","msg":"trace[891877728] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20843; }","duration":"208.901606ms","start":"2026-08-11T11:58:12.868679Z","end":"2026-08-11T11:58:13.077581Z","steps":["trace[891877728] 'agreement among raft nodes before linearized reading' (duration: 208.823014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:58:13.38095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.297035ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-08-11T11:58:13.381232Z","caller":"traceutil/trace.go:171","msg":"trace[1681977259] linearizableReadLoop","detail":"{readStateIndex:21551; appliedIndex:21550; }","duration":"223.950914ms","start":"2026-08-11T11:58:13.157078Z","end":"2026-08-11T11:58:13.381028Z","steps":["trace[1681977259] 'read index received' (duration: 121.518446ms)","trace[1681977259] 'applied index is now lower than readState.Index' (duration: 102.430668ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:58:13.381578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.497769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-08-11T11:58:13.381622Z","caller":"traceutil/trace.go:171","msg":"trace[955297372] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:20844; }","duration":"224.55059ms","start":"2026-08-11T11:58:13.157061Z","end":"2026-08-11T11:58:13.381612Z","steps":["trace[955297372] 'agreement among raft nodes before linearized reading' (duration: 224.213921ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:13.593849Z","caller":"traceutil/trace.go:171","msg":"trace[2053374254] transaction","detail":"{read_only:false; response_revision:20846; number_of_response:1; }","duration":"191.097869ms","start":"2026-08-11T11:58:13.402705Z","end":"2026-08-11T11:58:13.593803Z","steps":["trace[2053374254] 'process raft request' (duration: 128.550708ms)","trace[2053374254] 'compare' (duration: 62.051969ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:58:13.594592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.195542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-11T11:58:13.59469Z","caller":"traceutil/trace.go:171","msg":"trace[912125688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20846; }","duration":"191.337016ms","start":"2026-08-11T11:58:13.40333Z","end":"2026-08-11T11:58:13.594667Z","steps":["trace[912125688] 'agreement among raft nodes before linearized reading' (duration: 191.010317ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:58:13.594926Z","caller":"traceutil/trace.go:171","msg":"trace[1319038912] linearizableReadLoop","detail":"{readStateIndex:21553; appliedIndex:21552; }","duration":"190.846083ms","start":"2026-08-11T11:58:13.403372Z","end":"2026-08-11T11:58:13.594218Z","steps":["trace[1319038912] 'read index received' (duration: 127.828161ms)","trace[1319038912] 'applied index is now lower than readState.Index' (duration: 63.014302ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:00:52.373996Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19869} {"level":"info","ts":"2026-08-11T12:00:52.484518Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19869,"took":"108.144019ms","hash":468811649,"current-db-size-bytes":58245120,"current-db-size":"58 MB","current-db-size-in-use-bytes":30781440,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-11T12:00:52.484591Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":468811649,"revision":19869,"compact-revision":17491} {"level":"info","ts":"2026-08-11T12:05:31.693258Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000066b8.wal"} {"level":"info","ts":"2026-08-11T12:05:38.086808Z","caller":"traceutil/trace.go:171","msg":"trace[915166056] linearizableReadLoop","detail":"{readStateIndex:26407; appliedIndex:26406; }","duration":"120.726187ms","start":"2026-08-11T12:05:37.966064Z","end":"2026-08-11T12:05:38.08679Z","steps":["trace[915166056] 'read index received' (duration: 120.593583ms)","trace[915166056] 'applied index is now lower than readState.Index' (duration: 131.544µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:05:38.087024Z","caller":"traceutil/trace.go:171","msg":"trace[1566459349] transaction","detail":"{read_only:false; response_revision:25569; number_of_response:1; }","duration":"125.909733ms","start":"2026-08-11T12:05:37.961106Z","end":"2026-08-11T12:05:38.087016Z","steps":["trace[1566459349] 'process raft request' (duration: 125.563235ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:05:38.087233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.152767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-11T12:05:38.087259Z","caller":"traceutil/trace.go:171","msg":"trace[920970098] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25569; }","duration":"121.221548ms","start":"2026-08-11T12:05:37.966031Z","end":"2026-08-11T12:05:38.087253Z","steps":["trace[920970098] 'agreement among raft nodes before linearized reading' (duration: 121.118315ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:05:38.087386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.657349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-11T12:05:38.087405Z","caller":"traceutil/trace.go:171","msg":"trace[1400672922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25569; }","duration":"101.71216ms","start":"2026-08-11T12:05:37.985688Z","end":"2026-08-11T12:05:38.0874Z","steps":["trace[1400672922] 'agreement among raft nodes before linearized reading' (duration: 101.656849ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:43.66795Z","caller":"traceutil/trace.go:171","msg":"trace[2132978197] transaction","detail":"{read_only:false; response_revision:25604; number_of_response:1; }","duration":"100.082632ms","start":"2026-08-11T12:05:43.567845Z","end":"2026-08-11T12:05:43.667928Z","steps":["trace[2132978197] 'process raft request' (duration: 88.229601ms)","trace[2132978197] 'compare' (duration: 11.753268ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:05:43.897017Z","caller":"traceutil/trace.go:171","msg":"trace[936375929] transaction","detail":"{read_only:false; response_revision:25605; number_of_response:1; }","duration":"119.350183ms","start":"2026-08-11T12:05:43.777636Z","end":"2026-08-11T12:05:43.896986Z","steps":["trace[936375929] 'process raft request' (duration: 99.765404ms)","trace[936375929] 'compare' (duration: 19.1799ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:05:44.172534Z","caller":"traceutil/trace.go:171","msg":"trace[1519234278] transaction","detail":"{read_only:false; response_revision:25606; number_of_response:1; }","duration":"391.853147ms","start":"2026-08-11T12:05:43.780648Z","end":"2026-08-11T12:05:44.172501Z","steps":["trace[1519234278] 'process raft request' (duration: 381.38499ms)","trace[1519234278] 'compare' (duration: 10.296463ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:05:44.172699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:05:43.780637Z","time spent":"391.977899ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T12:05:44.172694Z","caller":"traceutil/trace.go:171","msg":"trace[482898051] transaction","detail":"{read_only:false; response_revision:25607; number_of_response:1; }","duration":"391.933478ms","start":"2026-08-11T12:05:43.780724Z","end":"2026-08-11T12:05:44.172658Z","steps":["trace[482898051] 'process raft request' (duration: 391.739894ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:44.172838Z","caller":"traceutil/trace.go:171","msg":"trace[360891090] transaction","detail":"{read_only:false; response_revision:25608; number_of_response:1; }","duration":"378.645914ms","start":"2026-08-11T12:05:43.79418Z","end":"2026-08-11T12:05:44.172826Z","steps":["trace[360891090] 'process raft request' (duration: 378.445659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:05:44.172853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:05:43.780683Z","time spent":"392.084102ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T12:05:44.172902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:05:43.79416Z","time spent":"378.714835ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T12:05:44.172814Z","caller":"traceutil/trace.go:171","msg":"trace[1764657191] linearizableReadLoop","detail":"{readStateIndex:26447; appliedIndex:26444; }","duration":"210.29167ms","start":"2026-08-11T12:05:43.962483Z","end":"2026-08-11T12:05:44.172775Z","steps":["trace[1764657191] 'read index received' (duration: 199.622369ms)","trace[1764657191] 'applied index is now lower than readState.Index' (duration: 10.668161ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:05:44.173038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.549557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-08-11T12:05:44.173106Z","caller":"traceutil/trace.go:171","msg":"trace[1307146509] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25609; }","duration":"210.68001ms","start":"2026-08-11T12:05:43.96241Z","end":"2026-08-11T12:05:44.17309Z","steps":["trace[1307146509] 'agreement among raft nodes before linearized reading' (duration: 210.451704ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:48.460564Z","caller":"traceutil/trace.go:171","msg":"trace[454561709] linearizableReadLoop","detail":"{readStateIndex:26488; appliedIndex:26484; }","duration":"262.760375ms","start":"2026-08-11T12:05:48.197778Z","end":"2026-08-11T12:05:48.460539Z","steps":["trace[454561709] 'read index received' (duration: 25.912134ms)","trace[454561709] 'applied index is now lower than readState.Index' (duration: 236.847151ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:05:48.46076Z","caller":"traceutil/trace.go:171","msg":"trace[471417790] transaction","detail":"{read_only:false; response_revision:25648; number_of_response:1; }","duration":"271.792187ms","start":"2026-08-11T12:05:48.188945Z","end":"2026-08-11T12:05:48.460737Z","steps":["trace[471417790] 'process raft request' (duration: 271.554191ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:48.460848Z","caller":"traceutil/trace.go:171","msg":"trace[1889878150] transaction","detail":"{read_only:false; response_revision:25646; number_of_response:1; }","duration":"272.000712ms","start":"2026-08-11T12:05:48.188811Z","end":"2026-08-11T12:05:48.460812Z","steps":["trace[1889878150] 'process raft request' (duration: 271.482719ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:05:48.461023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.219926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-11T12:05:48.461097Z","caller":"traceutil/trace.go:171","msg":"trace[544557395] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25648; }","duration":"263.324329ms","start":"2026-08-11T12:05:48.197756Z","end":"2026-08-11T12:05:48.46108Z","steps":["trace[544557395] 'agreement among raft nodes before linearized reading' (duration: 263.124344ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:48.461031Z","caller":"traceutil/trace.go:171","msg":"trace[2124749372] transaction","detail":"{read_only:false; response_revision:25647; number_of_response:1; }","duration":"272.127345ms","start":"2026-08-11T12:05:48.188888Z","end":"2026-08-11T12:05:48.461016Z","steps":["trace[2124749372] 'process raft request' (duration: 271.566511ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:05:48.461268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.439558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-11T12:05:48.461325Z","caller":"traceutil/trace.go:171","msg":"trace[60176711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25648; }","duration":"262.52679ms","start":"2026-08-11T12:05:48.198785Z","end":"2026-08-11T12:05:48.461311Z","steps":["trace[60176711] 'agreement among raft nodes before linearized reading' (duration: 262.337315ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:05:48.461334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.610253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T12:05:48.461405Z","caller":"traceutil/trace.go:171","msg":"trace[1231092492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25648; }","duration":"240.708266ms","start":"2026-08-11T12:05:48.220682Z","end":"2026-08-11T12:05:48.46139Z","steps":["trace[1231092492] 'agreement among raft nodes before linearized reading' (duration: 240.612563ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:05:48.461449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.53108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-11T12:05:48.461492Z","caller":"traceutil/trace.go:171","msg":"trace[907023639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25648; }","duration":"262.617692ms","start":"2026-08-11T12:05:48.198864Z","end":"2026-08-11T12:05:48.461482Z","steps":["trace[907023639] 'agreement among raft nodes before linearized reading' (duration: 262.502889ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:05:48.624462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.83521ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T12:05:48.624858Z","caller":"traceutil/trace.go:171","msg":"trace[710772563] transaction","detail":"{read_only:false; response_revision:25649; number_of_response:1; }","duration":"159.703031ms","start":"2026-08-11T12:05:48.465132Z","end":"2026-08-11T12:05:48.624835Z","steps":["trace[710772563] 'process raft request' (duration: 49.40482ms)","trace[710772563] 'compare' (duration: 109.41699ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:05:48.625156Z","caller":"traceutil/trace.go:171","msg":"trace[1630640294] transaction","detail":"{read_only:false; response_revision:25650; number_of_response:1; }","duration":"159.861017ms","start":"2026-08-11T12:05:48.465285Z","end":"2026-08-11T12:05:48.625146Z","steps":["trace[1630640294] 'process raft request' (duration: 159.448636ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:48.625214Z","caller":"traceutil/trace.go:171","msg":"trace[1254706669] transaction","detail":"{read_only:false; response_revision:25651; number_of_response:1; }","duration":"159.63461ms","start":"2026-08-11T12:05:48.465573Z","end":"2026-08-11T12:05:48.625208Z","steps":["trace[1254706669] 'process raft request' (duration: 159.226859ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:49.022498Z","caller":"traceutil/trace.go:171","msg":"trace[40839052] linearizableReadLoop","detail":"{readStateIndex:26494; appliedIndex:26492; }","duration":"253.384005ms","start":"2026-08-11T12:05:48.76909Z","end":"2026-08-11T12:05:49.022474Z","steps":["trace[40839052] 'read index received' (duration: 183.68886ms)","trace[40839052] 'applied index is now lower than readState.Index' (duration: 69.693995ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:05:49.022539Z","caller":"traceutil/trace.go:171","msg":"trace[314170946] transaction","detail":"{read_only:false; response_revision:25654; number_of_response:1; }","duration":"283.050042ms","start":"2026-08-11T12:05:48.739455Z","end":"2026-08-11T12:05:49.022505Z","steps":["trace[314170946] 'process raft request' (duration: 282.884958ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:49.022495Z","caller":"traceutil/trace.go:171","msg":"trace[1503784247] transaction","detail":"{read_only:false; response_revision:25653; number_of_response:1; }","duration":"328.488445ms","start":"2026-08-11T12:05:48.693915Z","end":"2026-08-11T12:05:49.022403Z","steps":["trace[1503784247] 'process raft request' (duration: 258.943443ms)","trace[1503784247] 'compare' (duration: 69.115761ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:05:49.022748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:05:48.693902Z","time spent":"328.750831ms","remote":"127.0.0.1:46136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T12:05:49.022935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.792606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra.18cabebf3e10fdd9\" ","response":"range_response_count:1 size:695"} {"level":"info","ts":"2026-08-11T12:05:49.023017Z","caller":"traceutil/trace.go:171","msg":"trace[1583655078] range","detail":"{range_begin:/registry/events/openstack/container-infra.18cabebf3e10fdd9; range_end:; response_count:1; response_revision:25654; }","duration":"253.95512ms","start":"2026-08-11T12:05:48.769036Z","end":"2026-08-11T12:05:49.022991Z","steps":["trace[1583655078] 'agreement among raft nodes before linearized reading' (duration: 253.547699ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:05:49.465739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.060507ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T12:05:49.466043Z","caller":"traceutil/trace.go:171","msg":"trace[292258760] linearizableReadLoop","detail":"{readStateIndex:26497; appliedIndex:26495; }","duration":"190.471865ms","start":"2026-08-11T12:05:49.275549Z","end":"2026-08-11T12:05:49.46602Z","steps":["trace[292258760] 'read index received' (duration: 36.7413ms)","trace[292258760] 'applied index is now lower than readState.Index' (duration: 153.729345ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:05:49.466109Z","caller":"traceutil/trace.go:171","msg":"trace[373920005] transaction","detail":"{read_only:false; response_revision:25656; number_of_response:1; }","duration":"256.696647ms","start":"2026-08-11T12:05:49.209403Z","end":"2026-08-11T12:05:49.466099Z","steps":["trace[373920005] 'process raft request' (duration: 256.461341ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:49.466031Z","caller":"traceutil/trace.go:171","msg":"trace[237868114] transaction","detail":"{read_only:false; response_revision:25655; number_of_response:1; }","duration":"282.902758ms","start":"2026-08-11T12:05:49.183088Z","end":"2026-08-11T12:05:49.46599Z","steps":["trace[237868114] 'process raft request' (duration: 129.188424ms)","trace[237868114] 'compare' (duration: 152.531135ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:05:49.466395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.845114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-08-11T12:05:49.466485Z","caller":"traceutil/trace.go:171","msg":"trace[645030502] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25656; }","duration":"190.946916ms","start":"2026-08-11T12:05:49.275495Z","end":"2026-08-11T12:05:49.466442Z","steps":["trace[645030502] 'agreement among raft nodes before linearized reading' (duration: 190.658909ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:05:49.466396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.763138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-11T12:05:49.466595Z","caller":"traceutil/trace.go:171","msg":"trace[1606069260] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25656; }","duration":"181.003884ms","start":"2026-08-11T12:05:49.285574Z","end":"2026-08-11T12:05:49.466578Z","steps":["trace[1606069260] 'agreement among raft nodes before linearized reading' (duration: 180.692436ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:49.63596Z","caller":"traceutil/trace.go:171","msg":"trace[879484134] transaction","detail":"{read_only:false; response_revision:25657; number_of_response:1; }","duration":"165.575465ms","start":"2026-08-11T12:05:49.470361Z","end":"2026-08-11T12:05:49.635936Z","steps":["trace[879484134] 'process raft request' (duration: 165.430951ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:49.683883Z","caller":"traceutil/trace.go:171","msg":"trace[511178131] linearizableReadLoop","detail":"{readStateIndex:26499; appliedIndex:26497; }","duration":"210.374712ms","start":"2026-08-11T12:05:49.473491Z","end":"2026-08-11T12:05:49.683866Z","steps":["trace[511178131] 'read index received' (duration: 162.313846ms)","trace[511178131] 'applied index is now lower than readState.Index' (duration: 48.060246ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:05:49.683982Z","caller":"traceutil/trace.go:171","msg":"trace[1517896316] transaction","detail":"{read_only:false; response_revision:25658; number_of_response:1; }","duration":"211.779247ms","start":"2026-08-11T12:05:49.472163Z","end":"2026-08-11T12:05:49.683942Z","steps":["trace[1517896316] 'process raft request' (duration: 211.561731ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:05:49.684147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.942569ms","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":"warn","ts":"2026-08-11T12:05:49.684168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.847025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T12:05:49.684217Z","caller":"traceutil/trace.go:171","msg":"trace[1329265945] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0; response_count:0; response_revision:25658; }","duration":"110.922137ms","start":"2026-08-11T12:05:49.573283Z","end":"2026-08-11T12:05:49.684205Z","steps":["trace[1329265945] 'agreement among raft nodes before linearized reading' (duration: 110.797414ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:49.684215Z","caller":"traceutil/trace.go:171","msg":"trace[1628038275] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25658; }","duration":"207.035891ms","start":"2026-08-11T12:05:49.477163Z","end":"2026-08-11T12:05:49.684198Z","steps":["trace[1628038275] 'agreement among raft nodes before linearized reading' (duration: 206.903397ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:05:49.684147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.64589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-08-11T12:05:49.68428Z","caller":"traceutil/trace.go:171","msg":"trace[1868422355] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25658; }","duration":"210.796743ms","start":"2026-08-11T12:05:49.473459Z","end":"2026-08-11T12:05:49.684256Z","steps":["trace[1868422355] 'agreement among raft nodes before linearized reading' (duration: 210.500096ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:49.828135Z","caller":"traceutil/trace.go:171","msg":"trace[2012702433] transaction","detail":"{read_only:false; response_revision:25660; number_of_response:1; }","duration":"131.364847ms","start":"2026-08-11T12:05:49.696753Z","end":"2026-08-11T12:05:49.828118Z","steps":["trace[2012702433] 'process raft request' (duration: 131.332326ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:49.828182Z","caller":"traceutil/trace.go:171","msg":"trace[784392149] transaction","detail":"{read_only:false; response_revision:25659; number_of_response:1; }","duration":"144.227482ms","start":"2026-08-11T12:05:49.683922Z","end":"2026-08-11T12:05:49.828149Z","steps":["trace[784392149] 'process raft request' (duration: 130.892925ms)","trace[784392149] 'compare' (duration: 13.166453ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:05:49.82831Z","caller":"traceutil/trace.go:171","msg":"trace[1473058266] linearizableReadLoop","detail":"{readStateIndex:26500; appliedIndex:26499; }","duration":"142.895829ms","start":"2026-08-11T12:05:49.685406Z","end":"2026-08-11T12:05:49.828302Z","steps":["trace[1473058266] 'read index received' (duration: 129.483541ms)","trace[1473058266] 'applied index is now lower than readState.Index' (duration: 13.411628ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:05:49.828444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.024072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18556"} {"level":"info","ts":"2026-08-11T12:05:49.828472Z","caller":"traceutil/trace.go:171","msg":"trace[755639804] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25660; }","duration":"143.088284ms","start":"2026-08-11T12:05:49.685377Z","end":"2026-08-11T12:05:49.828465Z","steps":["trace[755639804] 'agreement among raft nodes before linearized reading' (duration: 142.955111ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:50.130955Z","caller":"traceutil/trace.go:171","msg":"trace[1546829257] linearizableReadLoop","detail":"{readStateIndex:26502; appliedIndex:26501; }","duration":"201.776082ms","start":"2026-08-11T12:05:49.929154Z","end":"2026-08-11T12:05:50.13093Z","steps":["trace[1546829257] 'read index received' (duration: 181.810323ms)","trace[1546829257] 'applied index is now lower than readState.Index' (duration: 19.964679ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:05:50.131245Z","caller":"traceutil/trace.go:171","msg":"trace[1902280134] transaction","detail":"{read_only:false; response_revision:25661; number_of_response:1; }","duration":"209.814099ms","start":"2026-08-11T12:05:49.921416Z","end":"2026-08-11T12:05:50.13123Z","steps":["trace[1902280134] 'process raft request' (duration: 189.605104ms)","trace[1902280134] 'compare' (duration: 19.801655ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:05:50.131494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.328815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T12:05:50.131578Z","caller":"traceutil/trace.go:171","msg":"trace[159322659] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25661; }","duration":"202.459398ms","start":"2026-08-11T12:05:49.929101Z","end":"2026-08-11T12:05:50.13156Z","steps":["trace[159322659] 'agreement among raft nodes before linearized reading' (duration: 202.321595ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:50.427374Z","caller":"traceutil/trace.go:171","msg":"trace[979915651] transaction","detail":"{read_only:false; response_revision:25662; number_of_response:1; }","duration":"198.188345ms","start":"2026-08-11T12:05:50.229159Z","end":"2026-08-11T12:05:50.427347Z","steps":["trace[979915651] 'process raft request' (duration: 196.927423ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:50.875644Z","caller":"traceutil/trace.go:171","msg":"trace[945780765] transaction","detail":"{read_only:false; response_revision:25668; number_of_response:1; }","duration":"215.325194ms","start":"2026-08-11T12:05:50.660297Z","end":"2026-08-11T12:05:50.875622Z","steps":["trace[945780765] 'process raft request' (duration: 215.266283ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:50.875746Z","caller":"traceutil/trace.go:171","msg":"trace[1777162358] transaction","detail":"{read_only:false; response_revision:25667; number_of_response:1; }","duration":"216.917842ms","start":"2026-08-11T12:05:50.658785Z","end":"2026-08-11T12:05:50.875703Z","steps":["trace[1777162358] 'process raft request' (duration: 197.622859ms)","trace[1777162358] 'compare' (duration: 19.038467ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:05:51.402278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.727002ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-11T12:05:51.403592Z","caller":"traceutil/trace.go:171","msg":"trace[2142426234] linearizableReadLoop","detail":"{readStateIndex:26511; appliedIndex:26509; }","duration":"181.200736ms","start":"2026-08-11T12:05:51.222373Z","end":"2026-08-11T12:05:51.403574Z","steps":["trace[2142426234] 'read index received' (duration: 35.65µs)","trace[2142426234] 'applied index is now lower than readState.Index' (duration: 181.163296ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:05:51.403668Z","caller":"traceutil/trace.go:171","msg":"trace[786299302] transaction","detail":"{read_only:false; response_revision:25669; number_of_response:1; }","duration":"365.098491ms","start":"2026-08-11T12:05:51.038532Z","end":"2026-08-11T12:05:51.40363Z","steps":["trace[786299302] 'process raft request' (duration: 363.850341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:05:51.403837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:05:51.038504Z","time spent":"365.246755ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T12:05:51.406795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.379446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T12:05:51.406913Z","caller":"traceutil/trace.go:171","msg":"trace[432553809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25669; }","duration":"184.55115ms","start":"2026-08-11T12:05:51.222336Z","end":"2026-08-11T12:05:51.406887Z","steps":["trace[432553809] 'agreement among raft nodes before linearized reading' (duration: 181.33279ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:51.577086Z","caller":"traceutil/trace.go:171","msg":"trace[1301699857] transaction","detail":"{read_only:false; response_revision:25670; number_of_response:1; }","duration":"104.743045ms","start":"2026-08-11T12:05:51.472317Z","end":"2026-08-11T12:05:51.57706Z","steps":["trace[1301699857] 'process raft request' (duration: 104.572001ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:05:51.737107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.119697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-08-11T12:05:51.737203Z","caller":"traceutil/trace.go:171","msg":"trace[1547400478] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:25670; }","duration":"128.265761ms","start":"2026-08-11T12:05:51.608915Z","end":"2026-08-11T12:05:51.737181Z","steps":["trace[1547400478] 'range keys from in-memory index tree' (duration: 127.948393ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:51.880418Z","caller":"traceutil/trace.go:171","msg":"trace[1191788092] transaction","detail":"{read_only:false; response_revision:25671; number_of_response:1; }","duration":"134.353011ms","start":"2026-08-11T12:05:51.746032Z","end":"2026-08-11T12:05:51.880385Z","steps":["trace[1191788092] 'process raft request' (duration: 133.879938ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:52.163497Z","caller":"traceutil/trace.go:171","msg":"trace[1402781235] linearizableReadLoop","detail":"{readStateIndex:26516; appliedIndex:26514; }","duration":"273.574071ms","start":"2026-08-11T12:05:51.889906Z","end":"2026-08-11T12:05:52.16348Z","steps":["trace[1402781235] 'read index received' (duration: 17.57106ms)","trace[1402781235] 'applied index is now lower than readState.Index' (duration: 256.002061ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:05:52.163542Z","caller":"traceutil/trace.go:171","msg":"trace[1790725915] transaction","detail":"{read_only:false; response_revision:25673; number_of_response:1; }","duration":"279.023363ms","start":"2026-08-11T12:05:51.884485Z","end":"2026-08-11T12:05:52.163508Z","steps":["trace[1790725915] 'process raft request' (duration: 264.476706ms)","trace[1790725915] 'compare' (duration: 14.380493ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:05:52.163681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.710804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-11T12:05:52.163715Z","caller":"traceutil/trace.go:171","msg":"trace[1791859579] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25674; }","duration":"273.819456ms","start":"2026-08-11T12:05:51.889887Z","end":"2026-08-11T12:05:52.163707Z","steps":["trace[1791859579] 'agreement among raft nodes before linearized reading' (duration: 273.668743ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:52.163839Z","caller":"traceutil/trace.go:171","msg":"trace[328195103] transaction","detail":"{read_only:false; response_revision:25674; number_of_response:1; }","duration":"278.673756ms","start":"2026-08-11T12:05:51.885135Z","end":"2026-08-11T12:05:52.163809Z","steps":["trace[328195103] 'process raft request' (duration: 278.300667ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:05:52.174684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.097079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-11T12:05:52.174701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.417358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-11T12:05:52.174745Z","caller":"traceutil/trace.go:171","msg":"trace[76806602] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:25675; }","duration":"106.209632ms","start":"2026-08-11T12:05:52.068521Z","end":"2026-08-11T12:05:52.17473Z","steps":["trace[76806602] 'agreement among raft nodes before linearized reading' (duration: 106.093429ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:52.174775Z","caller":"traceutil/trace.go:171","msg":"trace[1955397396] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:25675; }","duration":"202.553121ms","start":"2026-08-11T12:05:51.972203Z","end":"2026-08-11T12:05:52.174756Z","steps":["trace[1955397396] 'agreement among raft nodes before linearized reading' (duration: 202.385727ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:52.554442Z","caller":"traceutil/trace.go:171","msg":"trace[1199975706] transaction","detail":"{read_only:false; response_revision:25676; number_of_response:1; }","duration":"178.81903ms","start":"2026-08-11T12:05:52.375595Z","end":"2026-08-11T12:05:52.554414Z","steps":["trace[1199975706] 'process raft request' (duration: 98.448951ms)","trace[1199975706] 'compare' (duration: 80.291017ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:05:52.574079Z","caller":"traceutil/trace.go:171","msg":"trace[2007626324] transaction","detail":"{read_only:false; response_revision:25678; number_of_response:1; }","duration":"109.453131ms","start":"2026-08-11T12:05:52.464605Z","end":"2026-08-11T12:05:52.574058Z","steps":["trace[2007626324] 'process raft request' (duration: 109.41142ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:52.57438Z","caller":"traceutil/trace.go:171","msg":"trace[1398480659] transaction","detail":"{read_only:false; response_revision:25677; number_of_response:1; }","duration":"140.857609ms","start":"2026-08-11T12:05:52.433515Z","end":"2026-08-11T12:05:52.574373Z","steps":["trace[1398480659] 'process raft request' (duration: 140.403348ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:52.854935Z","caller":"traceutil/trace.go:171","msg":"trace[78043057] transaction","detail":"{read_only:false; response_revision:25679; number_of_response:1; }","duration":"362.420446ms","start":"2026-08-11T12:05:52.492481Z","end":"2026-08-11T12:05:52.854901Z","steps":["trace[78043057] 'process raft request' (duration: 362.129399ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:05:52.855141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:05:52.492461Z","time spent":"362.57958ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T12:05:52.855591Z","caller":"traceutil/trace.go:171","msg":"trace[1670377933] transaction","detail":"{read_only:false; response_revision:25680; number_of_response:1; }","duration":"361.33821ms","start":"2026-08-11T12:05:52.494233Z","end":"2026-08-11T12:05:52.855572Z","steps":["trace[1670377933] 'process raft request' (duration: 360.594541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:05:52.855687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:05:52.494202Z","time spent":"361.442602ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T12:05:52.855755Z","caller":"traceutil/trace.go:171","msg":"trace[72284737] transaction","detail":"{read_only:false; response_revision:25681; number_of_response:1; }","duration":"359.832153ms","start":"2026-08-11T12:05:52.49589Z","end":"2026-08-11T12:05:52.855722Z","steps":["trace[72284737] 'process raft request' (duration: 359.26043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:05:52.856164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:05:52.49588Z","time spent":"360.192672ms","remote":"127.0.0.1:46106","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-08-11T12:05:53.009682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.285763ms","expected-duration":"100ms","prefix":"","request":"header: compaction: ","response":"size:6"} {"level":"info","ts":"2026-08-11T12:05:53.009783Z","caller":"traceutil/trace.go:171","msg":"trace[1742202233] linearizableReadLoop","detail":"{readStateIndex:26523; appliedIndex:26520; }","duration":"493.925677ms","start":"2026-08-11T12:05:52.51584Z","end":"2026-08-11T12:05:53.009766Z","steps":["trace[1742202233] 'read index received' (duration: 61.622749ms)","trace[1742202233] 'applied index is now lower than readState.Index' (duration: 432.301568ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:05:53.010108Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22215} {"level":"info","ts":"2026-08-11T12:05:53.012241Z","caller":"traceutil/trace.go:171","msg":"trace[831453329] compact","detail":"{revision:22215; response_revision:25681; }","duration":"456.809817ms","start":"2026-08-11T12:05:52.555408Z","end":"2026-08-11T12:05:53.012218Z","steps":["trace[831453329] 'process raft request' (duration: 300.11651ms)","trace[831453329] 'check and update compact revision' (duration: 153.194741ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:05:53.012305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.093345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-08-11T12:05:53.01232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:05:52.555379Z","time spent":"456.93749ms","remote":"127.0.0.1:45872","response type":"/etcdserverpb.KV/Compact","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-11T12:05:53.012355Z","caller":"traceutil/trace.go:171","msg":"trace[1883691238] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25681; }","duration":"330.178887ms","start":"2026-08-11T12:05:52.682161Z","end":"2026-08-11T12:05:53.01234Z","steps":["trace[1883691238] 'agreement among raft nodes before linearized reading' (duration: 330.013053ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:05:53.012399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:05:52.682139Z","time spent":"330.251998ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-11T12:05:53.012745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.883379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T12:05:53.012847Z","caller":"traceutil/trace.go:171","msg":"trace[2047373162] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:25681; }","duration":"497.042724ms","start":"2026-08-11T12:05:52.515782Z","end":"2026-08-11T12:05:53.012825Z","steps":["trace[2047373162] 'agreement among raft nodes before linearized reading' (duration: 496.823928ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:05:53.01293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:05:52.515768Z","time spent":"497.146656ms","remote":"127.0.0.1:59908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-08-11T12:05:53.013143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.87339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-11T12:05:53.013216Z","caller":"traceutil/trace.go:171","msg":"trace[1764688736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25681; }","duration":"131.977472ms","start":"2026-08-11T12:05:52.88121Z","end":"2026-08-11T12:05:53.013188Z","steps":["trace[1764688736] 'agreement among raft nodes before linearized reading' (duration: 131.559342ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:05:53.013478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.29824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-11T12:05:53.013524Z","caller":"traceutil/trace.go:171","msg":"trace[1041940567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25681; }","duration":"132.372891ms","start":"2026-08-11T12:05:52.881141Z","end":"2026-08-11T12:05:53.013514Z","steps":["trace[1041940567] 'agreement among raft nodes before linearized reading' (duration: 131.617053ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:05:53.390881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.362347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T12:05:53.390959Z","caller":"traceutil/trace.go:171","msg":"trace[1477727645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25684; }","duration":"140.821538ms","start":"2026-08-11T12:05:53.25012Z","end":"2026-08-11T12:05:53.390941Z","steps":["trace[1477727645] 'range keys from in-memory index tree' (duration: 140.278135ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:05:53.741541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.136936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T12:05:53.741741Z","caller":"traceutil/trace.go:171","msg":"trace[1138052394] transaction","detail":"{read_only:false; response_revision:25686; number_of_response:1; }","duration":"158.891892ms","start":"2026-08-11T12:05:53.582832Z","end":"2026-08-11T12:05:53.741724Z","steps":["trace[1138052394] 'process raft request' (duration: 158.81639ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:53.74204Z","caller":"traceutil/trace.go:171","msg":"trace[1059380720] transaction","detail":"{read_only:false; response_revision:25685; number_of_response:1; }","duration":"330.935444ms","start":"2026-08-11T12:05:53.411066Z","end":"2026-08-11T12:05:53.742001Z","steps":["trace[1059380720] 'process raft request' (duration: 227.250855ms)","trace[1059380720] 'compare' (duration: 99.168328ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:05:53.742561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:05:53.411044Z","time spent":"331.094409ms","remote":"127.0.0.1:46106","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-08-11T12:05:54.022813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.103244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-11T12:05:54.022898Z","caller":"traceutil/trace.go:171","msg":"trace[319833708] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:25686; }","duration":"152.266269ms","start":"2026-08-11T12:05:53.870614Z","end":"2026-08-11T12:05:54.02288Z","steps":["trace[319833708] 'count revisions from in-memory index tree' (duration: 152.022143ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:54.023782Z","caller":"traceutil/trace.go:171","msg":"trace[1517822957] transaction","detail":"{read_only:false; response_revision:25687; number_of_response:1; }","duration":"111.741066ms","start":"2026-08-11T12:05:53.912024Z","end":"2026-08-11T12:05:54.023765Z","steps":["trace[1517822957] 'process raft request' (duration: 50.060426ms)","trace[1517822957] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/capo-system/controller-leader-election-capo; req_size:520; } (duration: 60.529102ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:05:54.281048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.203113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-11T12:05:54.281174Z","caller":"traceutil/trace.go:171","msg":"trace[135438292] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25688; }","duration":"113.346787ms","start":"2026-08-11T12:05:54.167787Z","end":"2026-08-11T12:05:54.281134Z","steps":["trace[135438292] 'range keys from in-memory index tree' (duration: 113.023998ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:54.434762Z","caller":"traceutil/trace.go:171","msg":"trace[284496104] linearizableReadLoop","detail":"{readStateIndex:26532; appliedIndex:26531; }","duration":"215.108728ms","start":"2026-08-11T12:05:54.219627Z","end":"2026-08-11T12:05:54.434736Z","steps":["trace[284496104] 'read index received' (duration: 203.37764ms)","trace[284496104] 'applied index is now lower than readState.Index' (duration: 11.730248ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:05:54.434785Z","caller":"traceutil/trace.go:171","msg":"trace[980098397] transaction","detail":"{read_only:false; response_revision:25689; number_of_response:1; }","duration":"254.328848ms","start":"2026-08-11T12:05:54.180418Z","end":"2026-08-11T12:05:54.434747Z","steps":["trace[980098397] 'process raft request' (duration: 242.5794ms)","trace[980098397] 'compare' (duration: 11.604795ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:05:54.434898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.248131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T12:05:54.434933Z","caller":"traceutil/trace.go:171","msg":"trace[2072983495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25689; }","duration":"215.335493ms","start":"2026-08-11T12:05:54.219587Z","end":"2026-08-11T12:05:54.434922Z","steps":["trace[2072983495] 'agreement among raft nodes before linearized reading' (duration: 215.250151ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:05:54.612962Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22215,"took":"1.597784721s","hash":3681507255,"current-db-size-bytes":58245120,"current-db-size":"58 MB","current-db-size-in-use-bytes":45883392,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-08-11T12:05:54.613089Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3681507255,"revision":22215,"compact-revision":19869} {"level":"info","ts":"2026-08-11T12:05:54.613657Z","caller":"traceutil/trace.go:171","msg":"trace[1101825805] linearizableReadLoop","detail":"{readStateIndex:26533; appliedIndex:26532; }","duration":"177.573749ms","start":"2026-08-11T12:05:54.43605Z","end":"2026-08-11T12:05:54.613623Z","steps":["trace[1101825805] 'read index received' (duration: 177.161789ms)","trace[1101825805] 'applied index is now lower than readState.Index' (duration: 410.92µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:05:54.613759Z","caller":"traceutil/trace.go:171","msg":"trace[852527208] transaction","detail":"{read_only:false; response_revision:25690; number_of_response:1; }","duration":"328.034804ms","start":"2026-08-11T12:05:54.285702Z","end":"2026-08-11T12:05:54.613736Z","steps":["trace[852527208] 'process raft request' (duration: 327.595493ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:05:54.613795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.736682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T12:05:54.61384Z","caller":"traceutil/trace.go:171","msg":"trace[722666730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25690; }","duration":"177.822955ms","start":"2026-08-11T12:05:54.435994Z","end":"2026-08-11T12:05:54.613817Z","steps":["trace[722666730] 'agreement among raft nodes before linearized reading' (duration: 177.740253ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:05:54.613888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:05:54.285676Z","time spent":"328.137056ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T12:05:54.968474Z","caller":"traceutil/trace.go:171","msg":"trace[1499059301] transaction","detail":"{read_only:false; response_revision:25694; number_of_response:1; }","duration":"106.685753ms","start":"2026-08-11T12:05:54.861764Z","end":"2026-08-11T12:05:54.96845Z","steps":["trace[1499059301] 'process raft request' (duration: 106.488358ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:06:00.026833Z","caller":"traceutil/trace.go:171","msg":"trace[1944820823] transaction","detail":"{read_only:false; response_revision:25729; number_of_response:1; }","duration":"266.549659ms","start":"2026-08-11T12:05:59.760261Z","end":"2026-08-11T12:06:00.026811Z","steps":["trace[1944820823] 'process raft request' (duration: 266.401166ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:06:00.354786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.683814ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T12:06:00.35496Z","caller":"traceutil/trace.go:171","msg":"trace[1052533062] transaction","detail":"{read_only:false; response_revision:25730; number_of_response:1; }","duration":"593.733621ms","start":"2026-08-11T12:05:59.761195Z","end":"2026-08-11T12:06:00.354928Z","steps":["trace[1052533062] 'process raft request' (duration: 410.227456ms)","trace[1052533062] 'compare' (duration: 182.589182ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:06:00.354996Z","caller":"traceutil/trace.go:171","msg":"trace[1915564768] transaction","detail":"{read_only:false; response_revision:25731; number_of_response:1; }","duration":"312.227497ms","start":"2026-08-11T12:06:00.042742Z","end":"2026-08-11T12:06:00.354969Z","steps":["trace[1915564768] 'process raft request' (duration: 312.125345ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:06:00.355089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:05:59.76117Z","time spent":"593.851595ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T12:06:00.355153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:06:00.042725Z","time spent":"312.358761ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T12:06:00.355255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.720115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-11T12:06:00.354932Z","caller":"traceutil/trace.go:171","msg":"trace[1589561760] linearizableReadLoop","detail":"{readStateIndex:26575; appliedIndex:26573; }","duration":"309.398568ms","start":"2026-08-11T12:06:00.045521Z","end":"2026-08-11T12:06:00.35492Z","steps":["trace[1589561760] 'read index received' (duration: 125.820702ms)","trace[1589561760] 'applied index is now lower than readState.Index' (duration: 183.576976ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:06:00.355319Z","caller":"traceutil/trace.go:171","msg":"trace[1077562709] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25731; }","duration":"309.836888ms","start":"2026-08-11T12:06:00.045468Z","end":"2026-08-11T12:06:00.355305Z","steps":["trace[1077562709] 'agreement among raft nodes before linearized reading' (duration: 309.629303ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:06:00.355354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:06:00.045448Z","time spent":"309.89773ms","remote":"127.0.0.1:46106","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-08-11T12:06:00.355638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.611371ms","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":"warn","ts":"2026-08-11T12:06:00.355664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.001147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"warn","ts":"2026-08-11T12:06:00.355668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.887724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T12:06:00.355686Z","caller":"traceutil/trace.go:171","msg":"trace[1470126643] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25731; }","duration":"104.057708ms","start":"2026-08-11T12:06:00.251622Z","end":"2026-08-11T12:06:00.35568Z","steps":["trace[1470126643] 'agreement among raft nodes before linearized reading' (duration: 103.982337ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:06:00.355708Z","caller":"traceutil/trace.go:171","msg":"trace[1064908743] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25731; }","duration":"134.954375ms","start":"2026-08-11T12:06:00.220738Z","end":"2026-08-11T12:06:00.355693Z","steps":["trace[1064908743] 'agreement among raft nodes before linearized reading' (duration: 134.895763ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:06:00.355671Z","caller":"traceutil/trace.go:171","msg":"trace[231447352] 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:25731; }","duration":"195.647172ms","start":"2026-08-11T12:06:00.160014Z","end":"2026-08-11T12:06:00.355662Z","steps":["trace[231447352] 'agreement among raft nodes before linearized reading' (duration: 195.58527ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:06:00.935531Z","caller":"traceutil/trace.go:171","msg":"trace[1224278596] linearizableReadLoop","detail":"{readStateIndex:26577; appliedIndex:26576; }","duration":"299.978377ms","start":"2026-08-11T12:06:00.635526Z","end":"2026-08-11T12:06:00.935504Z","steps":["trace[1224278596] 'read index received' (duration: 299.840233ms)","trace[1224278596] 'applied index is now lower than readState.Index' (duration: 137.033µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:06:00.935572Z","caller":"traceutil/trace.go:171","msg":"trace[679182781] transaction","detail":"{read_only:false; response_revision:25733; number_of_response:1; }","duration":"482.182789ms","start":"2026-08-11T12:06:00.453348Z","end":"2026-08-11T12:06:00.935531Z","steps":["trace[679182781] 'process raft request' (duration: 482.015945ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:06:00.935761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:06:00.453336Z","time spent":"482.315322ms","remote":"127.0.0.1:46106","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-08-11T12:06:00.935846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.296914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-11T12:06:00.93594Z","caller":"traceutil/trace.go:171","msg":"trace[1770623339] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25733; }","duration":"300.440447ms","start":"2026-08-11T12:06:00.635477Z","end":"2026-08-11T12:06:00.935917Z","steps":["trace[1770623339] 'agreement among raft nodes before linearized reading' (duration: 300.12489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:06:00.935994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:06:00.63546Z","time spent":"300.52324ms","remote":"127.0.0.1:46106","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-08-11T12:06:01.259944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.406035ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T12:06:01.260645Z","caller":"traceutil/trace.go:171","msg":"trace[1668097993] linearizableReadLoop","detail":"{readStateIndex:26579; appliedIndex:26577; }","duration":"325.01626ms","start":"2026-08-11T12:06:00.935605Z","end":"2026-08-11T12:06:01.260621Z","steps":["trace[1668097993] 'read index received' (duration: 115.922899ms)","trace[1668097993] 'applied index is now lower than readState.Index' (duration: 209.091161ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:06:01.260672Z","caller":"traceutil/trace.go:171","msg":"trace[663073370] transaction","detail":"{read_only:false; response_revision:25735; number_of_response:1; }","duration":"338.122131ms","start":"2026-08-11T12:06:00.922516Z","end":"2026-08-11T12:06:01.260638Z","steps":["trace[663073370] 'process raft request' (duration: 337.834244ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:06:01.260736Z","caller":"traceutil/trace.go:171","msg":"trace[1548541337] transaction","detail":"{read_only:false; response_revision:25734; number_of_response:1; }","duration":"615.666808ms","start":"2026-08-11T12:06:00.645026Z","end":"2026-08-11T12:06:01.260693Z","steps":["trace[1548541337] 'process raft request' (duration: 406.397903ms)","trace[1548541337] 'compare' (duration: 208.010575ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:06:01.260865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.384888ms","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":"warn","ts":"2026-08-11T12:06:01.260898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:06:00.922497Z","time spent":"338.295245ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T12:06:01.260944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:06:00.645Z","time spent":"615.798801ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T12:06:01.260971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.961066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-08-11T12:06:01.260932Z","caller":"traceutil/trace.go:171","msg":"trace[425125248] 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:25735; }","duration":"336.486731ms","start":"2026-08-11T12:06:00.924427Z","end":"2026-08-11T12:06:01.260914Z","steps":["trace[425125248] 'agreement among raft nodes before linearized reading' (duration: 336.211854ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:06:01.261072Z","caller":"traceutil/trace.go:171","msg":"trace[1007257859] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:25735; }","duration":"603.11864ms","start":"2026-08-11T12:06:00.657925Z","end":"2026-08-11T12:06:01.261043Z","steps":["trace[1007257859] 'agreement among raft nodes before linearized reading' (duration: 602.812473ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:06:01.261132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:06:00.657905Z","time spent":"603.218763ms","remote":"127.0.0.1:46010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-08-11T12:06:01.261121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:06:00.924401Z","time spent":"336.693815ms","remote":"127.0.0.1:46430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-08-11T12:06:01.282844Z","caller":"traceutil/trace.go:171","msg":"trace[1336328582] transaction","detail":"{read_only:false; response_revision:25736; number_of_response:1; }","duration":"341.474923ms","start":"2026-08-11T12:06:00.941278Z","end":"2026-08-11T12:06:01.282752Z","steps":["trace[1336328582] 'process raft request' (duration: 341.269568ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:06:01.283049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:06:00.941256Z","time spent":"341.691339ms","remote":"127.0.0.1:46106","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-08-11T12:06:01.351053Z","caller":"traceutil/trace.go:171","msg":"trace[1326567722] transaction","detail":"{read_only:false; response_revision:25737; number_of_response:1; }","duration":"359.040843ms","start":"2026-08-11T12:06:00.991993Z","end":"2026-08-11T12:06:01.351034Z","steps":["trace[1326567722] 'process raft request' (duration: 358.612432ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:06:01.351392Z","caller":"traceutil/trace.go:171","msg":"trace[486420677] transaction","detail":"{read_only:false; response_revision:25738; number_of_response:1; }","duration":"359.068654ms","start":"2026-08-11T12:06:00.992296Z","end":"2026-08-11T12:06:01.351365Z","steps":["trace[486420677] 'process raft request' (duration: 358.663784ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:06:01.351566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:06:00.992235Z","time spent":"359.241167ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T12:06:01.351759Z","caller":"traceutil/trace.go:171","msg":"trace[1514748823] transaction","detail":"{read_only:false; response_revision:25739; number_of_response:1; }","duration":"358.558821ms","start":"2026-08-11T12:06:00.993158Z","end":"2026-08-11T12:06:01.351717Z","steps":["trace[1514748823] 'process raft request' (duration: 358.087359ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:06:01.35187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:06:00.991963Z","time spent":"359.196597ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T12:06:01.351969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:06:00.993146Z","time spent":"358.698234ms","remote":"127.0.0.1:46106","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-08-11T12:06:01.352213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.514715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-11T12:06:01.35227Z","caller":"traceutil/trace.go:171","msg":"trace[1696437544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25739; }","duration":"290.615687ms","start":"2026-08-11T12:06:01.06164Z","end":"2026-08-11T12:06:01.352255Z","steps":["trace[1696437544] 'agreement among raft nodes before linearized reading' (duration: 290.397822ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:06:01.35234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.835727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-11T12:06:01.352229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.939571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-11T12:06:01.352404Z","caller":"traceutil/trace.go:171","msg":"trace[1171572540] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:25739; }","duration":"155.928789ms","start":"2026-08-11T12:06:01.19645Z","end":"2026-08-11T12:06:01.352379Z","steps":["trace[1171572540] 'agreement among raft nodes before linearized reading' (duration: 155.842087ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:06:01.352424Z","caller":"traceutil/trace.go:171","msg":"trace[767558818] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25739; }","duration":"290.170617ms","start":"2026-08-11T12:06:01.062234Z","end":"2026-08-11T12:06:01.352404Z","steps":["trace[767558818] 'agreement among raft nodes before linearized reading' (duration: 289.797428ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:06:01.352519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.454798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-11T12:06:01.352586Z","caller":"traceutil/trace.go:171","msg":"trace[446848205] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25739; }","duration":"291.619542ms","start":"2026-08-11T12:06:01.060948Z","end":"2026-08-11T12:06:01.352567Z","steps":["trace[446848205] 'agreement among raft nodes before linearized reading' (duration: 291.362506ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:06:06.697896Z","caller":"traceutil/trace.go:171","msg":"trace[1091397517] linearizableReadLoop","detail":"{readStateIndex:26678; appliedIndex:26676; }","duration":"308.120056ms","start":"2026-08-11T12:06:06.389744Z","end":"2026-08-11T12:06:06.697864Z","steps":["trace[1091397517] 'read index received' (duration: 49.921152ms)","trace[1091397517] 'applied index is now lower than readState.Index' (duration: 258.195254ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:06:06.69794Z","caller":"traceutil/trace.go:171","msg":"trace[213607528] transaction","detail":"{read_only:false; response_revision:25832; number_of_response:1; }","duration":"321.880692ms","start":"2026-08-11T12:06:06.376019Z","end":"2026-08-11T12:06:06.697899Z","steps":["trace[213607528] 'process raft request' (duration: 293.560129ms)","trace[213607528] 'compare' (duration: 27.837732ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:06:06.698093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:06:06.37599Z","time spent":"322.019956ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T12:06:06.698128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.288744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T12:06:06.698186Z","caller":"traceutil/trace.go:171","msg":"trace[659033081] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:25832; }","duration":"164.402156ms","start":"2026-08-11T12:06:06.533767Z","end":"2026-08-11T12:06:06.698169Z","steps":["trace[659033081] 'agreement among raft nodes before linearized reading' (duration: 164.281673ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:06:06.698195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.446474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-11T12:06:06.69824Z","caller":"traceutil/trace.go:171","msg":"trace[1471766400] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25832; }","duration":"308.556106ms","start":"2026-08-11T12:06:06.389669Z","end":"2026-08-11T12:06:06.698225Z","steps":["trace[1471766400] 'agreement among raft nodes before linearized reading' (duration: 308.349561ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:06:06.698274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:06:06.389616Z","time spent":"308.649149ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-11T12:06:07.15582Z","caller":"traceutil/trace.go:171","msg":"trace[1279177261] transaction","detail":"{read_only:false; response_revision:25835; number_of_response:1; }","duration":"172.114744ms","start":"2026-08-11T12:06:06.983675Z","end":"2026-08-11T12:06:07.155789Z","steps":["trace[1279177261] 'process raft request' (duration: 171.630863ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:06:07.273742Z","caller":"traceutil/trace.go:171","msg":"trace[1200738737] transaction","detail":"{read_only:false; response_revision:25836; number_of_response:1; }","duration":"256.316077ms","start":"2026-08-11T12:06:07.017396Z","end":"2026-08-11T12:06:07.273712Z","steps":["trace[1200738737] 'process raft request' (duration: 207.930852ms)","trace[1200738737] 'compare' (duration: 48.088188ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:06:19.146785Z","caller":"traceutil/trace.go:171","msg":"trace[532276494] transaction","detail":"{read_only:false; response_revision:25956; number_of_response:1; }","duration":"123.974296ms","start":"2026-08-11T12:06:19.022781Z","end":"2026-08-11T12:06:19.146755Z","steps":["trace[532276494] 'process raft request' (duration: 123.786622ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:06:19.447008Z","caller":"traceutil/trace.go:171","msg":"trace[668529734] transaction","detail":"{read_only:false; response_revision:25957; number_of_response:1; }","duration":"105.429093ms","start":"2026-08-11T12:06:19.341552Z","end":"2026-08-11T12:06:19.446982Z","steps":["trace[668529734] 'process raft request' (duration: 105.192067ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:06:19.732276Z","caller":"traceutil/trace.go:171","msg":"trace[2044619984] linearizableReadLoop","detail":"{readStateIndex:26808; appliedIndex:26806; }","duration":"282.681713ms","start":"2026-08-11T12:06:19.449572Z","end":"2026-08-11T12:06:19.732254Z","steps":["trace[2044619984] 'read index received' (duration: 184.11554ms)","trace[2044619984] 'applied index is now lower than readState.Index' (duration: 98.564873ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:06:19.732382Z","caller":"traceutil/trace.go:171","msg":"trace[1061893601] transaction","detail":"{read_only:false; response_revision:25958; number_of_response:1; }","duration":"301.72461ms","start":"2026-08-11T12:06:19.430644Z","end":"2026-08-11T12:06:19.732369Z","steps":["trace[1061893601] 'process raft request' (duration: 203.156896ms)","trace[1061893601] 'compare' (duration: 98.015481ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:06:19.732479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:06:19.430621Z","time spent":"301.787311ms","remote":"127.0.0.1:46106","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-08-11T12:06:19.732463Z","caller":"traceutil/trace.go:171","msg":"trace[244716146] transaction","detail":"{read_only:false; response_revision:25959; number_of_response:1; }","duration":"301.187787ms","start":"2026-08-11T12:06:19.431249Z","end":"2026-08-11T12:06:19.732437Z","steps":["trace[244716146] 'process raft request' (duration: 300.944221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:06:19.732611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:06:19.431212Z","time spent":"301.32409ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T12:06:19.73277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.196236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-11T12:06:19.73281Z","caller":"traceutil/trace.go:171","msg":"trace[1040867147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25959; }","duration":"283.263648ms","start":"2026-08-11T12:06:19.449537Z","end":"2026-08-11T12:06:19.732801Z","steps":["trace[1040867147] 'agreement among raft nodes before linearized reading' (duration: 283.128904ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:06:19.748356Z","caller":"traceutil/trace.go:171","msg":"trace[1211044114] transaction","detail":"{read_only:false; response_revision:25960; number_of_response:1; }","duration":"296.533722ms","start":"2026-08-11T12:06:19.451803Z","end":"2026-08-11T12:06:19.748336Z","steps":["trace[1211044114] 'process raft request' (duration: 296.243554ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:06:19.748479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.120258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-11T12:06:19.748582Z","caller":"traceutil/trace.go:171","msg":"trace[530544233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25961; }","duration":"297.261691ms","start":"2026-08-11T12:06:19.451298Z","end":"2026-08-11T12:06:19.74856Z","steps":["trace[530544233] 'agreement among raft nodes before linearized reading' (duration: 296.980373ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:06:19.748755Z","caller":"traceutil/trace.go:171","msg":"trace[1397089267] transaction","detail":"{read_only:false; response_revision:25961; number_of_response:1; }","duration":"273.215772ms","start":"2026-08-11T12:06:19.475523Z","end":"2026-08-11T12:06:19.748739Z","steps":["trace[1397089267] 'process raft request' (duration: 272.685798ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:06:19.748972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.130112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-11T12:06:19.749012Z","caller":"traceutil/trace.go:171","msg":"trace[1441269918] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25961; }","duration":"296.187004ms","start":"2026-08-11T12:06:19.452816Z","end":"2026-08-11T12:06:19.749003Z","steps":["trace[1441269918] 'agreement among raft nodes before linearized reading' (duration: 296.042121ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:06:21.219299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.094249ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-11T12:06:21.336747Z","caller":"traceutil/trace.go:171","msg":"trace[2115222180] linearizableReadLoop","detail":"{readStateIndex:26819; appliedIndex:26818; }","duration":"116.991505ms","start":"2026-08-11T12:06:21.219728Z","end":"2026-08-11T12:06:21.336719Z","steps":["trace[2115222180] 'read index received' (duration: 116.777439ms)","trace[2115222180] 'applied index is now lower than readState.Index' (duration: 212.826µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:06:21.336855Z","caller":"traceutil/trace.go:171","msg":"trace[2133488965] transaction","detail":"{read_only:false; response_revision:25969; number_of_response:1; }","duration":"185.162966ms","start":"2026-08-11T12:06:21.151661Z","end":"2026-08-11T12:06:21.336824Z","steps":["trace[2133488965] 'process raft request' (duration: 184.865167ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:06:21.336901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.151669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T12:06:21.336966Z","caller":"traceutil/trace.go:171","msg":"trace[982625174] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25969; }","duration":"117.229411ms","start":"2026-08-11T12:06:21.219722Z","end":"2026-08-11T12:06:21.336952Z","steps":["trace[982625174] 'agreement among raft nodes before linearized reading' (duration: 117.103038ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:06:35.951532Z","caller":"traceutil/trace.go:171","msg":"trace[1429954539] transaction","detail":"{read_only:false; response_revision:26092; number_of_response:1; }","duration":"105.104534ms","start":"2026-08-11T12:06:35.846411Z","end":"2026-08-11T12:06:35.951516Z","steps":["trace[1429954539] 'process raft request' (duration: 105.004851ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:06:40.813558Z","caller":"traceutil/trace.go:171","msg":"trace[452193128] transaction","detail":"{read_only:false; response_revision:26126; number_of_response:1; }","duration":"150.2776ms","start":"2026-08-11T12:06:40.66326Z","end":"2026-08-11T12:06:40.813538Z","steps":["trace[452193128] 'process raft request' (duration: 150.026694ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:06:41.012302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.877576ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T12:06:41.012387Z","caller":"traceutil/trace.go:171","msg":"trace[840737498] linearizableReadLoop","detail":"{readStateIndex:26980; appliedIndex:26979; }","duration":"151.868419ms","start":"2026-08-11T12:06:40.860504Z","end":"2026-08-11T12:06:41.012372Z","steps":["trace[840737498] 'read index received' (duration: 31.724006ms)","trace[840737498] 'applied index is now lower than readState.Index' (duration: 120.143543ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:06:41.012487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.991342ms","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-08-11T12:06:41.012469Z","caller":"traceutil/trace.go:171","msg":"trace[339261817] transaction","detail":"{read_only:false; response_revision:26127; number_of_response:1; }","duration":"202.572331ms","start":"2026-08-11T12:06:40.809848Z","end":"2026-08-11T12:06:41.01242Z","steps":["trace[339261817] 'process raft request' (duration: 82.49333ms)","trace[339261817] 'compare' (duration: 119.648151ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:06:41.012594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.567472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-11T12:06:41.012608Z","caller":"traceutil/trace.go:171","msg":"trace[2041965597] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26127; }","duration":"142.618663ms","start":"2026-08-11T12:06:40.869985Z","end":"2026-08-11T12:06:41.012604Z","steps":["trace[2041965597] 'agreement among raft nodes before linearized reading' (duration: 142.534781ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:06:41.012506Z","caller":"traceutil/trace.go:171","msg":"trace[485668541] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:26127; }","duration":"152.051944ms","start":"2026-08-11T12:06:40.860446Z","end":"2026-08-11T12:06:41.012498Z","steps":["trace[485668541] 'agreement among raft nodes before linearized reading' (duration: 152.006683ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:06:43.277692Z","caller":"traceutil/trace.go:171","msg":"trace[44958254] linearizableReadLoop","detail":"{readStateIndex:26995; appliedIndex:26994; }","duration":"239.080006ms","start":"2026-08-11T12:06:43.03859Z","end":"2026-08-11T12:06:43.277671Z","steps":["trace[44958254] 'read index received' (duration: 238.955223ms)","trace[44958254] 'applied index is now lower than readState.Index' (duration: 123.613µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:06:43.277797Z","caller":"traceutil/trace.go:171","msg":"trace[1559138380] transaction","detail":"{read_only:false; response_revision:26141; number_of_response:1; }","duration":"260.448638ms","start":"2026-08-11T12:06:43.017313Z","end":"2026-08-11T12:06:43.277762Z","steps":["trace[1559138380] 'process raft request' (duration: 260.204683ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:06:43.277985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.379223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-11T12:06:43.278059Z","caller":"traceutil/trace.go:171","msg":"trace[301521178] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26141; }","duration":"239.504256ms","start":"2026-08-11T12:06:43.038534Z","end":"2026-08-11T12:06:43.278038Z","steps":["trace[301521178] 'agreement among raft nodes before linearized reading' (duration: 239.26735ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:06:43.737257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.235395ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T12:06:43.737753Z","caller":"traceutil/trace.go:171","msg":"trace[168919307] transaction","detail":"{read_only:false; response_revision:26144; number_of_response:1; }","duration":"204.405256ms","start":"2026-08-11T12:06:43.533157Z","end":"2026-08-11T12:06:43.737562Z","steps":["trace[168919307] 'process raft request' (duration: 45.772021ms)","trace[168919307] 'compare' (duration: 158.129523ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:06:43.953133Z","caller":"traceutil/trace.go:171","msg":"trace[229012412] transaction","detail":"{read_only:false; response_revision:26145; number_of_response:1; }","duration":"129.372498ms","start":"2026-08-11T12:06:43.823729Z","end":"2026-08-11T12:06:43.953102Z","steps":["trace[229012412] 'process raft request' (duration: 44.768576ms)","trace[229012412] 'compare' (duration: 84.439338ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:06:43.953274Z","caller":"traceutil/trace.go:171","msg":"trace[731713209] transaction","detail":"{read_only:false; response_revision:26146; number_of_response:1; }","duration":"113.037538ms","start":"2026-08-11T12:06:43.8402Z","end":"2026-08-11T12:06:43.953238Z","steps":["trace[731713209] 'process raft request' (duration: 112.867994ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:06:44.039595Z","caller":"traceutil/trace.go:171","msg":"trace[1144982352] transaction","detail":"{read_only:false; response_revision:26147; number_of_response:1; }","duration":"169.4288ms","start":"2026-08-11T12:06:43.870133Z","end":"2026-08-11T12:06:44.039562Z","steps":["trace[1144982352] 'process raft request' (duration: 169.259736ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:06:44.277175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.875353ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T12:06:44.277445Z","caller":"traceutil/trace.go:171","msg":"trace[2085419805] transaction","detail":"{read_only:false; response_revision:26151; number_of_response:1; }","duration":"232.489054ms","start":"2026-08-11T12:06:44.044937Z","end":"2026-08-11T12:06:44.277426Z","steps":["trace[2085419805] 'process raft request' (duration: 232.448003ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:06:44.277469Z","caller":"traceutil/trace.go:171","msg":"trace[396005168] transaction","detail":"{read_only:false; response_revision:26148; number_of_response:1; }","duration":"233.425267ms","start":"2026-08-11T12:06:44.044002Z","end":"2026-08-11T12:06:44.277428Z","steps":["trace[396005168] 'process raft request' (duration: 49.213455ms)","trace[396005168] 'compare' (duration: 183.564676ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:06:44.277486Z","caller":"traceutil/trace.go:171","msg":"trace[847956436] transaction","detail":"{read_only:false; response_revision:26149; number_of_response:1; }","duration":"233.401567ms","start":"2026-08-11T12:06:44.044054Z","end":"2026-08-11T12:06:44.277456Z","steps":["trace[847956436] 'process raft request' (duration: 233.231153ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:06:44.277797Z","caller":"traceutil/trace.go:171","msg":"trace[84065089] transaction","detail":"{read_only:false; response_revision:26150; number_of_response:1; }","duration":"233.694424ms","start":"2026-08-11T12:06:44.044056Z","end":"2026-08-11T12:06:44.277751Z","steps":["trace[84065089] 'process raft request' (duration: 233.292984ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:06:46.393559Z","caller":"traceutil/trace.go:171","msg":"trace[1943234658] transaction","detail":"{read_only:false; response_revision:26163; number_of_response:1; }","duration":"108.815655ms","start":"2026-08-11T12:06:46.284714Z","end":"2026-08-11T12:06:46.39353Z","steps":["trace[1943234658] 'process raft request' (duration: 108.635801ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:06:46.625586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.510397ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T12:06:46.625839Z","caller":"traceutil/trace.go:171","msg":"trace[1379629426] transaction","detail":"{read_only:false; response_revision:26164; number_of_response:1; }","duration":"339.627958ms","start":"2026-08-11T12:06:46.286123Z","end":"2026-08-11T12:06:46.62575Z","steps":["trace[1379629426] 'process raft request' (duration: 188.871435ms)","trace[1379629426] 'compare' (duration: 150.117067ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:06:46.62622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:06:46.286088Z","time spent":"339.916835ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T12:06:46.626783Z","caller":"traceutil/trace.go:171","msg":"trace[1863789351] transaction","detail":"{read_only:false; response_revision:26165; number_of_response:1; }","duration":"339.672789ms","start":"2026-08-11T12:06:46.287055Z","end":"2026-08-11T12:06:46.626727Z","steps":["trace[1863789351] 'process raft request' (duration: 338.625563ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:06:46.626837Z","caller":"traceutil/trace.go:171","msg":"trace[834256057] transaction","detail":"{read_only:false; response_revision:26166; number_of_response:1; }","duration":"338.678064ms","start":"2026-08-11T12:06:46.288127Z","end":"2026-08-11T12:06:46.626805Z","steps":["trace[834256057] 'process raft request' (duration: 338.454799ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:06:46.626944Z","caller":"traceutil/trace.go:171","msg":"trace[153099858] transaction","detail":"{read_only:false; response_revision:26167; number_of_response:1; }","duration":"338.11147ms","start":"2026-08-11T12:06:46.288804Z","end":"2026-08-11T12:06:46.626916Z","steps":["trace[153099858] 'process raft request' (duration: 337.861244ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:06:46.626997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:06:46.288103Z","time spent":"338.810248ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T12:06:46.627076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:06:46.288791Z","time spent":"338.218742ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T12:06:46.627001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:06:46.287046Z","time spent":"339.851233ms","remote":"127.0.0.1:46106","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-08-11T12:06:46.627388Z","caller":"traceutil/trace.go:171","msg":"trace[559234807] transaction","detail":"{read_only:false; response_revision:26168; number_of_response:1; }","duration":"314.717907ms","start":"2026-08-11T12:06:46.312655Z","end":"2026-08-11T12:06:46.627373Z","steps":["trace[559234807] 'process raft request' (duration: 314.142203ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:06:46.627329Z","caller":"traceutil/trace.go:171","msg":"trace[15031841] linearizableReadLoop","detail":"{readStateIndex:27022; appliedIndex:27017; }","duration":"335.167298ms","start":"2026-08-11T12:06:46.292025Z","end":"2026-08-11T12:06:46.627193Z","steps":["trace[15031841] 'read index received' (duration: 101.320831ms)","trace[15031841] 'applied index is now lower than readState.Index' (duration: 233.844747ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:06:46.627453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:06:46.31263Z","time spent":"314.791659ms","remote":"127.0.0.1:46106","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-08-11T12:06:46.627553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.523177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T12:06:46.627595Z","caller":"traceutil/trace.go:171","msg":"trace[2088791748] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26168; }","duration":"335.584249ms","start":"2026-08-11T12:06:46.292Z","end":"2026-08-11T12:06:46.627584Z","steps":["trace[2088791748] 'agreement among raft nodes before linearized reading' (duration: 335.491096ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:06:46.627621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:06:46.291985Z","time spent":"335.63025ms","remote":"127.0.0.1:46310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-08-11T12:06:46.891614Z","caller":"traceutil/trace.go:171","msg":"trace[1458221925] transaction","detail":"{read_only:false; response_revision:26169; number_of_response:1; }","duration":"197.56945ms","start":"2026-08-11T12:06:46.694015Z","end":"2026-08-11T12:06:46.891585Z","steps":["trace[1458221925] 'process raft request' (duration: 187.78959ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:06:46.974645Z","caller":"traceutil/trace.go:171","msg":"trace[382641607] transaction","detail":"{read_only:false; response_revision:26170; number_of_response:1; }","duration":"143.32293ms","start":"2026-08-11T12:06:46.83128Z","end":"2026-08-11T12:06:46.974603Z","steps":["trace[382641607] 'process raft request' (duration: 142.674204ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:07:02.875864Z","caller":"traceutil/trace.go:171","msg":"trace[696272687] linearizableReadLoop","detail":"{readStateIndex:27153; appliedIndex:27152; }","duration":"179.655811ms","start":"2026-08-11T12:07:02.696186Z","end":"2026-08-11T12:07:02.875842Z","steps":["trace[696272687] 'read index received' (duration: 179.449516ms)","trace[696272687] 'applied index is now lower than readState.Index' (duration: 205.335µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:07:02.876233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.018579ms","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-08-11T12:07:02.876285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.015715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-11T12:07:02.876331Z","caller":"traceutil/trace.go:171","msg":"trace[948319523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26294; }","duration":"180.174803ms","start":"2026-08-11T12:07:02.696133Z","end":"2026-08-11T12:07:02.876308Z","steps":["trace[948319523] 'agreement among raft nodes before linearized reading' (duration: 179.864986ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:07:02.876346Z","caller":"traceutil/trace.go:171","msg":"trace[1965979359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26294; }","duration":"139.120748ms","start":"2026-08-11T12:07:02.737214Z","end":"2026-08-11T12:07:02.876334Z","steps":["trace[1965979359] 'agreement among raft nodes before linearized reading' (duration: 138.954174ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:07:02.876514Z","caller":"traceutil/trace.go:171","msg":"trace[63025169] transaction","detail":"{read_only:false; response_revision:26294; number_of_response:1; }","duration":"195.670823ms","start":"2026-08-11T12:07:02.680825Z","end":"2026-08-11T12:07:02.876496Z","steps":["trace[63025169] 'process raft request' (duration: 194.884494ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:07:02.876689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.546186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-11T12:07:02.876738Z","caller":"traceutil/trace.go:171","msg":"trace[345460961] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26294; }","duration":"132.608517ms","start":"2026-08-11T12:07:02.744115Z","end":"2026-08-11T12:07:02.876724Z","steps":["trace[345460961] 'agreement among raft nodes before linearized reading' (duration: 132.440553ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:07:02.876868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.826592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-11T12:07:02.876899Z","caller":"traceutil/trace.go:171","msg":"trace[1289259140] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26294; }","duration":"132.895784ms","start":"2026-08-11T12:07:02.743995Z","end":"2026-08-11T12:07:02.876891Z","steps":["trace[1289259140] 'agreement among raft nodes before linearized reading' (duration: 132.816542ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:07:03.197471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.759838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T12:07:03.197993Z","caller":"traceutil/trace.go:171","msg":"trace[1517966388] transaction","detail":"{read_only:false; response_revision:26296; number_of_response:1; }","duration":"317.032996ms","start":"2026-08-11T12:07:02.880918Z","end":"2026-08-11T12:07:03.197951Z","steps":["trace[1517966388] 'process raft request' (duration: 148.671742ms)","trace[1517966388] 'compare' (duration: 167.624295ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:07:03.198173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:07:02.88091Z","time spent":"317.168889ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T12:07:03.198243Z","caller":"traceutil/trace.go:171","msg":"trace[389628008] transaction","detail":"{read_only:false; response_revision:26297; number_of_response:1; }","duration":"317.246071ms","start":"2026-08-11T12:07:02.880964Z","end":"2026-08-11T12:07:03.19821Z","steps":["trace[389628008] 'process raft request' (duration: 316.604634ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:07:03.19828Z","caller":"traceutil/trace.go:171","msg":"trace[1078476709] transaction","detail":"{read_only:false; response_revision:26298; number_of_response:1; }","duration":"317.045324ms","start":"2026-08-11T12:07:02.881178Z","end":"2026-08-11T12:07:03.198224Z","steps":["trace[1078476709] 'process raft request' (duration: 316.830179ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:07:03.198378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:07:02.880959Z","time spent":"317.346993ms","remote":"127.0.0.1:46106","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-08-11T12:07:03.198365Z","caller":"traceutil/trace.go:171","msg":"trace[1403825929] linearizableReadLoop","detail":"{readStateIndex:27157; appliedIndex:27153; }","duration":"301.029622ms","start":"2026-08-11T12:07:02.897292Z","end":"2026-08-11T12:07:03.198322Z","steps":["trace[1403825929] 'read index received' (duration: 46.777286ms)","trace[1403825929] 'applied index is now lower than readState.Index' (duration: 254.244516ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:07:03.198524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:07:02.881172Z","time spent":"317.201399ms","remote":"127.0.0.1:46106","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-08-11T12:07:03.198692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.367731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-11T12:07:03.198746Z","caller":"traceutil/trace.go:171","msg":"trace[1024536432] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:26298; }","duration":"301.505184ms","start":"2026-08-11T12:07:02.897226Z","end":"2026-08-11T12:07:03.198732Z","steps":["trace[1024536432] 'agreement among raft nodes before linearized reading' (duration: 301.166256ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:07:03.198758Z","caller":"traceutil/trace.go:171","msg":"trace[1946159593] transaction","detail":"{read_only:false; response_revision:26299; number_of_response:1; }","duration":"172.075135ms","start":"2026-08-11T12:07:03.026663Z","end":"2026-08-11T12:07:03.198738Z","steps":["trace[1946159593] 'process raft request' (duration: 171.420479ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:07:03.198803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:07:02.897205Z","time spent":"301.588197ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":37,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-08-11T12:07:03.475889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.79025ms","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-08-11T12:07:03.475987Z","caller":"traceutil/trace.go:171","msg":"trace[520681169] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:26299; }","duration":"165.957804ms","start":"2026-08-11T12:07:03.31001Z","end":"2026-08-11T12:07:03.475968Z","steps":["trace[520681169] 'count revisions from in-memory index tree' (duration: 165.694218ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:07:03.65684Z","caller":"traceutil/trace.go:171","msg":"trace[581645425] transaction","detail":"{read_only:false; response_revision:26301; number_of_response:1; }","duration":"172.130585ms","start":"2026-08-11T12:07:03.484674Z","end":"2026-08-11T12:07:03.656805Z","steps":["trace[581645425] 'process raft request' (duration: 131.968652ms)","trace[581645425] 'compare' (duration: 39.896747ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:08:04.084398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.206572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-11T12:08:04.084641Z","caller":"traceutil/trace.go:171","msg":"trace[48284123] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26917; }","duration":"115.462928ms","start":"2026-08-11T12:08:03.969156Z","end":"2026-08-11T12:08:04.084619Z","steps":["trace[48284123] 'range keys from in-memory index tree' (duration: 115.009867ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:08:04.230303Z","caller":"traceutil/trace.go:171","msg":"trace[2069272329] transaction","detail":"{read_only:false; response_revision:26918; number_of_response:1; }","duration":"207.164613ms","start":"2026-08-11T12:08:04.023111Z","end":"2026-08-11T12:08:04.230275Z","steps":["trace[2069272329] 'process raft request' (duration: 207.0167ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:08:04.249018Z","caller":"traceutil/trace.go:171","msg":"trace[2142908627] linearizableReadLoop","detail":"{readStateIndex:27791; appliedIndex:27789; }","duration":"108.912308ms","start":"2026-08-11T12:08:04.140079Z","end":"2026-08-11T12:08:04.248992Z","steps":["trace[2142908627] 'read index received' (duration: 90.168259ms)","trace[2142908627] 'applied index is now lower than readState.Index' (duration: 18.742819ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:08:04.249151Z","caller":"traceutil/trace.go:171","msg":"trace[234122543] transaction","detail":"{read_only:false; response_revision:26919; number_of_response:1; }","duration":"160.111331ms","start":"2026-08-11T12:08:04.089025Z","end":"2026-08-11T12:08:04.249136Z","steps":["trace[234122543] 'process raft request' (duration: 159.829905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:08:04.249422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.300687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T12:08:04.249727Z","caller":"traceutil/trace.go:171","msg":"trace[2011408585] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:26919; }","duration":"109.476411ms","start":"2026-08-11T12:08:04.14003Z","end":"2026-08-11T12:08:04.249507Z","steps":["trace[2011408585] 'agreement among raft nodes before linearized reading' (duration: 109.214445ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:08:04.269217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.789854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T12:08:04.269389Z","caller":"traceutil/trace.go:171","msg":"trace[1889903701] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26920; }","duration":"129.01041ms","start":"2026-08-11T12:08:04.140359Z","end":"2026-08-11T12:08:04.269369Z","steps":["trace[1889903701] 'agreement among raft nodes before linearized reading' (duration: 128.765274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:08:04.269765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.126722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"warn","ts":"2026-08-11T12:08:04.269746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.676451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T12:08:04.269845Z","caller":"traceutil/trace.go:171","msg":"trace[1600549742] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:26920; }","duration":"129.220655ms","start":"2026-08-11T12:08:04.140614Z","end":"2026-08-11T12:08:04.269835Z","steps":["trace[1600549742] 'agreement among raft nodes before linearized reading' (duration: 129.050601ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:08:04.269917Z","caller":"traceutil/trace.go:171","msg":"trace[132586608] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26920; }","duration":"128.895657ms","start":"2026-08-11T12:08:04.140985Z","end":"2026-08-11T12:08:04.269881Z","steps":["trace[132586608] 'agreement among raft nodes before linearized reading' (duration: 128.584279ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:08:05.396491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.178289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-11T12:08:05.396577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.643229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-11T12:08:05.396639Z","caller":"traceutil/trace.go:171","msg":"trace[2025869735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26923; }","duration":"175.385664ms","start":"2026-08-11T12:08:05.221226Z","end":"2026-08-11T12:08:05.396611Z","steps":["trace[2025869735] 'range keys from in-memory index tree' (duration: 175.084148ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:08:05.396657Z","caller":"traceutil/trace.go:171","msg":"trace[1920583488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26923; }","duration":"161.737061ms","start":"2026-08-11T12:08:05.2349Z","end":"2026-08-11T12:08:05.396637Z","steps":["trace[1920583488] 'range keys from in-memory index tree' (duration: 161.451624ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:08:05.597282Z","caller":"traceutil/trace.go:171","msg":"trace[1629056935] transaction","detail":"{read_only:false; response_revision:26924; number_of_response:1; }","duration":"195.769784ms","start":"2026-08-11T12:08:05.401479Z","end":"2026-08-11T12:08:05.597249Z","steps":["trace[1629056935] 'process raft request' (duration: 195.58451ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:08:05.657823Z","caller":"traceutil/trace.go:171","msg":"trace[1496616540] linearizableReadLoop","detail":"{readStateIndex:27797; appliedIndex:27795; }","duration":"234.073794ms","start":"2026-08-11T12:08:05.423714Z","end":"2026-08-11T12:08:05.657788Z","steps":["trace[1496616540] 'read index received' (duration: 173.415538ms)","trace[1496616540] 'applied index is now lower than readState.Index' (duration: 60.656736ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:08:05.658005Z","caller":"traceutil/trace.go:171","msg":"trace[1716751270] transaction","detail":"{read_only:false; response_revision:26925; number_of_response:1; }","duration":"247.669236ms","start":"2026-08-11T12:08:05.410272Z","end":"2026-08-11T12:08:05.657941Z","steps":["trace[1716751270] 'process raft request' (duration: 247.317768ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:08:05.658236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.066534ms","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-08-11T12:08:05.65822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.949921ms","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-08-11T12:08:05.65822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.854312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-11T12:08:05.658267Z","caller":"traceutil/trace.go:171","msg":"trace[991230575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26925; }","duration":"132.091135ms","start":"2026-08-11T12:08:05.526164Z","end":"2026-08-11T12:08:05.658255Z","steps":["trace[991230575] 'agreement among raft nodes before linearized reading' (duration: 131.938161ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:08:05.658282Z","caller":"traceutil/trace.go:171","msg":"trace[961061622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26925; }","duration":"132.074444ms","start":"2026-08-11T12:08:05.526192Z","end":"2026-08-11T12:08:05.658266Z","steps":["trace[961061622] 'agreement among raft nodes before linearized reading' (duration: 131.90143ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:08:05.658301Z","caller":"traceutil/trace.go:171","msg":"trace[956317260] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26925; }","duration":"136.984686ms","start":"2026-08-11T12:08:05.521295Z","end":"2026-08-11T12:08:05.658279Z","steps":["trace[956317260] 'agreement among raft nodes before linearized reading' (duration: 136.74694ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:08:05.658053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.288209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T12:08:05.658393Z","caller":"traceutil/trace.go:171","msg":"trace[1807516061] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:26925; }","duration":"234.704278ms","start":"2026-08-11T12:08:05.423677Z","end":"2026-08-11T12:08:05.658381Z","steps":["trace[1807516061] 'agreement among raft nodes before linearized reading' (duration: 234.283638ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:08:05.804464Z","caller":"traceutil/trace.go:171","msg":"trace[1366680367] transaction","detail":"{read_only:false; response_revision:26927; number_of_response:1; }","duration":"141.015814ms","start":"2026-08-11T12:08:05.663423Z","end":"2026-08-11T12:08:05.804439Z","steps":["trace[1366680367] 'process raft request' (duration: 79.759513ms)","trace[1366680367] 'compare' (duration: 61.079626ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:08:05.804589Z","caller":"traceutil/trace.go:171","msg":"trace[1719953702] linearizableReadLoop","detail":"{readStateIndex:27800; appliedIndex:27797; }","duration":"139.057506ms","start":"2026-08-11T12:08:05.665508Z","end":"2026-08-11T12:08:05.804565Z","steps":["trace[1719953702] 'read index received' (duration: 29.455142ms)","trace[1719953702] 'applied index is now lower than readState.Index' (duration: 109.601274ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:08:05.80475Z","caller":"traceutil/trace.go:171","msg":"trace[2084893354] transaction","detail":"{read_only:false; response_revision:26928; number_of_response:1; }","duration":"141.243309ms","start":"2026-08-11T12:08:05.663491Z","end":"2026-08-11T12:08:05.804734Z","steps":["trace[2084893354] 'process raft request' (duration: 140.906101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:08:05.804791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.283082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-08-11T12:08:05.80482Z","caller":"traceutil/trace.go:171","msg":"trace[962485036] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:26928; }","duration":"139.370054ms","start":"2026-08-11T12:08:05.665441Z","end":"2026-08-11T12:08:05.804811Z","steps":["trace[962485036] 'agreement among raft nodes before linearized reading' (duration: 139.19917ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:08:06.137158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.332706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T12:08:06.137526Z","caller":"traceutil/trace.go:171","msg":"trace[1624167600] transaction","detail":"{read_only:false; response_revision:26929; number_of_response:1; }","duration":"436.11392ms","start":"2026-08-11T12:08:05.701375Z","end":"2026-08-11T12:08:06.137489Z","steps":["trace[1624167600] 'process raft request' (duration: 204.357974ms)","trace[1624167600] 'compare' (duration: 231.171362ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:08:06.137689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:08:05.701337Z","time spent":"436.265684ms","remote":"127.0.0.1:46106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T12:08:06.137819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:08:05.805899Z","time spent":"331.919128ms","remote":"127.0.0.1:45888","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-11T12:08:06.137632Z","caller":"traceutil/trace.go:171","msg":"trace[1590557124] linearizableReadLoop","detail":"{readStateIndex:27803; appliedIndex:27800; }","duration":"178.910901ms","start":"2026-08-11T12:08:05.958702Z","end":"2026-08-11T12:08:06.137613Z","steps":["trace[1590557124] 'read index received' (duration: 98.388879ms)","trace[1590557124] 'applied index is now lower than readState.Index' (duration: 80.520812ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:08:06.13798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.27085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-11T12:08:06.138024Z","caller":"traceutil/trace.go:171","msg":"trace[2145690915] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:26930; }","duration":"179.347012ms","start":"2026-08-11T12:08:05.958664Z","end":"2026-08-11T12:08:06.138011Z","steps":["trace[2145690915] 'agreement among raft nodes before linearized reading' (duration: 179.228329ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:08:06.138016Z","caller":"traceutil/trace.go:171","msg":"trace[1580163987] transaction","detail":"{read_only:false; response_revision:26930; number_of_response:1; }","duration":"260.101711ms","start":"2026-08-11T12:08:05.877769Z","end":"2026-08-11T12:08:06.13787Z","steps":["trace[1580163987] 'process raft request' (duration: 259.749992ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:08:06.393832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.869131ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-11T12:08:06.39406Z","caller":"traceutil/trace.go:171","msg":"trace[440725611] transaction","detail":"{read_only:false; response_revision:26933; number_of_response:1; }","duration":"119.158939ms","start":"2026-08-11T12:08:06.274871Z","end":"2026-08-11T12:08:06.39403Z","steps":["trace[440725611] 'process raft request' (duration: 119.066816ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:08:06.394131Z","caller":"traceutil/trace.go:171","msg":"trace[907005944] linearizableReadLoop","detail":"{readStateIndex:27805; appliedIndex:27804; }","duration":"157.99763ms","start":"2026-08-11T12:08:06.236103Z","end":"2026-08-11T12:08:06.394101Z","steps":["trace[907005944] 'read index received' (duration: 38.724769ms)","trace[907005944] 'applied index is now lower than readState.Index' (duration: 119.270621ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:08:06.394149Z","caller":"traceutil/trace.go:171","msg":"trace[195045081] transaction","detail":"{read_only:false; response_revision:26932; number_of_response:1; }","duration":"158.382129ms","start":"2026-08-11T12:08:06.235739Z","end":"2026-08-11T12:08:06.394121Z","steps":["trace[195045081] 'process raft request' (duration: 39.127688ms)","trace[195045081] 'compare' (duration: 118.656575ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:08:06.394364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.248156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-08-11T12:08:06.394407Z","caller":"traceutil/trace.go:171","msg":"trace[1453253556] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:26933; }","duration":"158.328759ms","start":"2026-08-11T12:08:06.236067Z","end":"2026-08-11T12:08:06.394396Z","steps":["trace[1453253556] 'agreement among raft nodes before linearized reading' (duration: 158.160834ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:08:06.440484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.568215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T12:08:06.440574Z","caller":"traceutil/trace.go:171","msg":"trace[908656286] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26934; }","duration":"165.712748ms","start":"2026-08-11T12:08:06.274841Z","end":"2026-08-11T12:08:06.440554Z","steps":["trace[908656286] 'agreement among raft nodes before linearized reading' (duration: 165.547934ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:08:06.440613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.183855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"} {"level":"warn","ts":"2026-08-11T12:08:06.440657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.066852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T12:08:06.440696Z","caller":"traceutil/trace.go:171","msg":"trace[405923077] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:26934; }","duration":"165.288908ms","start":"2026-08-11T12:08:06.27538Z","end":"2026-08-11T12:08:06.440669Z","steps":["trace[405923077] 'agreement among raft nodes before linearized reading' (duration: 165.073823ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:08:06.440671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.166809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T12:08:06.440748Z","caller":"traceutil/trace.go:171","msg":"trace[453077308] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:26934; }","duration":"187.199285ms","start":"2026-08-11T12:08:06.253531Z","end":"2026-08-11T12:08:06.44073Z","steps":["trace[453077308] 'agreement among raft nodes before linearized reading' (duration: 186.966449ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:08:06.44081Z","caller":"traceutil/trace.go:171","msg":"trace[1988363303] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26934; }","duration":"166.359964ms","start":"2026-08-11T12:08:06.274398Z","end":"2026-08-11T12:08:06.440758Z","steps":["trace[1988363303] 'agreement among raft nodes before linearized reading' (duration: 166.158849ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:08:06.440949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.546554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-11T12:08:06.441013Z","caller":"traceutil/trace.go:171","msg":"trace[1245620626] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26934; }","duration":"187.693487ms","start":"2026-08-11T12:08:06.253302Z","end":"2026-08-11T12:08:06.440996Z","steps":["trace[1245620626] 'agreement among raft nodes before linearized reading' (duration: 187.230656ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:08:06.560345Z","caller":"traceutil/trace.go:171","msg":"trace[1599689653] linearizableReadLoop","detail":"{readStateIndex:27808; appliedIndex:27807; }","duration":"120.007629ms","start":"2026-08-11T12:08:06.440314Z","end":"2026-08-11T12:08:06.560322Z","steps":["trace[1599689653] 'read index received' (duration: 119.901657ms)","trace[1599689653] 'applied index is now lower than readState.Index' (duration: 104.632µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:08:06.560419Z","caller":"traceutil/trace.go:171","msg":"trace[789975973] transaction","detail":"{read_only:false; response_revision:26935; number_of_response:1; }","duration":"143.267298ms","start":"2026-08-11T12:08:06.417101Z","end":"2026-08-11T12:08:06.560368Z","steps":["trace[789975973] 'process raft request' (duration: 143.060703ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:08:06.560614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.057018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-11T12:08:06.560669Z","caller":"traceutil/trace.go:171","msg":"trace[865208146] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:26935; }","duration":"164.169631ms","start":"2026-08-11T12:08:06.396486Z","end":"2026-08-11T12:08:06.560656Z","steps":["trace[865208146] 'agreement among raft nodes before linearized reading' (duration: 163.951446ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:08:06.585749Z","caller":"traceutil/trace.go:171","msg":"trace[618710875] transaction","detail":"{read_only:false; response_revision:26936; number_of_response:1; }","duration":"141.347941ms","start":"2026-08-11T12:08:06.444378Z","end":"2026-08-11T12:08:06.585726Z","steps":["trace[618710875] 'process raft request' (duration: 141.002793ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:09:00.073394Z","caller":"traceutil/trace.go:171","msg":"trace[1360104278] transaction","detail":"{read_only:false; response_revision:27422; number_of_response:1; }","duration":"124.494008ms","start":"2026-08-11T12:08:59.948872Z","end":"2026-08-11T12:09:00.073366Z","steps":["trace[1360104278] 'process raft request' (duration: 84.383566ms)","trace[1360104278] 'compare' (duration: 39.889907ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:09:00.227334Z","caller":"traceutil/trace.go:171","msg":"trace[2126858343] transaction","detail":"{read_only:false; response_revision:27424; number_of_response:1; }","duration":"149.851709ms","start":"2026-08-11T12:09:00.077452Z","end":"2026-08-11T12:09:00.227303Z","steps":["trace[2126858343] 'process raft request' (duration: 116.934213ms)","trace[2126858343] 'compare' (duration: 32.755223ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:09:00.227356Z","caller":"traceutil/trace.go:171","msg":"trace[972716459] linearizableReadLoop","detail":"{readStateIndex:28308; appliedIndex:28307; }","duration":"132.148276ms","start":"2026-08-11T12:09:00.095186Z","end":"2026-08-11T12:09:00.227334Z","steps":["trace[972716459] 'read index received' (duration: 99.195139ms)","trace[972716459] 'applied index is now lower than readState.Index' (duration: 32.952277ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:09:00.227512Z","caller":"traceutil/trace.go:171","msg":"trace[1041377689] transaction","detail":"{read_only:false; response_revision:27425; number_of_response:1; }","duration":"127.077012ms","start":"2026-08-11T12:09:00.100424Z","end":"2026-08-11T12:09:00.227501Z","steps":["trace[1041377689] 'process raft request' (duration: 126.876997ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:09:00.227714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.922032ms","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-08-11T12:09:00.228985Z","caller":"traceutil/trace.go:171","msg":"trace[1822295536] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27425; }","duration":"137.075538ms","start":"2026-08-11T12:09:00.090728Z","end":"2026-08-11T12:09:00.227804Z","steps":["trace[1822295536] 'agreement among raft nodes before linearized reading' (duration: 136.691158ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:09:00.771043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.175603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-11T12:09:00.771097Z","caller":"traceutil/trace.go:171","msg":"trace[1350847870] transaction","detail":"{read_only:false; response_revision:27430; number_of_response:1; }","duration":"114.482834ms","start":"2026-08-11T12:09:00.656573Z","end":"2026-08-11T12:09:00.771055Z","steps":["trace[1350847870] 'process raft request' (duration: 114.101725ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:09:00.771155Z","caller":"traceutil/trace.go:171","msg":"trace[1581022455] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27429; }","duration":"198.327496ms","start":"2026-08-11T12:09:00.572803Z","end":"2026-08-11T12:09:00.771131Z","steps":["trace[1581022455] 'range keys from in-memory index tree' (duration: 198.08075ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:09:00.771159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.993268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:1 size:21345"} {"level":"info","ts":"2026-08-11T12:09:00.771229Z","caller":"traceutil/trace.go:171","msg":"trace[1681407515] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:1; response_revision:27429; }","duration":"171.09682ms","start":"2026-08-11T12:09:00.600111Z","end":"2026-08-11T12:09:00.771208Z","steps":["trace[1681407515] 'range keys from in-memory index tree' (duration: 170.746972ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:10:53.018897Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25676} {"level":"info","ts":"2026-08-11T12:10:53.18612Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25676,"took":"153.826403ms","hash":1772004844,"current-db-size-bytes":59482112,"current-db-size":"60 MB","current-db-size-in-use-bytes":36069376,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-08-11T12:10:53.186202Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1772004844,"revision":25676,"compact-revision":22215} {"level":"info","ts":"2026-08-11T12:11:50.928812Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-11T12:11:50.933537Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-11T12:11:50.933662Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-11T12:15:53.024608Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28637} {"level":"info","ts":"2026-08-11T12:15:53.176486Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28637,"took":"146.955265ms","hash":1577655665,"current-db-size-bytes":59482112,"current-db-size":"60 MB","current-db-size-in-use-bytes":32034816,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-11T12:15:53.17656Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1577655665,"revision":28637,"compact-revision":25676} {"level":"info","ts":"2026-08-11T12:20:53.038135Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30880} {"level":"info","ts":"2026-08-11T12:20:53.16984Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30880,"took":"126.268716ms","hash":3127722099,"current-db-size-bytes":59482112,"current-db-size":"60 MB","current-db-size-in-use-bytes":30687232,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-11T12:20:53.169918Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3127722099,"revision":30880,"compact-revision":28637} {"level":"info","ts":"2026-08-11T12:25:53.044258Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33132} {"level":"info","ts":"2026-08-11T12:25:53.186271Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33132,"took":"137.43409ms","hash":3314814757,"current-db-size-bytes":60526592,"current-db-size":"60 MB","current-db-size-in-use-bytes":31047680,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-11T12:25:53.186344Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3314814757,"revision":33132,"compact-revision":30880} {"level":"info","ts":"2026-08-11T12:30:53.05118Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35431} {"level":"info","ts":"2026-08-11T12:30:53.200731Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35431,"took":"145.846873ms","hash":389962440,"current-db-size-bytes":60526592,"current-db-size":"60 MB","current-db-size-in-use-bytes":32157696,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-11T12:30:53.200859Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":389962440,"revision":35431,"compact-revision":33132} {"level":"info","ts":"2026-08-11T12:33:20.943775Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-11T12:33:20.949833Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-08-11T12:33:20.949953Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-08-11T12:35:53.058304Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37662} {"level":"info","ts":"2026-08-11T12:35:53.173758Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37662,"took":"111.882ms","hash":1549415813,"current-db-size-bytes":60948480,"current-db-size":"61 MB","current-db-size-in-use-bytes":32423936,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-11T12:35:53.17384Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1549415813,"revision":37662,"compact-revision":35431}