{"level":"warn","ts":"2026-08-05T11:08:25.863292Z","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-05T11:08:25.863367Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.83:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.83:2380","--initial-cluster=instance=https://199.19.213.83:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.83:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.83: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-05T11:08:25.863428Z","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-05T11:08:25.863437Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.83:2380"]} {"level":"info","ts":"2026-08-05T11:08:25.863457Z","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-05T11:08:25.864088Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.83:2379"]} {"level":"info","ts":"2026-08-05T11:08:25.864156Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.83:2380"],"listen-peer-urls":["https://199.19.213.83:2380"],"advertise-client-urls":["https://199.19.213.83:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.83:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.83: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-05T11:08:25.868719Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.381796ms"} {"level":"info","ts":"2026-08-05T11:08:25.878764Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5887d25060a34f1b","cluster-id":"8c4d9ec591b76d84"} {"level":"info","ts":"2026-08-05T11:08:25.878929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b switched to configuration voters=()"} {"level":"info","ts":"2026-08-05T11:08:25.879043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became follower at term 0"} {"level":"info","ts":"2026-08-05T11:08:25.879068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5887d25060a34f1b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-05T11:08:25.879092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became follower at term 1"} {"level":"info","ts":"2026-08-05T11:08:25.879217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b switched to configuration voters=(6379298639854128923)"} {"level":"warn","ts":"2026-08-05T11:08:25.8896Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-05T11:08:25.893104Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-05T11:08:25.894757Z","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-05T11:08:25.896774Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5887d25060a34f1b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-05T11:08:25.897717Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5887d25060a34f1b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-05T11:08:25.897773Z","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-05T11:08:25.897865Z","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-05T11:08:25.897874Z","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-05T11:08:25.89851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b switched to configuration voters=(6379298639854128923)"} {"level":"info","ts":"2026-08-05T11:08:25.898621Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8c4d9ec591b76d84","local-member-id":"5887d25060a34f1b","added-peer-id":"5887d25060a34f1b","added-peer-peer-urls":["https://199.19.213.83:2380"]} {"level":"info","ts":"2026-08-05T11:08:25.899917Z","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-05T11:08:25.899961Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.83:2380"} {"level":"info","ts":"2026-08-05T11:08:25.899974Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.83:2380"} {"level":"info","ts":"2026-08-05T11:08:25.900655Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5887d25060a34f1b","initial-advertise-peer-urls":["https://199.19.213.83:2380"],"listen-peer-urls":["https://199.19.213.83:2380"],"advertise-client-urls":["https://199.19.213.83:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.83:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-05T11:08:25.900701Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-05T11:08:26.880318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b is starting a new election at term 1"} {"level":"info","ts":"2026-08-05T11:08:26.880388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became pre-candidate at term 1"} {"level":"info","ts":"2026-08-05T11:08:26.880406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b received MsgPreVoteResp from 5887d25060a34f1b at term 1"} {"level":"info","ts":"2026-08-05T11:08:26.880419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became candidate at term 2"} {"level":"info","ts":"2026-08-05T11:08:26.880426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b received MsgVoteResp from 5887d25060a34f1b at term 2"} {"level":"info","ts":"2026-08-05T11:08:26.880437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became leader at term 2"} {"level":"info","ts":"2026-08-05T11:08:26.880446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5887d25060a34f1b elected leader 5887d25060a34f1b at term 2"} {"level":"info","ts":"2026-08-05T11:08:26.881359Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:08:26.882038Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T11:08:26.882039Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5887d25060a34f1b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.83:2379]}","request-path":"/0/members/5887d25060a34f1b/attributes","cluster-id":"8c4d9ec591b76d84","publish-timeout":"7s"} {"level":"info","ts":"2026-08-05T11:08:26.882085Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T11:08:26.88233Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-05T11:08:26.882352Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-05T11:08:26.884463Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.83:2379"} {"level":"info","ts":"2026-08-05T11:08:26.885067Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-05T11:08:26.893521Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8c4d9ec591b76d84","local-member-id":"5887d25060a34f1b","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:08:26.893964Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:08:26.894034Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:08:41.375622Z","caller":"traceutil/trace.go:171","msg":"trace[515705960] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"118.290906ms","start":"2026-08-05T11:08:41.257304Z","end":"2026-08-05T11:08:41.375595Z","steps":["trace[515705960] 'process raft request' (duration: 118.098962ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:09:02.247518Z","caller":"traceutil/trace.go:171","msg":"trace[1724323164] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"169.73357ms","start":"2026-08-05T11:09:02.077767Z","end":"2026-08-05T11:09:02.247501Z","steps":["trace[1724323164] 'process raft request' (duration: 165.64586ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:09:08.003191Z","caller":"traceutil/trace.go:171","msg":"trace[1733075853] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"150.039304ms","start":"2026-08-05T11:09:07.853113Z","end":"2026-08-05T11:09:08.003152Z","steps":["trace[1733075853] 'process raft request' (duration: 118.198324ms)","trace[1733075853] 'compare' (duration: 31.643457ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:09:08.774945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.527086ms","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-05T11:09:08.775059Z","caller":"traceutil/trace.go:171","msg":"trace[661670621] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:638; }","duration":"147.727921ms","start":"2026-08-05T11:09:08.627309Z","end":"2026-08-05T11:09:08.775037Z","steps":["trace[661670621] 'range keys from in-memory index tree' (duration: 147.290732ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:10:37.782538Z","caller":"traceutil/trace.go:171","msg":"trace[1408218592] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"146.54074ms","start":"2026-08-05T11:10:37.635957Z","end":"2026-08-05T11:10:37.782498Z","steps":["trace[1408218592] 'process raft request' (duration: 146.244774ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:10:49.496271Z","caller":"traceutil/trace.go:171","msg":"trace[1087858827] linearizableReadLoop","detail":"{readStateIndex:2024; appliedIndex:2023; }","duration":"127.058648ms","start":"2026-08-05T11:10:49.369179Z","end":"2026-08-05T11:10:49.496238Z","steps":["trace[1087858827] 'read index received' (duration: 126.846704ms)","trace[1087858827] 'applied index is now lower than readState.Index' (duration: 210.664µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:10:49.49646Z","caller":"traceutil/trace.go:171","msg":"trace[556378908] transaction","detail":"{read_only:false; response_revision:1952; number_of_response:1; }","duration":"131.544095ms","start":"2026-08-05T11:10:49.364777Z","end":"2026-08-05T11:10:49.496322Z","steps":["trace[556378908] 'process raft request' (duration: 131.27527ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:10:49.496628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.427435ms","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-05T11:10:49.496682Z","caller":"traceutil/trace.go:171","msg":"trace[1678905240] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1952; }","duration":"127.548727ms","start":"2026-08-05T11:10:49.369121Z","end":"2026-08-05T11:10:49.49667Z","steps":["trace[1678905240] 'agreement among raft nodes before linearized reading' (duration: 127.223071ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:10:52.35221Z","caller":"traceutil/trace.go:171","msg":"trace[1823793104] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"147.395856ms","start":"2026-08-05T11:10:52.204788Z","end":"2026-08-05T11:10:52.352184Z","steps":["trace[1823793104] 'process raft request' (duration: 147.222912ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:11:26.21067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.029934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-05T11:11:26.210756Z","caller":"traceutil/trace.go:171","msg":"trace[849166682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2162; }","duration":"161.159867ms","start":"2026-08-05T11:11:26.049579Z","end":"2026-08-05T11:11:26.210738Z","steps":["trace[849166682] 'range keys from in-memory index tree' (duration: 160.904071ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:11:26.210664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.962357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-08-05T11:11:26.210833Z","caller":"traceutil/trace.go:171","msg":"trace[436577366] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2162; }","duration":"126.225082ms","start":"2026-08-05T11:11:26.084581Z","end":"2026-08-05T11:11:26.210806Z","steps":["trace[436577366] 'range keys from in-memory index tree' (duration: 125.784863ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:26.347052Z","caller":"traceutil/trace.go:171","msg":"trace[698275081] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"132.256141ms","start":"2026-08-05T11:11:26.214765Z","end":"2026-08-05T11:11:26.347021Z","steps":["trace[698275081] 'process raft request' (duration: 132.083987ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:26.489254Z","caller":"traceutil/trace.go:171","msg":"trace[1341026757] linearizableReadLoop","detail":"{readStateIndex:2245; appliedIndex:2242; }","duration":"264.375746ms","start":"2026-08-05T11:11:26.224849Z","end":"2026-08-05T11:11:26.489225Z","steps":["trace[1341026757] 'read index received' (duration: 122.081871ms)","trace[1341026757] 'applied index is now lower than readState.Index' (duration: 142.292595ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:11:26.489308Z","caller":"traceutil/trace.go:171","msg":"trace[166844430] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"272.610278ms","start":"2026-08-05T11:11:26.21666Z","end":"2026-08-05T11:11:26.48927Z","steps":["trace[166844430] 'process raft request' (duration: 272.394284ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:11:26.489318Z","caller":"traceutil/trace.go:171","msg":"trace[2014591264] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"272.318622ms","start":"2026-08-05T11:11:26.216964Z","end":"2026-08-05T11:11:26.489283Z","steps":["trace[2014591264] 'process raft request' (duration: 272.217811ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:11:26.489529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.665962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-05T11:11:26.489617Z","caller":"traceutil/trace.go:171","msg":"trace[1038965165] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2165; }","duration":"264.790015ms","start":"2026-08-05T11:11:26.22481Z","end":"2026-08-05T11:11:26.4896Z","steps":["trace[1038965165] 'agreement among raft nodes before linearized reading' (duration: 264.54016ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:11:26.498961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.821486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:11:26.499046Z","caller":"traceutil/trace.go:171","msg":"trace[1387660365] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2166; }","duration":"120.961528ms","start":"2026-08-05T11:11:26.378064Z","end":"2026-08-05T11:11:26.499026Z","steps":["trace[1387660365] 'agreement among raft nodes before linearized reading' (duration: 120.757384ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:16:28.049015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.35157ms","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-05T11:16:28.049109Z","caller":"traceutil/trace.go:171","msg":"trace[900587389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4708; }","duration":"101.505703ms","start":"2026-08-05T11:16:27.947586Z","end":"2026-08-05T11:16:28.049091Z","steps":["trace[900587389] 'range keys from in-memory index tree' (duration: 101.146146ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:16:28.049289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.400957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-05T11:16:28.049411Z","caller":"traceutil/trace.go:171","msg":"trace[2025027707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4708; }","duration":"106.60195ms","start":"2026-08-05T11:16:27.942784Z","end":"2026-08-05T11:16:28.049386Z","steps":["trace[2025027707] 'range keys from in-memory index tree' (duration: 106.140082ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:16:28.185507Z","caller":"traceutil/trace.go:171","msg":"trace[1877605036] linearizableReadLoop","detail":"{readStateIndex:4888; appliedIndex:4886; }","duration":"115.124646ms","start":"2026-08-05T11:16:28.070358Z","end":"2026-08-05T11:16:28.185482Z","steps":["trace[1877605036] 'read index received' (duration: 114.983174ms)","trace[1877605036] 'applied index is now lower than readState.Index' (duration: 140.742µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:16:28.185556Z","caller":"traceutil/trace.go:171","msg":"trace[570680264] transaction","detail":"{read_only:false; response_revision:4710; number_of_response:1; }","duration":"130.470711ms","start":"2026-08-05T11:16:28.055046Z","end":"2026-08-05T11:16:28.185517Z","steps":["trace[570680264] 'process raft request' (duration: 130.234906ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:16:28.185584Z","caller":"traceutil/trace.go:171","msg":"trace[251113536] transaction","detail":"{read_only:false; response_revision:4711; number_of_response:1; }","duration":"128.623534ms","start":"2026-08-05T11:16:28.056918Z","end":"2026-08-05T11:16:28.185541Z","steps":["trace[251113536] 'process raft request' (duration: 128.518702ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:16:28.185905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.513203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-98b9c3ce-2759-4b25-836e-d3c2405a3585\" ","response":"range_response_count:1 size:3759"} {"level":"info","ts":"2026-08-05T11:16:28.186035Z","caller":"traceutil/trace.go:171","msg":"trace[1272324661] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-98b9c3ce-2759-4b25-836e-d3c2405a3585; range_end:; response_count:1; response_revision:4711; }","duration":"115.754168ms","start":"2026-08-05T11:16:28.070251Z","end":"2026-08-05T11:16:28.186005Z","steps":["trace[1272324661] 'agreement among raft nodes before linearized reading' (duration: 115.369601ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:16:28.187302Z","caller":"traceutil/trace.go:171","msg":"trace[1892079722] transaction","detail":"{read_only:false; response_revision:4712; number_of_response:1; }","duration":"112.426693ms","start":"2026-08-05T11:16:28.074855Z","end":"2026-08-05T11:16:28.187282Z","steps":["trace[1892079722] 'process raft request' (duration: 112.027826ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:16:28.187444Z","caller":"traceutil/trace.go:171","msg":"trace[1914521811] transaction","detail":"{read_only:false; response_revision:4713; number_of_response:1; }","duration":"105.814986ms","start":"2026-08-05T11:16:28.081607Z","end":"2026-08-05T11:16:28.187422Z","steps":["trace[1914521811] 'process raft request' (duration: 105.427519ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:16:28.187338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.189993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c76d6367-20c0-4d41-a5e8-64568de07da2\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-08-05T11:16:28.187569Z","caller":"traceutil/trace.go:171","msg":"trace[1934585078] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c76d6367-20c0-4d41-a5e8-64568de07da2; range_end:; response_count:1; response_revision:4713; }","duration":"107.438717ms","start":"2026-08-05T11:16:28.08011Z","end":"2026-08-05T11:16:28.187548Z","steps":["trace[1934585078] 'agreement among raft nodes before linearized reading' (duration: 107.021159ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:16:30.299007Z","caller":"traceutil/trace.go:171","msg":"trace[1612077148] transaction","detail":"{read_only:false; response_revision:4724; number_of_response:1; }","duration":"106.115111ms","start":"2026-08-05T11:16:30.192868Z","end":"2026-08-05T11:16:30.298983Z","steps":["trace[1612077148] 'process raft request' (duration: 105.970879ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:16:30.367024Z","caller":"traceutil/trace.go:171","msg":"trace[442487668] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"173.047958ms","start":"2026-08-05T11:16:30.193946Z","end":"2026-08-05T11:16:30.366994Z","steps":["trace[442487668] 'process raft request' (duration: 160.316064ms)","trace[442487668] 'compare' (duration: 12.361377ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:16:30.367172Z","caller":"traceutil/trace.go:171","msg":"trace[1804719822] linearizableReadLoop","detail":"{readStateIndex:4904; appliedIndex:4901; }","duration":"172.185512ms","start":"2026-08-05T11:16:30.194966Z","end":"2026-08-05T11:16:30.367152Z","steps":["trace[1804719822] 'read index received' (duration: 103.881979ms)","trace[1804719822] 'applied index is now lower than readState.Index' (duration: 68.302183ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:16:30.367174Z","caller":"traceutil/trace.go:171","msg":"trace[1987157143] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"172.60299ms","start":"2026-08-05T11:16:30.194553Z","end":"2026-08-05T11:16:30.367156Z","steps":["trace[1987157143] 'process raft request' (duration: 172.397647ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:16:30.36737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.364626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-98b9c3ce-2759-4b25-836e-d3c2405a3585\" ","response":"range_response_count:1 size:4049"} {"level":"warn","ts":"2026-08-05T11:16:30.367409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.939823ms","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-05T11:16:30.367417Z","caller":"traceutil/trace.go:171","msg":"trace[1988019630] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-98b9c3ce-2759-4b25-836e-d3c2405a3585; range_end:; response_count:1; response_revision:4726; }","duration":"172.472867ms","start":"2026-08-05T11:16:30.194934Z","end":"2026-08-05T11:16:30.367407Z","steps":["trace[1988019630] 'agreement among raft nodes before linearized reading' (duration: 172.287284ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:16:30.367454Z","caller":"traceutil/trace.go:171","msg":"trace[1072865266] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4726; }","duration":"165.009234ms","start":"2026-08-05T11:16:30.202428Z","end":"2026-08-05T11:16:30.367438Z","steps":["trace[1072865266] 'agreement among raft nodes before linearized reading' (duration: 164.850571ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:16:32.162918Z","caller":"traceutil/trace.go:171","msg":"trace[194939480] transaction","detail":"{read_only:false; response_revision:4857; number_of_response:1; }","duration":"167.168655ms","start":"2026-08-05T11:16:31.995718Z","end":"2026-08-05T11:16:32.162887Z","steps":["trace[194939480] 'process raft request' (duration: 166.89611ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:16:42.348175Z","caller":"traceutil/trace.go:171","msg":"trace[2069165065] transaction","detail":"{read_only:false; response_revision:5094; number_of_response:1; }","duration":"106.185403ms","start":"2026-08-05T11:16:42.24197Z","end":"2026-08-05T11:16:42.348155Z","steps":["trace[2069165065] 'process raft request' (duration: 86.168238ms)","trace[2069165065] 'compare' (duration: 19.903833ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:16:42.398459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.311676ms","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-05T11:16:42.398546Z","caller":"traceutil/trace.go:171","msg":"trace[670807447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5095; }","duration":"104.455939ms","start":"2026-08-05T11:16:42.294071Z","end":"2026-08-05T11:16:42.398527Z","steps":["trace[670807447] 'agreement among raft nodes before linearized reading' (duration: 104.109463ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:16:42.661632Z","caller":"traceutil/trace.go:171","msg":"trace[1798695850] transaction","detail":"{read_only:false; response_revision:5105; number_of_response:1; }","duration":"167.988482ms","start":"2026-08-05T11:16:42.493623Z","end":"2026-08-05T11:16:42.661611Z","steps":["trace[1798695850] 'process raft request' (duration: 162.856013ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:16:42.668423Z","caller":"traceutil/trace.go:171","msg":"trace[1083025825] transaction","detail":"{read_only:false; response_revision:5106; number_of_response:1; }","duration":"114.747778ms","start":"2026-08-05T11:16:42.55366Z","end":"2026-08-05T11:16:42.668408Z","steps":["trace[1083025825] 'process raft request' (duration: 114.652555ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:16:52.667576Z","caller":"traceutil/trace.go:171","msg":"trace[463218692] linearizableReadLoop","detail":"{readStateIndex:5489; appliedIndex:5488; }","duration":"158.038771ms","start":"2026-08-05T11:16:52.509503Z","end":"2026-08-05T11:16:52.667542Z","steps":["trace[463218692] 'read index received' (duration: 157.891358ms)","trace[463218692] 'applied index is now lower than readState.Index' (duration: 145.943µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:16:52.667636Z","caller":"traceutil/trace.go:171","msg":"trace[350841977] transaction","detail":"{read_only:false; response_revision:5297; number_of_response:1; }","duration":"164.441974ms","start":"2026-08-05T11:16:52.503153Z","end":"2026-08-05T11:16:52.667595Z","steps":["trace[350841977] 'process raft request' (duration: 164.22937ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:16:52.667852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.366108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-05T11:16:52.667898Z","caller":"traceutil/trace.go:171","msg":"trace[1718215710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5297; }","duration":"130.462531ms","start":"2026-08-05T11:16:52.537425Z","end":"2026-08-05T11:16:52.667888Z","steps":["trace[1718215710] 'agreement among raft nodes before linearized reading' (duration: 130.276267ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:16:52.667944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.434548ms","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-05T11:16:52.667996Z","caller":"traceutil/trace.go:171","msg":"trace[271229608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5297; }","duration":"158.53144ms","start":"2026-08-05T11:16:52.509451Z","end":"2026-08-05T11:16:52.667983Z","steps":["trace[271229608] 'agreement among raft nodes before linearized reading' (duration: 158.233165ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:16:52.668032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.105907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-05T11:16:52.668103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.297783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-05T11:16:52.668138Z","caller":"traceutil/trace.go:171","msg":"trace[711404123] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:5297; }","duration":"100.243569ms","start":"2026-08-05T11:16:52.567869Z","end":"2026-08-05T11:16:52.668112Z","steps":["trace[711404123] 'agreement among raft nodes before linearized reading' (duration: 100.062595ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:16:52.668188Z","caller":"traceutil/trace.go:171","msg":"trace[1416354188] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5297; }","duration":"121.427346ms","start":"2026-08-05T11:16:52.546737Z","end":"2026-08-05T11:16:52.668164Z","steps":["trace[1416354188] 'agreement among raft nodes before linearized reading' (duration: 121.200221ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:16:52.965163Z","caller":"traceutil/trace.go:171","msg":"trace[1560679737] transaction","detail":"{read_only:false; response_revision:5304; number_of_response:1; }","duration":"103.087923ms","start":"2026-08-05T11:16:52.862045Z","end":"2026-08-05T11:16:52.965133Z","steps":["trace[1560679737] 'process raft request' (duration: 34.903592ms)","trace[1560679737] 'compare' (duration: 68.037519ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:18:26.915963Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3013} {"level":"info","ts":"2026-08-05T11:18:26.995195Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3013,"took":"77.635191ms","hash":850545317,"current-db-size-bytes":58204160,"current-db-size":"58 MB","current-db-size-in-use-bytes":32497664,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-05T11:18:26.995271Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":850545317,"revision":3013,"compact-revision":-1} {"level":"warn","ts":"2026-08-05T11:22:38.683584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.901285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-05T11:22:38.683624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.539968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-05T11:22:38.683639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.103691ms","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-05T11:22:38.683685Z","caller":"traceutil/trace.go:171","msg":"trace[1534328807] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8055; }","duration":"213.633981ms","start":"2026-08-05T11:22:38.470037Z","end":"2026-08-05T11:22:38.683671Z","steps":["trace[1534328807] 'range keys from in-memory index tree' (duration: 213.404197ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:22:38.6837Z","caller":"traceutil/trace.go:171","msg":"trace[2143111734] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8055; }","duration":"226.195753ms","start":"2026-08-05T11:22:38.457488Z","end":"2026-08-05T11:22:38.683683Z","steps":["trace[2143111734] 'range keys from in-memory index tree' (duration: 225.984629ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:22:38.683706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.110399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-08-05T11:22:38.683721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.001697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-05T11:22:38.68374Z","caller":"traceutil/trace.go:171","msg":"trace[1814228748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8055; }","duration":"191.035507ms","start":"2026-08-05T11:22:38.492699Z","end":"2026-08-05T11:22:38.683734Z","steps":["trace[1814228748] 'range keys from in-memory index tree' (duration: 190.933145ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:22:38.683743Z","caller":"traceutil/trace.go:171","msg":"trace[322526691] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8055; }","duration":"178.158631ms","start":"2026-08-05T11:22:38.505572Z","end":"2026-08-05T11:22:38.683731Z","steps":["trace[322526691] 'range keys from in-memory index tree' (duration: 178.035788ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:22:38.683659Z","caller":"traceutil/trace.go:171","msg":"trace[331863166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8055; }","duration":"191.023597ms","start":"2026-08-05T11:22:38.49262Z","end":"2026-08-05T11:22:38.683644Z","steps":["trace[331863166] 'range keys from in-memory index tree' (duration: 190.701371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:22:38.683761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.146058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:22:38.68385Z","caller":"traceutil/trace.go:171","msg":"trace[88657794] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8055; }","duration":"305.27969ms","start":"2026-08-05T11:22:38.378557Z","end":"2026-08-05T11:22:38.683837Z","steps":["trace[88657794] 'range keys from in-memory index tree' (duration: 305.068196ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:22:38.683877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:22:38.378535Z","time spent":"305.336652ms","remote":"127.0.0.1:59002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-05T11:22:51.150445Z","caller":"traceutil/trace.go:171","msg":"trace[1581257556] transaction","detail":"{read_only:false; response_revision:8121; number_of_response:1; }","duration":"396.336909ms","start":"2026-08-05T11:22:50.75409Z","end":"2026-08-05T11:22:51.150427Z","steps":["trace[1581257556] 'process raft request' (duration: 387.709083ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:22:51.150629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.462621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-05T11:22:51.150634Z","caller":"traceutil/trace.go:171","msg":"trace[1455477118] transaction","detail":"{read_only:false; response_revision:8122; number_of_response:1; }","duration":"396.43958ms","start":"2026-08-05T11:22:50.754162Z","end":"2026-08-05T11:22:51.150602Z","steps":["trace[1455477118] 'process raft request' (duration: 396.109904ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:22:51.15043Z","caller":"traceutil/trace.go:171","msg":"trace[1053250579] linearizableReadLoop","detail":"{readStateIndex:8402; appliedIndex:8400; }","duration":"396.255957ms","start":"2026-08-05T11:22:50.754146Z","end":"2026-08-05T11:22:51.150402Z","steps":["trace[1053250579] 'read index received' (duration: 47.855449ms)","trace[1053250579] 'applied index is now lower than readState.Index' (duration: 348.399228ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:22:51.150811Z","caller":"traceutil/trace.go:171","msg":"trace[429510383] transaction","detail":"{read_only:false; response_revision:8123; number_of_response:1; }","duration":"396.586563ms","start":"2026-08-05T11:22:50.754197Z","end":"2026-08-05T11:22:51.150783Z","steps":["trace[429510383] 'process raft request' (duration: 396.155265ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:22:51.15084Z","caller":"traceutil/trace.go:171","msg":"trace[1262809352] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8123; }","duration":"396.605893ms","start":"2026-08-05T11:22:50.754103Z","end":"2026-08-05T11:22:51.150709Z","steps":["trace[1262809352] 'agreement among raft nodes before linearized reading' (duration: 396.382999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:22:51.150989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:22:50.754085Z","time spent":"396.842888ms","remote":"127.0.0.1:45654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-05T11:22:51.151081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:22:50.754067Z","time spent":"396.44053ms","remote":"127.0.0.1:45654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:22:51.151136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:22:50.754137Z","time spent":"396.648094ms","remote":"127.0.0.1:45654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:22:51.151624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:22:50.754175Z","time spent":"396.712065ms","remote":"127.0.0.1:45654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:22:51.161424Z","caller":"traceutil/trace.go:171","msg":"trace[486221332] transaction","detail":"{read_only:false; response_revision:8125; number_of_response:1; }","duration":"178.316333ms","start":"2026-08-05T11:22:50.983097Z","end":"2026-08-05T11:22:51.161414Z","steps":["trace[486221332] 'process raft request' (duration: 178.284313ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:22:51.161618Z","caller":"traceutil/trace.go:171","msg":"trace[1417643071] transaction","detail":"{read_only:false; response_revision:8124; number_of_response:1; }","duration":"215.491697ms","start":"2026-08-05T11:22:50.946119Z","end":"2026-08-05T11:22:51.161611Z","steps":["trace[1417643071] 'process raft request' (duration: 215.179361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:22:52.642465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.439769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T11:22:52.642566Z","caller":"traceutil/trace.go:171","msg":"trace[818362397] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:8129; }","duration":"199.615112ms","start":"2026-08-05T11:22:52.442931Z","end":"2026-08-05T11:22:52.642546Z","steps":["trace[818362397] 'count revisions from in-memory index tree' (duration: 199.364697ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:19.21279Z","caller":"traceutil/trace.go:171","msg":"trace[2036157422] transaction","detail":"{read_only:false; response_revision:8327; number_of_response:1; }","duration":"268.401683ms","start":"2026-08-05T11:23:18.944343Z","end":"2026-08-05T11:23:19.212744Z","steps":["trace[2036157422] 'process raft request' (duration: 267.843681ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:26.923041Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6175} {"level":"info","ts":"2026-08-05T11:23:27.013679Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6175,"took":"89.011959ms","hash":3287675335,"current-db-size-bytes":58204160,"current-db-size":"58 MB","current-db-size-in-use-bytes":23453696,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-08-05T11:23:27.013748Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3287675335,"revision":6175,"compact-revision":3013} {"level":"info","ts":"2026-08-05T11:23:31.081299Z","caller":"traceutil/trace.go:171","msg":"trace[2024855436] transaction","detail":"{read_only:false; response_revision:8432; number_of_response:1; }","duration":"199.482909ms","start":"2026-08-05T11:23:30.881789Z","end":"2026-08-05T11:23:31.081271Z","steps":["trace[2024855436] 'process raft request' (duration: 199.352186ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:31.825568Z","caller":"traceutil/trace.go:171","msg":"trace[226515160] linearizableReadLoop","detail":"{readStateIndex:8738; appliedIndex:8736; }","duration":"233.804979ms","start":"2026-08-05T11:23:31.591733Z","end":"2026-08-05T11:23:31.825538Z","steps":["trace[226515160] 'read index received' (duration: 16.642219ms)","trace[226515160] 'applied index is now lower than readState.Index' (duration: 217.16161ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:23:31.825816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.060194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-m2bwq\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-08-05T11:23:31.82588Z","caller":"traceutil/trace.go:171","msg":"trace[565312892] range","detail":"{range_begin:/registry/pods/openstack/keepalived-m2bwq; range_end:; response_count:1; response_revision:8442; }","duration":"234.162086ms","start":"2026-08-05T11:23:31.591706Z","end":"2026-08-05T11:23:31.825868Z","steps":["trace[565312892] 'agreement among raft nodes before linearized reading' (duration: 233.941682ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:31.825917Z","caller":"traceutil/trace.go:171","msg":"trace[168152946] transaction","detail":"{read_only:false; response_revision:8442; number_of_response:1; }","duration":"240.914475ms","start":"2026-08-05T11:23:31.584968Z","end":"2026-08-05T11:23:31.825882Z","steps":["trace[168152946] 'process raft request' (duration: 174.509719ms)","trace[168152946] 'compare' (duration: 65.846075ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:23:32.544333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.465545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:23:32.544404Z","caller":"traceutil/trace.go:171","msg":"trace[267345488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8444; }","duration":"168.564837ms","start":"2026-08-05T11:23:32.375824Z","end":"2026-08-05T11:23:32.544389Z","steps":["trace[267345488] 'range keys from in-memory index tree' (duration: 168.411944ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:32.643348Z","caller":"traceutil/trace.go:171","msg":"trace[1100291808] transaction","detail":"{read_only:false; response_revision:8445; number_of_response:1; }","duration":"265.756352ms","start":"2026-08-05T11:23:32.377562Z","end":"2026-08-05T11:23:32.643318Z","steps":["trace[1100291808] 'process raft request' (duration: 265.524428ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:32.734775Z","caller":"traceutil/trace.go:171","msg":"trace[1486021267] linearizableReadLoop","detail":"{readStateIndex:8742; appliedIndex:8740; }","duration":"189.506709ms","start":"2026-08-05T11:23:32.545244Z","end":"2026-08-05T11:23:32.734751Z","steps":["trace[1486021267] 'read index received' (duration: 97.93595ms)","trace[1486021267] 'applied index is now lower than readState.Index' (duration: 91.569789ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:23:32.73518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.918116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:23:32.735232Z","caller":"traceutil/trace.go:171","msg":"trace[1490334321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8446; }","duration":"189.983507ms","start":"2026-08-05T11:23:32.545233Z","end":"2026-08-05T11:23:32.735217Z","steps":["trace[1490334321] 'agreement among raft nodes before linearized reading' (duration: 189.875405ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:23:32.735223Z","caller":"traceutil/trace.go:171","msg":"trace[1561413337] transaction","detail":"{read_only:false; response_revision:8446; number_of_response:1; }","duration":"228.552858ms","start":"2026-08-05T11:23:32.506642Z","end":"2026-08-05T11:23:32.735195Z","steps":["trace[1561413337] 'process raft request' (duration: 145.160467ms)","trace[1561413337] 'compare' (duration: 82.812769ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:24:27.722149Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000245a.wal"} {"level":"info","ts":"2026-08-05T11:25:55.484742Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5887d25060a34f1b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-05T11:25:55.497163Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-05T11:25:55.497255Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-05T11:28:18.525371Z","caller":"traceutil/trace.go:171","msg":"trace[1048576488] transaction","detail":"{read_only:false; response_revision:10959; number_of_response:1; }","duration":"136.520572ms","start":"2026-08-05T11:28:18.388814Z","end":"2026-08-05T11:28:18.525334Z","steps":["trace[1048576488] 'process raft request' (duration: 136.313238ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:28:19.131065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.486191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:28:19.131362Z","caller":"traceutil/trace.go:171","msg":"trace[1278191697] transaction","detail":"{read_only:false; response_revision:10964; number_of_response:1; }","duration":"115.493507ms","start":"2026-08-05T11:28:19.015841Z","end":"2026-08-05T11:28:19.131335Z","steps":["trace[1278191697] 'process raft request' (duration: 14.496658ms)","trace[1278191697] 'compare' (duration: 100.301516ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:28:19.131393Z","caller":"traceutil/trace.go:171","msg":"trace[1320276918] transaction","detail":"{read_only:false; response_revision:10965; number_of_response:1; }","duration":"115.136091ms","start":"2026-08-05T11:28:19.01624Z","end":"2026-08-05T11:28:19.131376Z","steps":["trace[1320276918] 'process raft request' (duration: 114.945537ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:28:19.131498Z","caller":"traceutil/trace.go:171","msg":"trace[237045186] transaction","detail":"{read_only:false; response_revision:10966; number_of_response:1; }","duration":"115.161351ms","start":"2026-08-05T11:28:19.016322Z","end":"2026-08-05T11:28:19.131483Z","steps":["trace[237045186] 'process raft request' (duration: 114.979258ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:28:26.972359Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8400} {"level":"info","ts":"2026-08-05T11:28:27.118943Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8400,"took":"144.056878ms","hash":3289997738,"current-db-size-bytes":58204160,"current-db-size":"58 MB","current-db-size-in-use-bytes":26529792,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-05T11:28:27.119005Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3289997738,"revision":8400,"compact-revision":6175} {"level":"info","ts":"2026-08-05T11:33:26.978496Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11030} {"level":"info","ts":"2026-08-05T11:33:27.054393Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11030,"took":"73.357628ms","hash":2846419198,"current-db-size-bytes":58204160,"current-db-size":"58 MB","current-db-size-in-use-bytes":28147712,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-05T11:33:27.054469Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2846419198,"revision":11030,"compact-revision":8400} {"level":"info","ts":"2026-08-05T11:34:49.372218Z","caller":"traceutil/trace.go:171","msg":"trace[1034872768] linearizableReadLoop","detail":"{readStateIndex:14045; appliedIndex:14043; }","duration":"347.827155ms","start":"2026-08-05T11:34:49.024367Z","end":"2026-08-05T11:34:49.372194Z","steps":["trace[1034872768] 'read index received' (duration: 16.783012ms)","trace[1034872768] 'applied index is now lower than readState.Index' (duration: 331.042793ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:34:49.372302Z","caller":"traceutil/trace.go:171","msg":"trace[721643947] transaction","detail":"{read_only:false; response_revision:13585; number_of_response:1; }","duration":"367.001584ms","start":"2026-08-05T11:34:49.005256Z","end":"2026-08-05T11:34:49.372258Z","steps":["trace[721643947] 'process raft request' (duration: 366.706538ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:34:49.372445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.0547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-05T11:34:49.372488Z","caller":"traceutil/trace.go:171","msg":"trace[524923574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13585; }","duration":"348.147142ms","start":"2026-08-05T11:34:49.024331Z","end":"2026-08-05T11:34:49.372479Z","steps":["trace[524923574] 'agreement among raft nodes before linearized reading' (duration: 347.989899ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:34:49.372492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:34:49.00524Z","time spent":"367.152117ms","remote":"127.0.0.1:45654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:34:49.372524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:34:49.024319Z","time spent":"348.196732ms","remote":"127.0.0.1:45654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-05T11:34:49.850974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.581126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:34:49.851336Z","caller":"traceutil/trace.go:171","msg":"trace[804810923] transaction","detail":"{read_only:false; response_revision:13588; number_of_response:1; }","duration":"469.028072ms","start":"2026-08-05T11:34:49.382281Z","end":"2026-08-05T11:34:49.851309Z","steps":["trace[804810923] 'process raft request' (duration: 468.961151ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:34:49.851431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:34:49.382257Z","time spent":"469.135224ms","remote":"127.0.0.1:45654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:34:49.851601Z","caller":"traceutil/trace.go:171","msg":"trace[1375404122] transaction","detail":"{read_only:false; response_revision:13586; number_of_response:1; }","duration":"692.864188ms","start":"2026-08-05T11:34:49.158709Z","end":"2026-08-05T11:34:49.851573Z","steps":["trace[1375404122] 'process raft request' (duration: 225.541299ms)","trace[1375404122] 'compare' (duration: 466.27788ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:34:49.851997Z","caller":"traceutil/trace.go:171","msg":"trace[623413438] linearizableReadLoop","detail":"{readStateIndex:14047; appliedIndex:14045; }","duration":"479.425332ms","start":"2026-08-05T11:34:49.372297Z","end":"2026-08-05T11:34:49.851722Z","steps":["trace[623413438] 'read index received' (duration: 11.96386ms)","trace[623413438] 'applied index is now lower than readState.Index' (duration: 467.457512ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:34:49.852022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:34:49.158683Z","time spent":"692.99817ms","remote":"127.0.0.1:45654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:34:49.851411Z","caller":"traceutil/trace.go:171","msg":"trace[1703302318] transaction","detail":"{read_only:false; response_revision:13587; number_of_response:1; }","duration":"572.549609ms","start":"2026-08-05T11:34:49.278793Z","end":"2026-08-05T11:34:49.851342Z","steps":["trace[1703302318] 'process raft request' (duration: 572.348235ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:34:49.852219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.669232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-08-05T11:34:49.852271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.445504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-05T11:34:49.852273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:34:49.278765Z","time spent":"573.424386ms","remote":"127.0.0.1:45654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:34:49.852253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.45466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-fqbf8\" ","response":"range_response_count:1 size:12595"} {"level":"info","ts":"2026-08-05T11:34:49.852313Z","caller":"traceutil/trace.go:171","msg":"trace[1373880405] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13588; }","duration":"821.796204ms","start":"2026-08-05T11:34:49.030492Z","end":"2026-08-05T11:34:49.852288Z","steps":["trace[1373880405] 'agreement among raft nodes before linearized reading' (duration: 821.290263ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:34:49.852323Z","caller":"traceutil/trace.go:171","msg":"trace[803767533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13588; }","duration":"824.553936ms","start":"2026-08-05T11:34:49.027755Z","end":"2026-08-05T11:34:49.852309Z","steps":["trace[803767533] 'agreement among raft nodes before linearized reading' (duration: 824.314101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:34:49.852366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:34:49.030481Z","time spent":"821.874035ms","remote":"127.0.0.1:45654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-05T11:34:49.852364Z","caller":"traceutil/trace.go:171","msg":"trace[854227433] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-fqbf8; range_end:; response_count:1; response_revision:13588; }","duration":"792.611543ms","start":"2026-08-05T11:34:49.059722Z","end":"2026-08-05T11:34:49.852333Z","steps":["trace[854227433] 'agreement among raft nodes before linearized reading' (duration: 792.031301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:34:49.852397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:34:49.027732Z","time spent":"824.620878ms","remote":"127.0.0.1:45654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-05T11:34:49.852431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:34:49.05971Z","time spent":"792.705205ms","remote":"127.0.0.1:45554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":12618,"request content":"key:\"/registry/pods/openstack/openvswitch-fqbf8\" "} {"level":"warn","ts":"2026-08-05T11:34:49.891456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.610586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:34:49.891598Z","caller":"traceutil/trace.go:171","msg":"trace[1922965429] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:13589; }","duration":"478.770479ms","start":"2026-08-05T11:34:49.41278Z","end":"2026-08-05T11:34:49.89155Z","steps":["trace[1922965429] 'agreement among raft nodes before linearized reading' (duration: 478.564884ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:34:49.891682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:34:49.412763Z","time spent":"478.908182ms","remote":"127.0.0.1:45944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-08-05T11:34:49.89214Z","caller":"traceutil/trace.go:171","msg":"trace[279236626] transaction","detail":"{read_only:false; response_revision:13589; number_of_response:1; }","duration":"335.564971ms","start":"2026-08-05T11:34:49.556563Z","end":"2026-08-05T11:34:49.892128Z","steps":["trace[279236626] 'process raft request' (duration: 334.535531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:34:49.893505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:34:49.556543Z","time spent":"335.651692ms","remote":"127.0.0.1:45654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:36:38.486219Z","caller":"traceutil/trace.go:171","msg":"trace[18480487] transaction","detail":"{read_only:false; response_revision:14832; number_of_response:1; }","duration":"124.473783ms","start":"2026-08-05T11:36:38.361732Z","end":"2026-08-05T11:36:38.486206Z","steps":["trace[18480487] 'process raft request' (duration: 123.645636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:36:38.486274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.940225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:36:38.486303Z","caller":"traceutil/trace.go:171","msg":"trace[2046898195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14832; }","duration":"108.016707ms","start":"2026-08-05T11:36:38.378279Z","end":"2026-08-05T11:36:38.486296Z","steps":["trace[2046898195] 'agreement among raft nodes before linearized reading' (duration: 107.955026ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:36:38.486216Z","caller":"traceutil/trace.go:171","msg":"trace[414041681] linearizableReadLoop","detail":"{readStateIndex:15327; appliedIndex:15326; }","duration":"107.869433ms","start":"2026-08-05T11:36:38.378326Z","end":"2026-08-05T11:36:38.486196Z","steps":["trace[414041681] 'read index received' (duration: 106.987426ms)","trace[414041681] 'applied index is now lower than readState.Index' (duration: 881.067µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:36:38.62563Z","caller":"traceutil/trace.go:171","msg":"trace[672283546] transaction","detail":"{read_only:false; response_revision:14833; number_of_response:1; }","duration":"134.578037ms","start":"2026-08-05T11:36:38.49103Z","end":"2026-08-05T11:36:38.625608Z","steps":["trace[672283546] 'process raft request' (duration: 134.288442ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:36:38.648464Z","caller":"traceutil/trace.go:171","msg":"trace[712946371] linearizableReadLoop","detail":"{readStateIndex:15329; appliedIndex:15327; }","duration":"136.813019ms","start":"2026-08-05T11:36:38.511631Z","end":"2026-08-05T11:36:38.648444Z","steps":["trace[712946371] 'read index received' (duration: 113.677464ms)","trace[712946371] 'applied index is now lower than readState.Index' (duration: 23.134525ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:36:38.648591Z","caller":"traceutil/trace.go:171","msg":"trace[1730060019] transaction","detail":"{read_only:false; response_revision:14834; number_of_response:1; }","duration":"157.269803ms","start":"2026-08-05T11:36:38.491291Z","end":"2026-08-05T11:36:38.648561Z","steps":["trace[1730060019] 'process raft request' (duration: 157.067609ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:36:38.648693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.82497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T11:36:38.64873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.087276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-08-05T11:36:38.648775Z","caller":"traceutil/trace.go:171","msg":"trace[566947456] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14834; }","duration":"136.933453ms","start":"2026-08-05T11:36:38.511823Z","end":"2026-08-05T11:36:38.648757Z","steps":["trace[566947456] 'agreement among raft nodes before linearized reading' (duration: 136.786819ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:36:38.648791Z","caller":"traceutil/trace.go:171","msg":"trace[970679495] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14834; }","duration":"137.185807ms","start":"2026-08-05T11:36:38.511586Z","end":"2026-08-05T11:36:38.648772Z","steps":["trace[970679495] 'agreement among raft nodes before linearized reading' (duration: 136.981042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:36:38.649125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.81634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-08-05T11:36:38.649167Z","caller":"traceutil/trace.go:171","msg":"trace[1801098196] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:14834; }","duration":"136.864101ms","start":"2026-08-05T11:36:38.512291Z","end":"2026-08-05T11:36:38.649155Z","steps":["trace[1801098196] 'agreement among raft nodes before linearized reading' (duration: 136.703448ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:36:38.649137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.228458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T11:36:38.64929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.144156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-08-05T11:36:38.649361Z","caller":"traceutil/trace.go:171","msg":"trace[889959727] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14834; }","duration":"137.222818ms","start":"2026-08-05T11:36:38.512119Z","end":"2026-08-05T11:36:38.649341Z","steps":["trace[889959727] 'agreement among raft nodes before linearized reading' (duration: 137.059765ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:36:38.649389Z","caller":"traceutil/trace.go:171","msg":"trace[1472127160] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:14834; }","duration":"137.448572ms","start":"2026-08-05T11:36:38.511868Z","end":"2026-08-05T11:36:38.649316Z","steps":["trace[1472127160] 'agreement among raft nodes before linearized reading' (duration: 137.152607ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:38:26.985006Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13064} {"level":"info","ts":"2026-08-05T11:38:27.059945Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13064,"took":"72.344181ms","hash":802488526,"current-db-size-bytes":58204160,"current-db-size":"58 MB","current-db-size-in-use-bytes":31584256,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-05T11:38:27.059995Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":802488526,"revision":13064,"compact-revision":11030} {"level":"info","ts":"2026-08-05T11:39:15.757689Z","caller":"traceutil/trace.go:171","msg":"trace[1046318408] transaction","detail":"{read_only:false; response_revision:16120; number_of_response:1; }","duration":"101.855768ms","start":"2026-08-05T11:39:15.655805Z","end":"2026-08-05T11:39:15.757661Z","steps":["trace[1046318408] 'process raft request' (duration: 101.647733ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:39:18.140032Z","caller":"traceutil/trace.go:171","msg":"trace[1011174919] linearizableReadLoop","detail":"{readStateIndex:16671; appliedIndex:16670; }","duration":"118.13156ms","start":"2026-08-05T11:39:18.02188Z","end":"2026-08-05T11:39:18.140011Z","steps":["trace[1011174919] 'read index received' (duration: 117.854475ms)","trace[1011174919] 'applied index is now lower than readState.Index' (duration: 276.035µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:39:18.140385Z","caller":"traceutil/trace.go:171","msg":"trace[425632107] transaction","detail":"{read_only:false; response_revision:16133; number_of_response:1; }","duration":"166.865027ms","start":"2026-08-05T11:39:17.973502Z","end":"2026-08-05T11:39:18.140367Z","steps":["trace[425632107] 'process raft request' (duration: 166.355637ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:39:18.14078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.888554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:699"} {"level":"info","ts":"2026-08-05T11:39:18.140842Z","caller":"traceutil/trace.go:171","msg":"trace[1171932940] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:16133; }","duration":"118.997247ms","start":"2026-08-05T11:39:18.021829Z","end":"2026-08-05T11:39:18.140826Z","steps":["trace[1171932940] 'agreement among raft nodes before linearized reading' (duration: 118.820043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:17.237418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.069197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-bbj5w\" ","response":"range_response_count:1 size:22122"} {"level":"info","ts":"2026-08-05T11:42:17.237506Z","caller":"traceutil/trace.go:171","msg":"trace[1829377050] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-bbj5w; range_end:; response_count:1; response_revision:17407; }","duration":"223.21822ms","start":"2026-08-05T11:42:17.014269Z","end":"2026-08-05T11:42:17.237487Z","steps":["trace[1829377050] 'range keys from in-memory index tree' (duration: 222.922635ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:17.237582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:42:16.833671Z","time spent":"403.905723ms","remote":"127.0.0.1:47596","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-05T11:42:17.237865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.92184ms","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-05T11:42:17.237908Z","caller":"traceutil/trace.go:171","msg":"trace[154276449] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17407; }","duration":"202.998591ms","start":"2026-08-05T11:42:17.034899Z","end":"2026-08-05T11:42:17.237898Z","steps":["trace[154276449] 'range keys from in-memory index tree' (duration: 202.805838ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:42:17.424137Z","caller":"traceutil/trace.go:171","msg":"trace[766672985] transaction","detail":"{read_only:false; response_revision:17408; number_of_response:1; }","duration":"182.199303ms","start":"2026-08-05T11:42:17.241915Z","end":"2026-08-05T11:42:17.424114Z","steps":["trace[766672985] 'process raft request' (duration: 182.048ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:42:17.470129Z","caller":"traceutil/trace.go:171","msg":"trace[1655481617] linearizableReadLoop","detail":"{readStateIndex:17989; appliedIndex:17987; }","duration":"225.685767ms","start":"2026-08-05T11:42:17.24442Z","end":"2026-08-05T11:42:17.470105Z","steps":["trace[1655481617] 'read index received' (duration: 179.52364ms)","trace[1655481617] 'applied index is now lower than readState.Index' (duration: 46.160837ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:42:17.470203Z","caller":"traceutil/trace.go:171","msg":"trace[1942340406] transaction","detail":"{read_only:false; response_revision:17409; number_of_response:1; }","duration":"227.748557ms","start":"2026-08-05T11:42:17.242421Z","end":"2026-08-05T11:42:17.470169Z","steps":["trace[1942340406] 'process raft request' (duration: 207.379606ms)","trace[1942340406] 'compare' (duration: 20.198078ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:42:17.470394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.635446ms","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-05T11:42:17.470533Z","caller":"traceutil/trace.go:171","msg":"trace[1187205196] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17409; }","duration":"213.756649ms","start":"2026-08-05T11:42:17.256682Z","end":"2026-08-05T11:42:17.470439Z","steps":["trace[1187205196] 'agreement among raft nodes before linearized reading' (duration: 213.595485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:42:17.471175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.742399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:942478"} {"level":"info","ts":"2026-08-05T11:42:17.471215Z","caller":"traceutil/trace.go:171","msg":"trace[1810526334] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:17409; }","duration":"226.862801ms","start":"2026-08-05T11:42:17.244342Z","end":"2026-08-05T11:42:17.471205Z","steps":["trace[1810526334] 'agreement among raft nodes before linearized reading' (duration: 225.898272ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:43:26.99113Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15615} {"level":"info","ts":"2026-08-05T11:43:27.083555Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15615,"took":"89.49267ms","hash":1050732774,"current-db-size-bytes":58204160,"current-db-size":"58 MB","current-db-size-in-use-bytes":34525184,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-05T11:43:27.083613Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1050732774,"revision":15615,"compact-revision":13064} {"level":"info","ts":"2026-08-05T11:46:04.8957Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5887d25060a34f1b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-05T11:46:04.90006Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-05T11:46:04.900161Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-08-05T11:47:05.453923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.371465ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-05T11:47:05.940513Z","caller":"traceutil/trace.go:171","msg":"trace[2105816534] linearizableReadLoop","detail":"{readStateIndex:20356; appliedIndex:20355; }","duration":"276.377751ms","start":"2026-08-05T11:47:05.664106Z","end":"2026-08-05T11:47:05.940483Z","steps":["trace[2105816534] 'read index received' (duration: 276.153737ms)","trace[2105816534] 'applied index is now lower than readState.Index' (duration: 222.924µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:47:05.940615Z","caller":"traceutil/trace.go:171","msg":"trace[2089627644] transaction","detail":"{read_only:false; response_revision:19700; number_of_response:1; }","duration":"308.856116ms","start":"2026-08-05T11:47:05.631728Z","end":"2026-08-05T11:47:05.940584Z","steps":["trace[2089627644] 'process raft request' (duration: 308.54646ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:47:05.940774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:47:05.631329Z","time spent":"309.365006ms","remote":"127.0.0.1:45654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:47:05.940758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.525674ms","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-05T11:47:05.940857Z","caller":"traceutil/trace.go:171","msg":"trace[340465633] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:19700; }","duration":"251.666027ms","start":"2026-08-05T11:47:05.689155Z","end":"2026-08-05T11:47:05.940821Z","steps":["trace[340465633] 'agreement among raft nodes before linearized reading' (duration: 251.510334ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:47:05.94083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.555856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:47:05.940926Z","caller":"traceutil/trace.go:171","msg":"trace[1853358047] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:19700; }","duration":"109.710349ms","start":"2026-08-05T11:47:05.831188Z","end":"2026-08-05T11:47:05.940899Z","steps":["trace[1853358047] 'agreement among raft nodes before linearized reading' (duration: 109.534746ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:47:05.940904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.773189ms","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-05T11:47:05.940927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.286513ms","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-05T11:47:05.940988Z","caller":"traceutil/trace.go:171","msg":"trace[2035986492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19700; }","duration":"276.890151ms","start":"2026-08-05T11:47:05.664078Z","end":"2026-08-05T11:47:05.940968Z","steps":["trace[2035986492] 'agreement among raft nodes before linearized reading' (duration: 276.509424ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:47:05.941007Z","caller":"traceutil/trace.go:171","msg":"trace[277883584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19700; }","duration":"195.371395ms","start":"2026-08-05T11:47:05.74562Z","end":"2026-08-05T11:47:05.940991Z","steps":["trace[277883584] 'agreement among raft nodes before linearized reading' (duration: 195.159021ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:47:06.197856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.646565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T11:47:06.198Z","caller":"traceutil/trace.go:171","msg":"trace[620478874] linearizableReadLoop","detail":"{readStateIndex:20359; appliedIndex:20356; }","duration":"250.757249ms","start":"2026-08-05T11:47:05.94723Z","end":"2026-08-05T11:47:06.197987Z","steps":["trace[620478874] 'read index received' (duration: 85.923921ms)","trace[620478874] 'applied index is now lower than readState.Index' (duration: 164.832468ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:47:06.19812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.886052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-05T11:47:06.198137Z","caller":"traceutil/trace.go:171","msg":"trace[552223717] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:19703; }","duration":"250.904032ms","start":"2026-08-05T11:47:05.947227Z","end":"2026-08-05T11:47:06.198131Z","steps":["trace[552223717] 'agreement among raft nodes before linearized reading' (duration: 250.79492ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:47:06.198282Z","caller":"traceutil/trace.go:171","msg":"trace[1507371346] transaction","detail":"{read_only:false; response_revision:19702; number_of_response:1; }","duration":"252.35731ms","start":"2026-08-05T11:47:05.945918Z","end":"2026-08-05T11:47:06.198275Z","steps":["trace[1507371346] 'process raft request' (duration: 252.010873ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:47:06.198328Z","caller":"traceutil/trace.go:171","msg":"trace[1816582954] transaction","detail":"{read_only:false; response_revision:19703; number_of_response:1; }","duration":"252.36226ms","start":"2026-08-05T11:47:05.945937Z","end":"2026-08-05T11:47:06.198299Z","steps":["trace[1816582954] 'process raft request' (duration: 252.025614ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:47:06.198338Z","caller":"traceutil/trace.go:171","msg":"trace[950822383] transaction","detail":"{read_only:false; response_revision:19701; number_of_response:1; }","duration":"252.437702ms","start":"2026-08-05T11:47:05.945845Z","end":"2026-08-05T11:47:06.198282Z","steps":["trace[950822383] 'process raft request' (duration: 87.280898ms)","trace[950822383] 'compare' (duration: 164.546893ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:47:06.632489Z","caller":"traceutil/trace.go:171","msg":"trace[1609962186] transaction","detail":"{read_only:false; response_revision:19704; number_of_response:1; }","duration":"110.364121ms","start":"2026-08-05T11:47:06.522094Z","end":"2026-08-05T11:47:06.632458Z","steps":["trace[1609962186] 'process raft request' (duration: 110.164197ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:47:06.922376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.216507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:47:06.922452Z","caller":"traceutil/trace.go:171","msg":"trace[1891238006] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:19705; }","duration":"215.336578ms","start":"2026-08-05T11:47:06.707095Z","end":"2026-08-05T11:47:06.922431Z","steps":["trace[1891238006] 'count revisions from in-memory index tree' (duration: 215.098944ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:47:06.922882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.514617ms","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-05T11:47:06.922999Z","caller":"traceutil/trace.go:171","msg":"trace[1326344865] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19705; }","duration":"196.69234ms","start":"2026-08-05T11:47:06.726283Z","end":"2026-08-05T11:47:06.922975Z","steps":["trace[1326344865] 'range keys from in-memory index tree' (duration: 196.333614ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:47:07.084774Z","caller":"traceutil/trace.go:171","msg":"trace[1581011966] transaction","detail":"{read_only:false; response_revision:19706; number_of_response:1; }","duration":"157.832194ms","start":"2026-08-05T11:47:06.926923Z","end":"2026-08-05T11:47:07.084755Z","steps":["trace[1581011966] 'process raft request' (duration: 157.696952ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:47:07.146305Z","caller":"traceutil/trace.go:171","msg":"trace[911673738] transaction","detail":"{read_only:false; response_revision:19707; number_of_response:1; }","duration":"219.253405ms","start":"2026-08-05T11:47:06.927013Z","end":"2026-08-05T11:47:07.146266Z","steps":["trace[911673738] 'process raft request' (duration: 206.343826ms)","trace[911673738] 'compare' (duration: 12.674424ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:48:26.998275Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18174} {"level":"info","ts":"2026-08-05T11:48:27.125357Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18174,"took":"123.494463ms","hash":196379561,"current-db-size-bytes":58204160,"current-db-size":"58 MB","current-db-size-in-use-bytes":30445568,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-05T11:48:27.125419Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":196379561,"revision":18174,"compact-revision":15615} {"level":"info","ts":"2026-08-05T11:48:39.231467Z","caller":"traceutil/trace.go:171","msg":"trace[1892275121] transaction","detail":"{read_only:false; response_revision:20502; number_of_response:1; }","duration":"158.114379ms","start":"2026-08-05T11:48:39.073332Z","end":"2026-08-05T11:48:39.231446Z","steps":["trace[1892275121] 'process raft request' (duration: 157.978406ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:49:05.424562Z","caller":"traceutil/trace.go:171","msg":"trace[1889926159] transaction","detail":"{read_only:false; response_revision:20669; number_of_response:1; }","duration":"142.299874ms","start":"2026-08-05T11:49:05.282221Z","end":"2026-08-05T11:49:05.424521Z","steps":["trace[1889926159] 'process raft request' (duration: 142.037299ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:49:05.763293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.580354ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T11:49:05.763733Z","caller":"traceutil/trace.go:171","msg":"trace[1683452689] transaction","detail":"{read_only:false; response_revision:20670; number_of_response:1; }","duration":"430.437162ms","start":"2026-08-05T11:49:05.333269Z","end":"2026-08-05T11:49:05.763707Z","steps":["trace[1683452689] 'process raft request' (duration: 189.384139ms)","trace[1683452689] 'compare' (duration: 240.447142ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:49:05.763865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:49:05.333249Z","time spent":"430.551344ms","remote":"127.0.0.1:45654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:49:05.794724Z","caller":"traceutil/trace.go:171","msg":"trace[2006881167] linearizableReadLoop","detail":"{readStateIndex:21365; appliedIndex:21362; }","duration":"416.602657ms","start":"2026-08-05T11:49:05.378098Z","end":"2026-08-05T11:49:05.7947Z","steps":["trace[2006881167] 'read index received' (duration: 46.079375ms)","trace[2006881167] 'applied index is now lower than readState.Index' (duration: 370.522362ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:49:05.795116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.999264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T11:49:05.795151Z","caller":"traceutil/trace.go:171","msg":"trace[808691720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20671; }","duration":"417.086556ms","start":"2026-08-05T11:49:05.378055Z","end":"2026-08-05T11:49:05.795142Z","steps":["trace[808691720] 'agreement among raft nodes before linearized reading' (duration: 416.977164ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:49:05.795229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:49:05.378035Z","time spent":"417.143327ms","remote":"127.0.0.1:59002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-05T11:49:05.79523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.401948ms","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-05T11:49:05.795321Z","caller":"traceutil/trace.go:171","msg":"trace[1416651633] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20671; }","duration":"102.51886ms","start":"2026-08-05T11:49:05.69279Z","end":"2026-08-05T11:49:05.795308Z","steps":["trace[1416651633] 'agreement among raft nodes before linearized reading' (duration: 102.304356ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:49:48.578135Z","caller":"traceutil/trace.go:171","msg":"trace[1512577210] transaction","detail":"{read_only:false; response_revision:20972; number_of_response:1; }","duration":"150.900811ms","start":"2026-08-05T11:49:48.427217Z","end":"2026-08-05T11:49:48.578118Z","steps":["trace[1512577210] 'process raft request' (duration: 150.775118ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:49:48.636566Z","caller":"traceutil/trace.go:171","msg":"trace[270903230] transaction","detail":"{read_only:false; response_revision:20973; number_of_response:1; }","duration":"201.488333ms","start":"2026-08-05T11:49:48.435052Z","end":"2026-08-05T11:49:48.63654Z","steps":["trace[270903230] 'process raft request' (duration: 201.33796ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:50:59.373543Z","caller":"traceutil/trace.go:171","msg":"trace[793353406] transaction","detail":"{read_only:false; response_revision:22135; number_of_response:1; }","duration":"251.83097ms","start":"2026-08-05T11:50:59.121678Z","end":"2026-08-05T11:50:59.373509Z","steps":["trace[793353406] 'process raft request' (duration: 199.429562ms)","trace[793353406] 'compare' (duration: 52.269096ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:52:11.412536Z","caller":"traceutil/trace.go:171","msg":"trace[1219663890] transaction","detail":"{read_only:false; response_revision:22897; number_of_response:1; }","duration":"122.560035ms","start":"2026-08-05T11:52:11.289941Z","end":"2026-08-05T11:52:11.412501Z","steps":["trace[1219663890] 'process raft request' (duration: 122.399162ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:52:29.255369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.108373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T11:52:29.255587Z","caller":"traceutil/trace.go:171","msg":"trace[1406594307] transaction","detail":"{read_only:false; response_revision:23070; number_of_response:1; }","duration":"132.327063ms","start":"2026-08-05T11:52:29.123216Z","end":"2026-08-05T11:52:29.255543Z","steps":["trace[1406594307] 'process raft request' (duration: 29.902704ms)","trace[1406594307] 'compare' (duration: 101.833778ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:52:29.614469Z","caller":"traceutil/trace.go:171","msg":"trace[1686188407] transaction","detail":"{read_only:false; response_revision:23074; number_of_response:1; }","duration":"129.131612ms","start":"2026-08-05T11:52:29.485306Z","end":"2026-08-05T11:52:29.614437Z","steps":["trace[1686188407] 'process raft request' (duration: 58.997774ms)","trace[1686188407] 'compare' (duration: 70.005445ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:52:29.656509Z","caller":"traceutil/trace.go:171","msg":"trace[1020553649] transaction","detail":"{read_only:false; response_revision:23075; number_of_response:1; }","duration":"132.69974ms","start":"2026-08-05T11:52:29.523775Z","end":"2026-08-05T11:52:29.656475Z","steps":["trace[1020553649] 'process raft request' (duration: 132.491336ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:53:27.004923Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20414} {"level":"info","ts":"2026-08-05T11:53:27.109357Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20414,"took":"100.827978ms","hash":4277482164,"current-db-size-bytes":58204160,"current-db-size":"58 MB","current-db-size-in-use-bytes":43646976,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-05T11:53:27.109433Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4277482164,"revision":20414,"compact-revision":18174} {"level":"info","ts":"2026-08-05T11:58:27.010953Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23516} {"level":"info","ts":"2026-08-05T11:58:27.14108Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23516,"took":"126.107645ms","hash":1928221947,"current-db-size-bytes":58204160,"current-db-size":"58 MB","current-db-size-in-use-bytes":30498816,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-05T11:58:27.141156Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1928221947,"revision":23516,"compact-revision":20414}