{"level":"warn","ts":"2026-09-09T06:17:11.307765Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-09T06:17:11.307882Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.91: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://162.253.55.91:2380","--initial-cluster=instance=https://162.253.55.91:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.91:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.91: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-09-09T06:17:11.307964Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-09T06:17:11.308164Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.91:2380"]} {"level":"info","ts":"2026-09-09T06:17:11.308185Z","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-09-09T06:17:11.309758Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.91:2379"]} {"level":"info","ts":"2026-09-09T06:17:11.310163Z","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://162.253.55.91:2380"],"listen-peer-urls":["https://162.253.55.91:2380"],"advertise-client-urls":["https://162.253.55.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.91: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-09-09T06:17:11.313214Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.515614ms"} {"level":"info","ts":"2026-09-09T06:17:11.326119Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8a5b61ed9120d6b3","cluster-id":"9e0c1e8055c016ad"} {"level":"info","ts":"2026-09-09T06:17:11.326195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 switched to configuration voters=()"} {"level":"info","ts":"2026-09-09T06:17:11.326215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became follower at term 0"} {"level":"info","ts":"2026-09-09T06:17:11.326223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8a5b61ed9120d6b3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-09T06:17:11.326234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became follower at term 1"} {"level":"info","ts":"2026-09-09T06:17:11.326405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 switched to configuration voters=(9969669873084716723)"} {"level":"warn","ts":"2026-09-09T06:17:11.332074Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-09T06:17:11.333938Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-09T06:17:11.335594Z","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-09-09T06:17:11.33794Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8a5b61ed9120d6b3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-09T06:17:11.339116Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8a5b61ed9120d6b3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-09T06:17:11.339337Z","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-09-09T06:17:11.339412Z","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-09-09T06:17:11.339429Z","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-09-09T06:17:11.339779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 switched to configuration voters=(9969669873084716723)"} {"level":"info","ts":"2026-09-09T06:17:11.339917Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9e0c1e8055c016ad","local-member-id":"8a5b61ed9120d6b3","added-peer-id":"8a5b61ed9120d6b3","added-peer-peer-urls":["https://162.253.55.91:2380"]} {"level":"info","ts":"2026-09-09T06:17:11.343094Z","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-09-09T06:17:11.343346Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8a5b61ed9120d6b3","initial-advertise-peer-urls":["https://162.253.55.91:2380"],"listen-peer-urls":["https://162.253.55.91:2380"],"advertise-client-urls":["https://162.253.55.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-09T06:17:11.343383Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-09T06:17:11.343484Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.91:2380"} {"level":"info","ts":"2026-09-09T06:17:11.3435Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.91:2380"} {"level":"info","ts":"2026-09-09T06:17:12.326871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 is starting a new election at term 1"} {"level":"info","ts":"2026-09-09T06:17:12.326964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-09T06:17:12.326993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 received MsgPreVoteResp from 8a5b61ed9120d6b3 at term 1"} {"level":"info","ts":"2026-09-09T06:17:12.327038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became candidate at term 2"} {"level":"info","ts":"2026-09-09T06:17:12.327048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 received MsgVoteResp from 8a5b61ed9120d6b3 at term 2"} {"level":"info","ts":"2026-09-09T06:17:12.327063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became leader at term 2"} {"level":"info","ts":"2026-09-09T06:17:12.327075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8a5b61ed9120d6b3 elected leader 8a5b61ed9120d6b3 at term 2"} {"level":"info","ts":"2026-09-09T06:17:12.328583Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8a5b61ed9120d6b3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.91:2379]}","request-path":"/0/members/8a5b61ed9120d6b3/attributes","cluster-id":"9e0c1e8055c016ad","publish-timeout":"7s"} {"level":"info","ts":"2026-09-09T06:17:12.328633Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T06:17:12.328719Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:17:12.32872Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T06:17:12.328877Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-09T06:17:12.329065Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-09T06:17:12.330308Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9e0c1e8055c016ad","local-member-id":"8a5b61ed9120d6b3","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:17:12.330507Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:17:12.330651Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:17:12.33295Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.91:2379"} {"level":"info","ts":"2026-09-09T06:17:12.333056Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-09T06:17:30.222467Z","caller":"traceutil/trace.go:171","msg":"trace[510516625] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"107.690336ms","start":"2026-09-09T06:17:30.114752Z","end":"2026-09-09T06:17:30.222442Z","steps":["trace[510516625] 'process raft request' (duration: 107.474801ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:57.707634Z","caller":"traceutil/trace.go:171","msg":"trace[723725002] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"111.457915ms","start":"2026-09-09T06:17:57.596118Z","end":"2026-09-09T06:17:57.707576Z","steps":["trace[723725002] 'process raft request' (duration: 106.486648ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:17:57.913058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.808422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vp8mv\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-09T06:17:57.913204Z","caller":"traceutil/trace.go:171","msg":"trace[1950846177] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vp8mv; range_end:; response_count:1; response_revision:595; }","duration":"148.015287ms","start":"2026-09-09T06:17:57.765164Z","end":"2026-09-09T06:17:57.913179Z","steps":["trace[1950846177] 'range keys from in-memory index tree' (duration: 147.620629ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:58.064387Z","caller":"traceutil/trace.go:171","msg":"trace[951802858] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"282.75474ms","start":"2026-09-09T06:17:57.781598Z","end":"2026-09-09T06:17:58.064353Z","steps":["trace[951802858] 'process raft request' (duration: 282.550956ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:58.151823Z","caller":"traceutil/trace.go:171","msg":"trace[1521089222] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"216.30147ms","start":"2026-09-09T06:17:57.935481Z","end":"2026-09-09T06:17:58.151782Z","steps":["trace[1521089222] 'process raft request' (duration: 177.983109ms)","trace[1521089222] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/pods/kube-system/cilium-vp8mv; req_size:17126; } (duration: 37.976953ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:17:58.567379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.884155ms","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-09-09T06:17:58.567483Z","caller":"traceutil/trace.go:171","msg":"trace[1930634994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:598; }","duration":"191.050888ms","start":"2026-09-09T06:17:58.376405Z","end":"2026-09-09T06:17:58.567456Z","steps":["trace[1930634994] 'range keys from in-memory index tree' (duration: 190.712561ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:18:13.996645Z","caller":"traceutil/trace.go:171","msg":"trace[1377161685] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"102.526875ms","start":"2026-09-09T06:18:13.894079Z","end":"2026-09-09T06:18:13.996605Z","steps":["trace[1377161685] 'process raft request' (duration: 102.29938ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:19:34.960333Z","caller":"traceutil/trace.go:171","msg":"trace[1288122036] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"243.320338ms","start":"2026-09-09T06:19:34.716939Z","end":"2026-09-09T06:19:34.960259Z","steps":["trace[1288122036] 'process raft request' (duration: 243.148854ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:19:34.976771Z","caller":"traceutil/trace.go:171","msg":"trace[534901233] linearizableReadLoop","detail":"{readStateIndex:1911; appliedIndex:1909; }","duration":"156.572541ms","start":"2026-09-09T06:19:34.820174Z","end":"2026-09-09T06:19:34.976746Z","steps":["trace[534901233] 'read index received' (duration: 140.017816ms)","trace[534901233] 'applied index is now lower than readState.Index' (duration: 16.552865ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:19:34.976912Z","caller":"traceutil/trace.go:171","msg":"trace[503208836] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"183.324863ms","start":"2026-09-09T06:19:34.793545Z","end":"2026-09-09T06:19:34.97687Z","steps":["trace[503208836] 'process raft request' (duration: 183.102459ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:19:34.977136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.931318ms","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":"info","ts":"2026-09-09T06:19:34.97727Z","caller":"traceutil/trace.go:171","msg":"trace[1726001747] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1843; }","duration":"157.098272ms","start":"2026-09-09T06:19:34.820131Z","end":"2026-09-09T06:19:34.97723Z","steps":["trace[1726001747] 'agreement among raft nodes before linearized reading' (duration: 156.754325ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:19:35.334781Z","caller":"traceutil/trace.go:171","msg":"trace[1450979874] linearizableReadLoop","detail":"{readStateIndex:1914; appliedIndex:1913; }","duration":"131.764149ms","start":"2026-09-09T06:19:35.202978Z","end":"2026-09-09T06:19:35.334742Z","steps":["trace[1450979874] 'read index received' (duration: 77.669112ms)","trace[1450979874] 'applied index is now lower than readState.Index' (duration: 54.093547ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:19:35.335052Z","caller":"traceutil/trace.go:171","msg":"trace[1769122797] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"140.884755ms","start":"2026-09-09T06:19:35.194123Z","end":"2026-09-09T06:19:35.335007Z","steps":["trace[1769122797] 'process raft request' (duration: 86.635344ms)","trace[1769122797] 'compare' (duration: 53.764311ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:19:35.335157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.141477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-09T06:19:35.33525Z","caller":"traceutil/trace.go:171","msg":"trace[657044336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1846; }","duration":"132.293761ms","start":"2026-09-09T06:19:35.202937Z","end":"2026-09-09T06:19:35.335231Z","steps":["trace[657044336] 'agreement among raft nodes before linearized reading' (duration: 132.004445ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:20:19.434025Z","caller":"traceutil/trace.go:171","msg":"trace[545459351] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"158.864608ms","start":"2026-09-09T06:20:19.275123Z","end":"2026-09-09T06:20:19.433988Z","steps":["trace[545459351] 'process raft request' (duration: 76.805873ms)","trace[545459351] 'compare' (duration: 81.581635ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:20:19.434138Z","caller":"traceutil/trace.go:171","msg":"trace[2062736897] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"158.551591ms","start":"2026-09-09T06:20:19.275553Z","end":"2026-09-09T06:20:19.434105Z","steps":["trace[2062736897] 'process raft request' (duration: 158.120412ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:25:12.591465Z","caller":"traceutil/trace.go:171","msg":"trace[66251610] linearizableReadLoop","detail":"{readStateIndex:4243; appliedIndex:4242; }","duration":"195.134463ms","start":"2026-09-09T06:25:12.396294Z","end":"2026-09-09T06:25:12.591429Z","steps":["trace[66251610] 'read index received' (duration: 194.830987ms)","trace[66251610] 'applied index is now lower than readState.Index' (duration: 301.526µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:25:12.591588Z","caller":"traceutil/trace.go:171","msg":"trace[222064748] transaction","detail":"{read_only:false; response_revision:4092; number_of_response:1; }","duration":"211.151787ms","start":"2026-09-09T06:25:12.380401Z","end":"2026-09-09T06:25:12.591553Z","steps":["trace[222064748] 'process raft request' (duration: 210.757138ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:25:12.591651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.342187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:25:12.591696Z","caller":"traceutil/trace.go:171","msg":"trace[1725986762] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4092; }","duration":"195.405638ms","start":"2026-09-09T06:25:12.396275Z","end":"2026-09-09T06:25:12.59168Z","steps":["trace[1725986762] 'agreement among raft nodes before linearized reading' (duration: 195.302616ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:26:49.917088Z","caller":"traceutil/trace.go:171","msg":"trace[1905146235] linearizableReadLoop","detail":"{readStateIndex:5406; appliedIndex:5405; }","duration":"115.749362ms","start":"2026-09-09T06:26:49.801321Z","end":"2026-09-09T06:26:49.91707Z","steps":["trace[1905146235] 'read index received' (duration: 67.375131ms)","trace[1905146235] 'applied index is now lower than readState.Index' (duration: 48.373421ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:26:49.917191Z","caller":"traceutil/trace.go:171","msg":"trace[752059909] transaction","detail":"{read_only:false; response_revision:5211; number_of_response:1; }","duration":"139.140317ms","start":"2026-09-09T06:26:49.778042Z","end":"2026-09-09T06:26:49.917183Z","steps":["trace[752059909] 'process raft request' (duration: 90.639233ms)","trace[752059909] 'compare' (duration: 48.277549ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:26:49.917602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.251483ms","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-09-09T06:26:49.917722Z","caller":"traceutil/trace.go:171","msg":"trace[530552544] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5211; }","duration":"116.420827ms","start":"2026-09-09T06:26:49.801271Z","end":"2026-09-09T06:26:49.917691Z","steps":["trace[530552544] 'agreement among raft nodes before linearized reading' (duration: 116.09747ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:26:49.925673Z","caller":"traceutil/trace.go:171","msg":"trace[1872129848] transaction","detail":"{read_only:false; response_revision:5212; number_of_response:1; }","duration":"120.540916ms","start":"2026-09-09T06:26:49.805091Z","end":"2026-09-09T06:26:49.925632Z","steps":["trace[1872129848] 'process raft request' (duration: 120.264ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:26:49.925758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.370439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-09T06:26:49.925833Z","caller":"traceutil/trace.go:171","msg":"trace[1701817281] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5212; }","duration":"111.46486ms","start":"2026-09-09T06:26:49.814348Z","end":"2026-09-09T06:26:49.925813Z","steps":["trace[1701817281] 'agreement among raft nodes before linearized reading' (duration: 111.255596ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:26:49.92608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.345796ms","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-09-09T06:26:49.926204Z","caller":"traceutil/trace.go:171","msg":"trace[798431092] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5212; }","duration":"110.54192ms","start":"2026-09-09T06:26:49.81563Z","end":"2026-09-09T06:26:49.926171Z","steps":["trace[798431092] 'agreement among raft nodes before linearized reading' (duration: 110.137362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:26:54.27864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.627427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-09T06:26:54.278916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.008165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-09T06:26:54.279014Z","caller":"traceutil/trace.go:171","msg":"trace[1996931446] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5352; }","duration":"329.144937ms","start":"2026-09-09T06:26:53.94985Z","end":"2026-09-09T06:26:54.278995Z","steps":["trace[1996931446] 'range keys from in-memory index tree' (duration: 328.844571ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:26:54.279054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:26:53.94984Z","time spent":"329.202169ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-09T06:26:54.279117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.408106ms","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-09-09T06:26:54.27922Z","caller":"traceutil/trace.go:171","msg":"trace[984545256] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5352; }","duration":"330.591319ms","start":"2026-09-09T06:26:53.9486Z","end":"2026-09-09T06:26:54.279191Z","steps":["trace[984545256] 'range keys from in-memory index tree' (duration: 330.024947ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:26:54.279312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:26:53.948565Z","time spent":"330.729643ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-09T06:26:54.279166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.558097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-drg2d\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-09-09T06:26:54.279419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.388609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786909"} {"level":"info","ts":"2026-09-09T06:26:54.279459Z","caller":"traceutil/trace.go:171","msg":"trace[1498600838] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5352; }","duration":"313.452771ms","start":"2026-09-09T06:26:53.965997Z","end":"2026-09-09T06:26:54.279449Z","steps":["trace[1498600838] 'range keys from in-memory index tree' (duration: 312.815027ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:26:54.27946Z","caller":"traceutil/trace.go:171","msg":"trace[1071458527] range","detail":"{range_begin:/registry/pods/openstack/keepalived-drg2d; range_end:; response_count:1; response_revision:5352; }","duration":"329.862474ms","start":"2026-09-09T06:26:53.949547Z","end":"2026-09-09T06:26:54.27941Z","steps":["trace[1071458527] 'range keys from in-memory index tree' (duration: 328.889813ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:26:54.279648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.421177ms","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-09-09T06:26:54.279642Z","caller":"traceutil/trace.go:171","msg":"trace[288848696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5352; }","duration":"295.815011ms","start":"2026-09-09T06:26:53.982916Z","end":"2026-09-09T06:26:54.278731Z","steps":["trace[288848696] 'range keys from in-memory index tree' (duration: 295.390872ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:26:54.27969Z","caller":"traceutil/trace.go:171","msg":"trace[1206781577] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5352; }","duration":"325.239425ms","start":"2026-09-09T06:26:53.95443Z","end":"2026-09-09T06:26:54.27967Z","steps":["trace[1206781577] 'range keys from in-memory index tree' (duration: 324.227953ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:26:54.279718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:26:53.954417Z","time spent":"325.292455ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-09T06:26:54.279681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:26:53.949535Z","time spent":"330.127869ms","remote":"127.0.0.1:37930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6544,"request content":"key:\"/registry/pods/openstack/keepalived-drg2d\" "} {"level":"warn","ts":"2026-09-09T06:26:54.279481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:26:53.965981Z","time spent":"313.493221ms","remote":"127.0.0.1:37824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":25,"response size":786933,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"warn","ts":"2026-09-09T06:26:54.279662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.883209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-09T06:26:54.279953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.102722ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:26:54.279987Z","caller":"traceutil/trace.go:171","msg":"trace[509740505] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5352; }","duration":"333.284008ms","start":"2026-09-09T06:26:53.946676Z","end":"2026-09-09T06:26:54.27996Z","steps":["trace[509740505] 'range keys from in-memory index tree' (duration: 331.931168ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:26:54.280088Z","caller":"traceutil/trace.go:171","msg":"trace[694432797] linearizableReadLoop","detail":"{readStateIndex:5552; appliedIndex:5551; }","duration":"244.895913ms","start":"2026-09-09T06:26:54.035172Z","end":"2026-09-09T06:26:54.280068Z","steps":["trace[694432797] 'read index received' (duration: 16.009474ms)","trace[694432797] 'applied index is now lower than readState.Index' (duration: 228.885019ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:26:54.28012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:26:53.946644Z","time spent":"333.459771ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-09T06:26:54.280146Z","caller":"traceutil/trace.go:171","msg":"trace[655674580] transaction","detail":"{read_only:false; response_revision:5353; number_of_response:1; }","duration":"274.695455ms","start":"2026-09-09T06:26:54.005429Z","end":"2026-09-09T06:26:54.280125Z","steps":["trace[655674580] 'process raft request' (duration: 45.784306ms)","trace[655674580] 'compare' (duration: 227.919218ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:26:54.280295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.1172ms","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-09-09T06:26:54.280365Z","caller":"traceutil/trace.go:171","msg":"trace[354860794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5353; }","duration":"245.236632ms","start":"2026-09-09T06:26:54.035116Z","end":"2026-09-09T06:26:54.280353Z","steps":["trace[354860794] 'agreement among raft nodes before linearized reading' (duration: 245.000786ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:26:54.399566Z","caller":"traceutil/trace.go:171","msg":"trace[1759323648] transaction","detail":"{read_only:false; response_revision:5357; number_of_response:1; }","duration":"114.131708ms","start":"2026-09-09T06:26:54.285402Z","end":"2026-09-09T06:26:54.399533Z","steps":["trace[1759323648] 'process raft request' (duration: 114.041136ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:26:54.399833Z","caller":"traceutil/trace.go:171","msg":"trace[1137445984] transaction","detail":"{read_only:false; response_revision:5355; number_of_response:1; }","duration":"114.773192ms","start":"2026-09-09T06:26:54.285045Z","end":"2026-09-09T06:26:54.399819Z","steps":["trace[1137445984] 'process raft request' (duration: 114.106558ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:26:54.399932Z","caller":"traceutil/trace.go:171","msg":"trace[654707896] transaction","detail":"{read_only:false; response_revision:5356; number_of_response:1; }","duration":"114.7137ms","start":"2026-09-09T06:26:54.285208Z","end":"2026-09-09T06:26:54.399922Z","steps":["trace[654707896] 'process raft request' (duration: 114.176929ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:26:54.400209Z","caller":"traceutil/trace.go:171","msg":"trace[732428767] transaction","detail":"{read_only:false; response_revision:5358; number_of_response:1; }","duration":"114.498576ms","start":"2026-09-09T06:26:54.285695Z","end":"2026-09-09T06:26:54.400194Z","steps":["trace[732428767] 'process raft request' (duration: 113.792601ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:26:54.4002Z","caller":"traceutil/trace.go:171","msg":"trace[1523807086] linearizableReadLoop","detail":"{readStateIndex:5556; appliedIndex:5552; }","duration":"114.565888ms","start":"2026-09-09T06:26:54.285606Z","end":"2026-09-09T06:26:54.400172Z","steps":["trace[1523807086] 'read index received' (duration: 80.868932ms)","trace[1523807086] 'applied index is now lower than readState.Index' (duration: 33.695756ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:26:54.400308Z","caller":"traceutil/trace.go:171","msg":"trace[1250081232] transaction","detail":"{read_only:false; response_revision:5359; number_of_response:1; }","duration":"114.506226ms","start":"2026-09-09T06:26:54.285741Z","end":"2026-09-09T06:26:54.400247Z","steps":["trace[1250081232] 'process raft request' (duration: 114.306522ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:26:54.400657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.018417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62865"} {"level":"info","ts":"2026-09-09T06:26:54.400742Z","caller":"traceutil/trace.go:171","msg":"trace[471908475] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5359; }","duration":"115.13581ms","start":"2026-09-09T06:26:54.285575Z","end":"2026-09-09T06:26:54.40071Z","steps":["trace[471908475] 'agreement among raft nodes before linearized reading' (duration: 114.70435ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:26:54.664912Z","caller":"traceutil/trace.go:171","msg":"trace[288625868] transaction","detail":"{read_only:false; response_revision:5361; number_of_response:1; }","duration":"121.818424ms","start":"2026-09-09T06:26:54.543061Z","end":"2026-09-09T06:26:54.664879Z","steps":["trace[288625868] 'process raft request' (duration: 121.597909ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:27:07.32269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.015162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:27:07.322861Z","caller":"traceutil/trace.go:171","msg":"trace[1894152741] linearizableReadLoop","detail":"{readStateIndex:5802; appliedIndex:5801; }","duration":"116.279313ms","start":"2026-09-09T06:27:07.206533Z","end":"2026-09-09T06:27:07.322813Z","steps":["trace[1894152741] 'read index received' (duration: 67.311µs)","trace[1894152741] 'applied index is now lower than readState.Index' (duration: 116.210482ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:27:07.323082Z","caller":"traceutil/trace.go:171","msg":"trace[2088023556] transaction","detail":"{read_only:false; response_revision:5595; number_of_response:1; }","duration":"134.551117ms","start":"2026-09-09T06:27:07.188485Z","end":"2026-09-09T06:27:07.323036Z","steps":["trace[2088023556] 'compare' (duration: 130.715945ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:27:07.323291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.762694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:10 size:380258"} {"level":"info","ts":"2026-09-09T06:27:07.323363Z","caller":"traceutil/trace.go:171","msg":"trace[1785207761] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:10; response_revision:5595; }","duration":"116.893017ms","start":"2026-09-09T06:27:07.206456Z","end":"2026-09-09T06:27:07.323349Z","steps":["trace[1785207761] 'agreement among raft nodes before linearized reading' (duration: 116.449317ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:27:08.759696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.182261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-09-09T06:27:08.759741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.5974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:27:08.759791Z","caller":"traceutil/trace.go:171","msg":"trace[917841155] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5608; }","duration":"317.301263ms","start":"2026-09-09T06:27:08.442468Z","end":"2026-09-09T06:27:08.759769Z","steps":["trace[917841155] 'range keys from in-memory index tree' (duration: 316.973586ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:27:08.759838Z","caller":"traceutil/trace.go:171","msg":"trace[1682451464] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:5608; }","duration":"311.717752ms","start":"2026-09-09T06:27:08.448095Z","end":"2026-09-09T06:27:08.759812Z","steps":["trace[1682451464] 'count revisions from in-memory index tree' (duration: 311.517288ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:27:08.759855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:27:08.442395Z","time spent":"317.444486ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-09T06:27:08.760196Z","caller":"traceutil/trace.go:171","msg":"trace[903772042] linearizableReadLoop","detail":"{readStateIndex:5816; appliedIndex:5815; }","duration":"261.87672ms","start":"2026-09-09T06:27:08.498286Z","end":"2026-09-09T06:27:08.760163Z","steps":["trace[903772042] 'read index received' (duration: 245.809704ms)","trace[903772042] 'applied index is now lower than readState.Index' (duration: 16.065876ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:27:08.760316Z","caller":"traceutil/trace.go:171","msg":"trace[1629090165] transaction","detail":"{read_only:false; response_revision:5609; number_of_response:1; }","duration":"264.039996ms","start":"2026-09-09T06:27:08.496262Z","end":"2026-09-09T06:27:08.760302Z","steps":["trace[1629090165] 'process raft request' (duration: 247.734455ms)","trace[1629090165] 'compare' (duration: 15.446452ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:27:08.760613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.3215ms","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-09-09T06:27:08.760671Z","caller":"traceutil/trace.go:171","msg":"trace[353556903] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5609; }","duration":"262.429402ms","start":"2026-09-09T06:27:08.498224Z","end":"2026-09-09T06:27:08.760653Z","steps":["trace[353556903] 'agreement among raft nodes before linearized reading' (duration: 262.204566ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:27:08.760887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:27:08.448063Z","time spent":"311.810864ms","remote":"127.0.0.1:38166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-09-09T06:27:08.936449Z","caller":"traceutil/trace.go:171","msg":"trace[272095067] linearizableReadLoop","detail":"{readStateIndex:5817; appliedIndex:5816; }","duration":"176.149523ms","start":"2026-09-09T06:27:08.76026Z","end":"2026-09-09T06:27:08.936409Z","steps":["trace[272095067] 'read index received' (duration: 104.258496ms)","trace[272095067] 'applied index is now lower than readState.Index' (duration: 71.889417ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:27:08.936726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.557051ms","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-09-09T06:27:08.936764Z","caller":"traceutil/trace.go:171","msg":"trace[172803403] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5610; }","duration":"430.681044ms","start":"2026-09-09T06:27:08.506069Z","end":"2026-09-09T06:27:08.93675Z","steps":["trace[172803403] 'agreement among raft nodes before linearized reading' (duration: 430.48699ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:27:08.936798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:27:08.506034Z","time spent":"430.754865ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-09T06:27:08.936777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.761557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-09-09T06:27:08.936824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.909272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-drg2d\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-09T06:27:08.936875Z","caller":"traceutil/trace.go:171","msg":"trace[1613300144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5610; }","duration":"424.89665ms","start":"2026-09-09T06:27:08.511955Z","end":"2026-09-09T06:27:08.936852Z","steps":["trace[1613300144] 'agreement among raft nodes before linearized reading' (duration: 424.546202ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:27:08.936929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:27:08.511944Z","time spent":"424.976671ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-09T06:27:08.936934Z","caller":"traceutil/trace.go:171","msg":"trace[187867748] range","detail":"{range_begin:/registry/pods/openstack/keepalived-drg2d; range_end:; response_count:1; response_revision:5610; }","duration":"407.092387ms","start":"2026-09-09T06:27:08.529812Z","end":"2026-09-09T06:27:08.936904Z","steps":["trace[187867748] 'agreement among raft nodes before linearized reading' (duration: 406.649138ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:27:08.936989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:27:08.529785Z","time spent":"407.193729ms","remote":"127.0.0.1:37930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6544,"request content":"key:\"/registry/pods/openstack/keepalived-drg2d\" "} {"level":"info","ts":"2026-09-09T06:27:08.937079Z","caller":"traceutil/trace.go:171","msg":"trace[1675733363] transaction","detail":"{read_only:false; response_revision:5610; number_of_response:1; }","duration":"405.028203ms","start":"2026-09-09T06:27:08.532037Z","end":"2026-09-09T06:27:08.937066Z","steps":["trace[1675733363] 'process raft request' (duration: 332.510402ms)","trace[1675733363] 'compare' (duration: 71.686563ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:27:08.937186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:27:08.532014Z","time spent":"405.095924ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:27:08.937207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.792273ms","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-09-09T06:27:08.937301Z","caller":"traceutil/trace.go:171","msg":"trace[394672697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5610; }","duration":"408.979038ms","start":"2026-09-09T06:27:08.528298Z","end":"2026-09-09T06:27:08.937277Z","steps":["trace[394672697] 'agreement among raft nodes before linearized reading' (duration: 408.614129ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:27:08.937304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.446906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-09T06:27:08.937379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:27:08.52827Z","time spent":"409.09918ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-09T06:27:08.937432Z","caller":"traceutil/trace.go:171","msg":"trace[631769114] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5610; }","duration":"434.632659ms","start":"2026-09-09T06:27:08.502773Z","end":"2026-09-09T06:27:08.937405Z","steps":["trace[631769114] 'agreement among raft nodes before linearized reading' (duration: 434.190299ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:27:08.937528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:27:08.502758Z","time spent":"434.758144ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-09T06:27:08.944856Z","caller":"traceutil/trace.go:171","msg":"trace[951790851] transaction","detail":"{read_only:false; response_revision:5611; number_of_response:1; }","duration":"180.195171ms","start":"2026-09-09T06:27:08.764626Z","end":"2026-09-09T06:27:08.944822Z","steps":["trace[951790851] 'process raft request' (duration: 180.006367ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:27:08.945135Z","caller":"traceutil/trace.go:171","msg":"trace[1741998520] transaction","detail":"{read_only:false; response_revision:5612; number_of_response:1; }","duration":"179.097047ms","start":"2026-09-09T06:27:08.766009Z","end":"2026-09-09T06:27:08.945106Z","steps":["trace[1741998520] 'process raft request' (duration: 178.755659ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:27:08.945282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.591707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-09-09T06:27:08.945366Z","caller":"traceutil/trace.go:171","msg":"trace[1591231540] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5612; }","duration":"153.71582ms","start":"2026-09-09T06:27:08.791635Z","end":"2026-09-09T06:27:08.945351Z","steps":["trace[1591231540] 'agreement among raft nodes before linearized reading' (duration: 153.499385ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:27:12.367736Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2954} {"level":"info","ts":"2026-09-09T06:27:12.450075Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2954,"took":"80.674158ms","hash":884540029,"current-db-size-bytes":55046144,"current-db-size":"55 MB","current-db-size-in-use-bytes":31072256,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-09T06:27:12.450194Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":884540029,"revision":2954,"compact-revision":-1} {"level":"info","ts":"2026-09-09T06:32:12.375723Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5678} {"level":"info","ts":"2026-09-09T06:32:12.468107Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5678,"took":"90.133621ms","hash":2137900952,"current-db-size-bytes":55046144,"current-db-size":"55 MB","current-db-size-in-use-bytes":25706496,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-09T06:32:12.468193Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2137900952,"revision":5678,"compact-revision":2954} {"level":"info","ts":"2026-09-09T06:34:14.451173Z","caller":"traceutil/trace.go:171","msg":"trace[1139804845] transaction","detail":"{read_only:false; response_revision:8974; number_of_response:1; }","duration":"137.245043ms","start":"2026-09-09T06:34:14.313886Z","end":"2026-09-09T06:34:14.451131Z","steps":["trace[1139804845] 'process raft request' (duration: 73.765776ms)","trace[1139804845] 'compare' (duration: 63.225021ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:34:15.264772Z","caller":"traceutil/trace.go:171","msg":"trace[371535328] transaction","detail":"{read_only:false; response_revision:8978; number_of_response:1; }","duration":"121.045541ms","start":"2026-09-09T06:34:15.143704Z","end":"2026-09-09T06:34:15.26475Z","steps":["trace[371535328] 'process raft request' (duration: 60.690794ms)","trace[371535328] 'compare' (duration: 60.194773ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:34:15.264882Z","caller":"traceutil/trace.go:171","msg":"trace[272355457] transaction","detail":"{read_only:false; response_revision:8979; number_of_response:1; }","duration":"102.29194ms","start":"2026-09-09T06:34:15.16256Z","end":"2026-09-09T06:34:15.264851Z","steps":["trace[272355457] 'process raft request' (duration: 102.153307ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:34:35.574565Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002528.wal"} {"level":"info","ts":"2026-09-09T06:35:21.861388Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8a5b61ed9120d6b3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-09T06:35:21.87346Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-09T06:35:21.873605Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-09-09T06:35:36.409226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.538326ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:35:36.40939Z","caller":"traceutil/trace.go:171","msg":"trace[1747971952] transaction","detail":"{read_only:false; response_revision:9749; number_of_response:1; }","duration":"158.436601ms","start":"2026-09-09T06:35:36.250931Z","end":"2026-09-09T06:35:36.409368Z","steps":["trace[1747971952] 'process raft request' (duration: 47.62353ms)","trace[1747971952] 'compare' (duration: 110.193578ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:35:36.530791Z","caller":"traceutil/trace.go:171","msg":"trace[339838028] transaction","detail":"{read_only:false; response_revision:9750; number_of_response:1; }","duration":"279.273085ms","start":"2026-09-09T06:35:36.251491Z","end":"2026-09-09T06:35:36.530764Z","steps":["trace[339838028] 'process raft request' (duration: 263.917577ms)","trace[339838028] 'compare' (duration: 15.179454ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:35:36.531032Z","caller":"traceutil/trace.go:171","msg":"trace[603446176] linearizableReadLoop","detail":"{readStateIndex:10093; appliedIndex:10091; }","duration":"121.634202ms","start":"2026-09-09T06:35:36.409336Z","end":"2026-09-09T06:35:36.53097Z","steps":["trace[603446176] 'read index received' (duration: 105.969387ms)","trace[603446176] 'applied index is now lower than readState.Index' (duration: 15.661365ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:35:36.531065Z","caller":"traceutil/trace.go:171","msg":"trace[167544809] transaction","detail":"{read_only:false; response_revision:9751; number_of_response:1; }","duration":"279.505959ms","start":"2026-09-09T06:35:36.251524Z","end":"2026-09-09T06:35:36.53103Z","steps":["trace[167544809] 'process raft request' (duration: 279.192813ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:35:36.531427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.058421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-drg2d\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-09T06:35:36.531482Z","caller":"traceutil/trace.go:171","msg":"trace[1849552042] range","detail":"{range_begin:/registry/pods/openstack/keepalived-drg2d; range_end:; response_count:1; response_revision:9751; }","duration":"122.191804ms","start":"2026-09-09T06:35:36.409276Z","end":"2026-09-09T06:35:36.531468Z","steps":["trace[1849552042] 'agreement among raft nodes before linearized reading' (duration: 121.855856ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:12.385532Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7996} {"level":"info","ts":"2026-09-09T06:37:12.46883Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7996,"took":"80.697867ms","hash":4198620001,"current-db-size-bytes":55046144,"current-db-size":"55 MB","current-db-size-in-use-bytes":25563136,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-09T06:37:12.468944Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4198620001,"revision":7996,"compact-revision":5678} {"level":"info","ts":"2026-09-09T06:37:18.351578Z","caller":"traceutil/trace.go:171","msg":"trace[239674657] transaction","detail":"{read_only:false; response_revision:10464; number_of_response:1; }","duration":"118.72896ms","start":"2026-09-09T06:37:18.232821Z","end":"2026-09-09T06:37:18.35155Z","steps":["trace[239674657] 'process raft request' (duration: 118.583717ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:18.606416Z","caller":"traceutil/trace.go:171","msg":"trace[1087829137] linearizableReadLoop","detail":"{readStateIndex:10830; appliedIndex:10828; }","duration":"230.171695ms","start":"2026-09-09T06:37:18.376206Z","end":"2026-09-09T06:37:18.606378Z","steps":["trace[1087829137] 'read index received' (duration: 24.186907ms)","trace[1087829137] 'applied index is now lower than readState.Index' (duration: 205.982558ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:18.606543Z","caller":"traceutil/trace.go:171","msg":"trace[1824958878] transaction","detail":"{read_only:false; response_revision:10466; number_of_response:1; }","duration":"231.429882ms","start":"2026-09-09T06:37:18.375056Z","end":"2026-09-09T06:37:18.606486Z","steps":["trace[1824958878] 'process raft request' (duration: 162.986458ms)","trace[1824958878] 'compare' (duration: 67.804991ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:18.606772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.431142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/ingress-nginx/ingress-nginx-controller-h27v5.18d392ae3f577773\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-09-09T06:37:18.606853Z","caller":"traceutil/trace.go:171","msg":"trace[2392967] range","detail":"{range_begin:/registry/events/ingress-nginx/ingress-nginx-controller-h27v5.18d392ae3f577773; range_end:; response_count:1; response_revision:10466; }","duration":"167.556055ms","start":"2026-09-09T06:37:18.439252Z","end":"2026-09-09T06:37:18.606808Z","steps":["trace[2392967] 'agreement among raft nodes before linearized reading' (duration: 167.31128ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:18.606671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.4368ms","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-09-09T06:37:18.606963Z","caller":"traceutil/trace.go:171","msg":"trace[1626767491] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:10466; }","duration":"230.757917ms","start":"2026-09-09T06:37:18.376149Z","end":"2026-09-09T06:37:18.606907Z","steps":["trace[1626767491] 'agreement among raft nodes before linearized reading' (duration: 230.393059ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:19.11631Z","caller":"traceutil/trace.go:171","msg":"trace[589858230] transaction","detail":"{read_only:false; response_revision:10478; number_of_response:1; }","duration":"147.869954ms","start":"2026-09-09T06:37:18.968352Z","end":"2026-09-09T06:37:19.116222Z","steps":["trace[589858230] 'process raft request' (duration: 130.777219ms)","trace[589858230] 'compare' (duration: 16.469392ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:19.711165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.277872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:37:19.711341Z","caller":"traceutil/trace.go:171","msg":"trace[784107622] transaction","detail":"{read_only:false; response_revision:10480; number_of_response:1; }","duration":"588.226025ms","start":"2026-09-09T06:37:19.123085Z","end":"2026-09-09T06:37:19.711311Z","steps":["trace[784107622] 'process raft request' (duration: 238.618326ms)","trace[784107622] 'compare' (duration: 349.065808ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:19.71147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:19.123066Z","time spent":"588.357359ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:37:19.794884Z","caller":"traceutil/trace.go:171","msg":"trace[282149389] linearizableReadLoop","detail":"{readStateIndex:10848; appliedIndex:10844; }","duration":"662.125676ms","start":"2026-09-09T06:37:19.132732Z","end":"2026-09-09T06:37:19.794857Z","steps":["trace[282149389] 'read index received' (duration: 228.990649ms)","trace[282149389] 'applied index is now lower than readState.Index' (duration: 433.133777ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:19.795115Z","caller":"traceutil/trace.go:171","msg":"trace[2053959942] transaction","detail":"{read_only:false; response_revision:10481; number_of_response:1; }","duration":"667.752347ms","start":"2026-09-09T06:37:19.127343Z","end":"2026-09-09T06:37:19.795096Z","steps":["trace[2053959942] 'process raft request' (duration: 667.072113ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:19.795858Z","caller":"traceutil/trace.go:171","msg":"trace[776099686] transaction","detail":"{read_only:false; response_revision:10484; number_of_response:1; }","duration":"663.073007ms","start":"2026-09-09T06:37:19.132747Z","end":"2026-09-09T06:37:19.79582Z","steps":["trace[776099686] 'process raft request' (duration: 661.905702ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:19.795991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:19.127328Z","time spent":"668.575905ms","remote":"127.0.0.1:37808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":808,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-09T06:37:19.795729Z","caller":"traceutil/trace.go:171","msg":"trace[447770548] transaction","detail":"{read_only:false; response_revision:10486; number_of_response:1; }","duration":"661.733519ms","start":"2026-09-09T06:37:19.133976Z","end":"2026-09-09T06:37:19.795709Z","steps":["trace[447770548] 'process raft request' (duration: 660.742317ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:19.796354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:19.133962Z","time spent":"662.338691ms","remote":"127.0.0.1:37808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-09T06:37:19.79577Z","caller":"traceutil/trace.go:171","msg":"trace[1822761942] transaction","detail":"{read_only:false; response_revision:10482; number_of_response:1; }","duration":"663.154729ms","start":"2026-09-09T06:37:19.132605Z","end":"2026-09-09T06:37:19.79576Z","steps":["trace[1822761942] 'process raft request' (duration: 661.957323ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:19.7958Z","caller":"traceutil/trace.go:171","msg":"trace[1929478792] transaction","detail":"{read_only:false; response_revision:10483; number_of_response:1; }","duration":"663.098748ms","start":"2026-09-09T06:37:19.132692Z","end":"2026-09-09T06:37:19.795791Z","steps":["trace[1929478792] 'process raft request' (duration: 661.927082ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:19.796692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:19.132652Z","time spent":"663.988577ms","remote":"127.0.0.1:37808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-09T06:37:19.795829Z","caller":"traceutil/trace.go:171","msg":"trace[747997731] transaction","detail":"{read_only:false; response_revision:10485; number_of_response:1; }","duration":"662.875433ms","start":"2026-09-09T06:37:19.132941Z","end":"2026-09-09T06:37:19.795817Z","steps":["trace[747997731] 'process raft request' (duration: 661.745869ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:19.796741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:19.132581Z","time spent":"663.957616ms","remote":"127.0.0.1:37808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":784,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-09T06:37:19.796841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:19.132926Z","time spent":"663.839983ms","remote":"127.0.0.1:37808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":814,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-09T06:37:19.795803Z","caller":"traceutil/trace.go:171","msg":"trace[1432621644] transaction","detail":"{read_only:false; response_revision:10487; number_of_response:1; }","duration":"654.32485ms","start":"2026-09-09T06:37:19.141428Z","end":"2026-09-09T06:37:19.795753Z","steps":["trace[1432621644] 'process raft request' (duration: 653.326778ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:19.79599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:19.132711Z","time spent":"663.20994ms","remote":"127.0.0.1:37808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":832,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-09T06:37:19.797051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.292563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-09-09T06:37:19.797089Z","caller":"traceutil/trace.go:171","msg":"trace[1095436512] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:10487; }","duration":"664.396325ms","start":"2026-09-09T06:37:19.132681Z","end":"2026-09-09T06:37:19.797078Z","steps":["trace[1095436512] 'agreement among raft nodes before linearized reading' (duration: 663.168689ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:19.797131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:19.132666Z","time spent":"664.457767ms","remote":"127.0.0.1:37824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":1,"response size":674,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" "} {"level":"warn","ts":"2026-09-09T06:37:19.797129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:19.141411Z","time spent":"655.486755ms","remote":"127.0.0.1:46822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3745,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:37:20.028348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.156428ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:37:20.028549Z","caller":"traceutil/trace.go:171","msg":"trace[767310560] linearizableReadLoop","detail":"{readStateIndex:10853; appliedIndex:10852; }","duration":"233.563288ms","start":"2026-09-09T06:37:19.79495Z","end":"2026-09-09T06:37:20.028513Z","steps":["trace[767310560] 'read index received' (duration: 43.166634ms)","trace[767310560] 'applied index is now lower than readState.Index' (duration: 190.394314ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:20.028714Z","caller":"traceutil/trace.go:171","msg":"trace[1917291598] transaction","detail":"{read_only:false; response_revision:10488; number_of_response:1; }","duration":"549.252832ms","start":"2026-09-09T06:37:19.479435Z","end":"2026-09-09T06:37:20.028688Z","steps":["trace[1917291598] 'process raft request' (duration: 358.664224ms)","trace[1917291598] 'compare' (duration: 190.026925ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:20.029308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.958831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-09T06:37:20.029386Z","caller":"traceutil/trace.go:171","msg":"trace[605338437] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10490; }","duration":"868.099984ms","start":"2026-09-09T06:37:19.161264Z","end":"2026-09-09T06:37:20.029364Z","steps":["trace[605338437] 'agreement among raft nodes before linearized reading' (duration: 867.386079ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:20.029449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:19.161242Z","time spent":"868.190306ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-09T06:37:20.029841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.617842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-drg2d\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-09T06:37:20.029911Z","caller":"traceutil/trace.go:171","msg":"trace[622299939] range","detail":"{range_begin:/registry/pods/openstack/keepalived-drg2d; range_end:; response_count:1; response_revision:10490; }","duration":"723.756805ms","start":"2026-09-09T06:37:19.306138Z","end":"2026-09-09T06:37:20.029894Z","steps":["trace[622299939] 'agreement among raft nodes before linearized reading' (duration: 722.491768ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:20.029994Z","caller":"traceutil/trace.go:171","msg":"trace[827271742] transaction","detail":"{read_only:false; response_revision:10491; number_of_response:1; }","duration":"213.487068ms","start":"2026-09-09T06:37:19.816492Z","end":"2026-09-09T06:37:20.029979Z","steps":["trace[827271742] 'process raft request' (duration: 212.529798ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:20.030174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:19.306098Z","time spent":"723.854177ms","remote":"127.0.0.1:37930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6544,"request content":"key:\"/registry/pods/openstack/keepalived-drg2d\" "} {"level":"warn","ts":"2026-09-09T06:37:20.030302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.014212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225401"} {"level":"info","ts":"2026-09-09T06:37:20.030402Z","caller":"traceutil/trace.go:171","msg":"trace[2137948546] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:10489; }","duration":"682.173275ms","start":"2026-09-09T06:37:19.348205Z","end":"2026-09-09T06:37:20.030379Z","steps":["trace[2137948546] 'agreement among raft nodes before linearized reading' (duration: 680.353997ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:20.030455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:19.348179Z","time spent":"682.265258ms","remote":"127.0.0.1:37828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2225425,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-09-09T06:37:20.029915Z","caller":"traceutil/trace.go:171","msg":"trace[1095330782] transaction","detail":"{read_only:false; response_revision:10490; number_of_response:1; }","duration":"224.609836ms","start":"2026-09-09T06:37:19.805287Z","end":"2026-09-09T06:37:20.029897Z","steps":["trace[1095330782] 'process raft request' (duration: 223.317439ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:20.029548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.885234ms","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-09-09T06:37:20.030793Z","caller":"traceutil/trace.go:171","msg":"trace[629282982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10490; }","duration":"887.173412ms","start":"2026-09-09T06:37:19.143602Z","end":"2026-09-09T06:37:20.030775Z","steps":["trace[629282982] 'agreement among raft nodes before linearized reading' (duration: 885.061057ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:20.031113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:19.479408Z","time spent":"549.524438ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:37:20.031103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:19.143591Z","time spent":"887.495919ms","remote":"127.0.0.1:38044","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":"info","ts":"2026-09-09T06:37:20.031305Z","caller":"traceutil/trace.go:171","msg":"trace[1657910762] transaction","detail":"{read_only:false; response_revision:10489; number_of_response:1; }","duration":"223.88798ms","start":"2026-09-09T06:37:19.804773Z","end":"2026-09-09T06:37:20.028661Z","steps":["trace[1657910762] 'process raft request' (duration: 223.682346ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:20.030336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.007442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:37:20.031834Z","caller":"traceutil/trace.go:171","msg":"trace[982446041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10491; }","duration":"332.131037ms","start":"2026-09-09T06:37:19.699277Z","end":"2026-09-09T06:37:20.031408Z","steps":["trace[982446041] 'agreement among raft nodes before linearized reading' (duration: 329.595522ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:20.031911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:19.699253Z","time spent":"332.618867ms","remote":"127.0.0.1:37716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-09T06:37:20.287693Z","caller":"traceutil/trace.go:171","msg":"trace[1818713881] transaction","detail":"{read_only:false; response_revision:10494; number_of_response:1; }","duration":"246.32219ms","start":"2026-09-09T06:37:20.041343Z","end":"2026-09-09T06:37:20.287666Z","steps":["trace[1818713881] 'process raft request' (duration: 246.279239ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:20.288058Z","caller":"traceutil/trace.go:171","msg":"trace[522349043] linearizableReadLoop","detail":"{readStateIndex:10857; appliedIndex:10856; }","duration":"259.416229ms","start":"2026-09-09T06:37:20.028595Z","end":"2026-09-09T06:37:20.288012Z","steps":["trace[522349043] 'read index received' (duration: 197.789101ms)","trace[522349043] 'applied index is now lower than readState.Index' (duration: 61.625068ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:20.288062Z","caller":"traceutil/trace.go:171","msg":"trace[225899129] transaction","detail":"{read_only:false; response_revision:10493; number_of_response:1; }","duration":"246.78999ms","start":"2026-09-09T06:37:20.041224Z","end":"2026-09-09T06:37:20.288014Z","steps":["trace[225899129] 'process raft request' (duration: 246.344781ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:20.288018Z","caller":"traceutil/trace.go:171","msg":"trace[437429182] transaction","detail":"{read_only:false; response_revision:10492; number_of_response:1; }","duration":"333.999156ms","start":"2026-09-09T06:37:19.953966Z","end":"2026-09-09T06:37:20.287965Z","steps":["trace[437429182] 'process raft request' (duration: 272.401639ms)","trace[437429182] 'compare' (duration: 60.998795ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:20.288386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.099056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/glance-api-certs\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:37:20.288422Z","caller":"traceutil/trace.go:171","msg":"trace[1656489799] range","detail":"{range_begin:/registry/secrets/openstack/glance-api-certs; range_end:; response_count:0; response_revision:10494; }","duration":"247.182069ms","start":"2026-09-09T06:37:20.041231Z","end":"2026-09-09T06:37:20.288413Z","steps":["trace[1656489799] 'agreement among raft nodes before linearized reading' (duration: 246.938464ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:20.288419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:19.953936Z","time spent":"334.348703ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:37:20.28843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.433125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:37:20.288428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.297542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:37:20.288488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.275769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-09-09T06:37:20.288508Z","caller":"traceutil/trace.go:171","msg":"trace[1807405330] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:10494; }","duration":"484.550697ms","start":"2026-09-09T06:37:19.803934Z","end":"2026-09-09T06:37:20.288484Z","steps":["trace[1807405330] 'agreement among raft nodes before linearized reading' (duration: 484.21753ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:20.288522Z","caller":"traceutil/trace.go:171","msg":"trace[293012716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10494; }","duration":"255.429545ms","start":"2026-09-09T06:37:20.033067Z","end":"2026-09-09T06:37:20.288497Z","steps":["trace[293012716] 'agreement among raft nodes before linearized reading' (duration: 255.106228ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:20.28858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:19.803912Z","time spent":"484.654909ms","remote":"127.0.0.1:37824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" "} {"level":"info","ts":"2026-09-09T06:37:20.288571Z","caller":"traceutil/trace.go:171","msg":"trace[1248916843] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:10494; }","duration":"203.410982ms","start":"2026-09-09T06:37:20.08514Z","end":"2026-09-09T06:37:20.288551Z","steps":["trace[1248916843] 'agreement among raft nodes before linearized reading' (duration: 203.161656ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:20.288748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.948385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:37 size:347294"} {"level":"info","ts":"2026-09-09T06:37:20.288799Z","caller":"traceutil/trace.go:171","msg":"trace[1614075552] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:37; response_revision:10494; }","duration":"248.084139ms","start":"2026-09-09T06:37:20.040698Z","end":"2026-09-09T06:37:20.288782Z","steps":["trace[1614075552] 'agreement among raft nodes before linearized reading' (duration: 247.523106ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:20.535603Z","caller":"traceutil/trace.go:171","msg":"trace[328852356] transaction","detail":"{read_only:false; number_of_response:1; response_revision:10496; }","duration":"145.040194ms","start":"2026-09-09T06:37:20.390532Z","end":"2026-09-09T06:37:20.535572Z","steps":["trace[328852356] 'process raft request' (duration: 144.88825ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:20.535911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.864272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:37:20.536162Z","caller":"traceutil/trace.go:171","msg":"trace[144987396] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:10496; }","duration":"147.115188ms","start":"2026-09-09T06:37:20.388943Z","end":"2026-09-09T06:37:20.536058Z","steps":["trace[144987396] 'agreement among raft nodes before linearized reading' (duration: 146.806641ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:20.657637Z","caller":"traceutil/trace.go:171","msg":"trace[425858182] linearizableReadLoop","detail":"{readStateIndex:10863; appliedIndex:10862; }","duration":"121.888968ms","start":"2026-09-09T06:37:20.535706Z","end":"2026-09-09T06:37:20.657595Z","steps":["trace[425858182] 'read index received' (duration: 121.375447ms)","trace[425858182] 'applied index is now lower than readState.Index' (duration: 511.951µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:20.657701Z","caller":"traceutil/trace.go:171","msg":"trace[597684633] transaction","detail":"{read_only:false; response_revision:10497; number_of_response:1; }","duration":"166.739907ms","start":"2026-09-09T06:37:20.490918Z","end":"2026-09-09T06:37:20.657658Z","steps":["trace[597684633] 'process raft request' (duration: 166.39541ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:20.657959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.161197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4725"} {"level":"info","ts":"2026-09-09T06:37:20.658064Z","caller":"traceutil/trace.go:171","msg":"trace[487558766] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:10497; }","duration":"160.273059ms","start":"2026-09-09T06:37:20.497747Z","end":"2026-09-09T06:37:20.65802Z","steps":["trace[487558766] 'agreement among raft nodes before linearized reading' (duration: 159.934132ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:20.658154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.617876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/glance-api-certs-4wc92\" ","response":"range_response_count:1 size:2466"} {"level":"info","ts":"2026-09-09T06:37:20.658213Z","caller":"traceutil/trace.go:171","msg":"trace[1268723916] range","detail":"{range_begin:/registry/secrets/openstack/glance-api-certs-4wc92; range_end:; response_count:1; response_revision:10497; }","duration":"167.735638ms","start":"2026-09-09T06:37:20.490462Z","end":"2026-09-09T06:37:20.658197Z","steps":["trace[1268723916] 'agreement among raft nodes before linearized reading' (duration: 167.306339ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:20.675153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.220811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-09-09T06:37:20.675224Z","caller":"traceutil/trace.go:171","msg":"trace[1265970692] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:10498; }","duration":"134.343024ms","start":"2026-09-09T06:37:20.540865Z","end":"2026-09-09T06:37:20.675208Z","steps":["trace[1265970692] 'agreement among raft nodes before linearized reading' (duration: 134.123619ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:20.675588Z","caller":"traceutil/trace.go:171","msg":"trace[873059521] transaction","detail":"{read_only:false; response_revision:10498; number_of_response:1; }","duration":"116.877381ms","start":"2026-09-09T06:37:20.558697Z","end":"2026-09-09T06:37:20.675574Z","steps":["trace[873059521] 'process raft request' (duration: 116.077524ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:20.675828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.000863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-09T06:37:20.675868Z","caller":"traceutil/trace.go:171","msg":"trace[1775124332] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:10498; }","duration":"130.089654ms","start":"2026-09-09T06:37:20.545768Z","end":"2026-09-09T06:37:20.675858Z","steps":["trace[1775124332] 'agreement among raft nodes before linearized reading' (duration: 129.966262ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:20.828092Z","caller":"traceutil/trace.go:171","msg":"trace[1111307804] linearizableReadLoop","detail":"{readStateIndex:10866; appliedIndex:10865; }","duration":"119.869806ms","start":"2026-09-09T06:37:20.708195Z","end":"2026-09-09T06:37:20.828065Z","steps":["trace[1111307804] 'read index received' (duration: 73.34289ms)","trace[1111307804] 'applied index is now lower than readState.Index' (duration: 46.525545ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:20.828183Z","caller":"traceutil/trace.go:171","msg":"trace[2101877127] transaction","detail":"{read_only:false; response_revision:10500; number_of_response:1; }","duration":"134.208722ms","start":"2026-09-09T06:37:20.693908Z","end":"2026-09-09T06:37:20.828117Z","steps":["trace[2101877127] 'process raft request' (duration: 87.737317ms)","trace[2101877127] 'compare' (duration: 46.150018ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:20.828367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.290528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-09-09T06:37:20.828421Z","caller":"traceutil/trace.go:171","msg":"trace[1793292628] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:10500; }","duration":"145.401131ms","start":"2026-09-09T06:37:20.682999Z","end":"2026-09-09T06:37:20.8284Z","steps":["trace[1793292628] 'agreement among raft nodes before linearized reading' (duration: 145.209077ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:20.828352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.128513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-09-09T06:37:20.828606Z","caller":"traceutil/trace.go:171","msg":"trace[64576873] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:10500; }","duration":"115.401609ms","start":"2026-09-09T06:37:20.713145Z","end":"2026-09-09T06:37:20.828547Z","steps":["trace[64576873] 'agreement among raft nodes before linearized reading' (duration: 115.078802ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:20.828643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.614691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/openstack/glance-api-certs\" ","response":"range_response_count:1 size:2442"} {"level":"info","ts":"2026-09-09T06:37:20.828774Z","caller":"traceutil/trace.go:171","msg":"trace[329165466] range","detail":"{range_begin:/registry/cert-manager.io/certificates/openstack/glance-api-certs; range_end:; response_count:1; response_revision:10500; }","duration":"106.788945ms","start":"2026-09-09T06:37:20.721966Z","end":"2026-09-09T06:37:20.828755Z","steps":["trace[329165466] 'agreement among raft nodes before linearized reading' (duration: 106.521649ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:21.362112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.852262ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:37:21.362306Z","caller":"traceutil/trace.go:171","msg":"trace[439309707] linearizableReadLoop","detail":"{readStateIndex:10873; appliedIndex:10872; }","duration":"129.395819ms","start":"2026-09-09T06:37:21.232876Z","end":"2026-09-09T06:37:21.362272Z","steps":["trace[439309707] 'read index received' (duration: 18.169989ms)","trace[439309707] 'applied index is now lower than readState.Index' (duration: 111.22294ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:21.36232Z","caller":"traceutil/trace.go:171","msg":"trace[1371081645] transaction","detail":"{read_only:false; response_revision:10507; number_of_response:1; }","duration":"142.652953ms","start":"2026-09-09T06:37:21.219617Z","end":"2026-09-09T06:37:21.36227Z","steps":["trace[1371081645] 'process raft request' (duration: 31.502534ms)","trace[1371081645] 'compare' (duration: 110.712479ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:21.425274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.356445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:37:21.425362Z","caller":"traceutil/trace.go:171","msg":"trace[1720174610] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:10507; }","duration":"192.529429ms","start":"2026-09-09T06:37:21.232808Z","end":"2026-09-09T06:37:21.425338Z","steps":["trace[1720174610] 'agreement among raft nodes before linearized reading' (duration: 129.611223ms)","trace[1720174610] 'range keys from in-memory index tree' (duration: 62.764583ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:21.426814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.427675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225401"} {"level":"info","ts":"2026-09-09T06:37:21.426952Z","caller":"traceutil/trace.go:171","msg":"trace[83288025] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:10507; }","duration":"190.599928ms","start":"2026-09-09T06:37:21.236326Z","end":"2026-09-09T06:37:21.426926Z","steps":["trace[83288025] 'agreement among raft nodes before linearized reading' (duration: 188.8459ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:21.680133Z","caller":"traceutil/trace.go:171","msg":"trace[800952571] transaction","detail":"{read_only:false; response_revision:10508; number_of_response:1; }","duration":"131.619287ms","start":"2026-09-09T06:37:21.548486Z","end":"2026-09-09T06:37:21.680105Z","steps":["trace[800952571] 'process raft request' (duration: 131.29292ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:22.54997Z","caller":"traceutil/trace.go:171","msg":"trace[1529742569] transaction","detail":"{read_only:false; response_revision:10512; number_of_response:1; }","duration":"253.945223ms","start":"2026-09-09T06:37:22.295999Z","end":"2026-09-09T06:37:22.549944Z","steps":["trace[1529742569] 'process raft request' (duration: 187.479731ms)","trace[1529742569] 'compare' (duration: 66.134845ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:22.550266Z","caller":"traceutil/trace.go:171","msg":"trace[669207884] linearizableReadLoop","detail":"{readStateIndex:10880; appliedIndex:10878; }","duration":"250.938849ms","start":"2026-09-09T06:37:22.299302Z","end":"2026-09-09T06:37:22.550241Z","steps":["trace[669207884] 'read index received' (duration: 38.631477ms)","trace[669207884] 'applied index is now lower than readState.Index' (duration: 212.305912ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:22.550371Z","caller":"traceutil/trace.go:171","msg":"trace[1015349231] transaction","detail":"{read_only:false; response_revision:10513; number_of_response:1; }","duration":"252.40475ms","start":"2026-09-09T06:37:22.297937Z","end":"2026-09-09T06:37:22.550341Z","steps":["trace[1015349231] 'process raft request' (duration: 251.91339ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:22.550664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.346857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-drg2d\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-09T06:37:22.55074Z","caller":"traceutil/trace.go:171","msg":"trace[1740514434] range","detail":"{range_begin:/registry/pods/openstack/keepalived-drg2d; range_end:; response_count:1; response_revision:10513; }","duration":"251.46029ms","start":"2026-09-09T06:37:22.29926Z","end":"2026-09-09T06:37:22.55072Z","steps":["trace[1740514434] 'agreement among raft nodes before linearized reading' (duration: 251.123883ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:23.363066Z","caller":"traceutil/trace.go:171","msg":"trace[1547806222] linearizableReadLoop","detail":"{readStateIndex:10884; appliedIndex:10883; }","duration":"200.361907ms","start":"2026-09-09T06:37:23.16265Z","end":"2026-09-09T06:37:23.363012Z","steps":["trace[1547806222] 'read index received' (duration: 200.060621ms)","trace[1547806222] 'applied index is now lower than readState.Index' (duration: 300.186µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:23.363143Z","caller":"traceutil/trace.go:171","msg":"trace[764442071] transaction","detail":"{read_only:false; response_revision:10517; number_of_response:1; }","duration":"213.907277ms","start":"2026-09-09T06:37:23.149183Z","end":"2026-09-09T06:37:23.363091Z","steps":["trace[764442071] 'process raft request' (duration: 213.58863ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:23.363283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.327474ms","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":"info","ts":"2026-09-09T06:37:23.363327Z","caller":"traceutil/trace.go:171","msg":"trace[1263001651] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:10517; }","duration":"213.392236ms","start":"2026-09-09T06:37:23.149922Z","end":"2026-09-09T06:37:23.363314Z","steps":["trace[1263001651] 'agreement among raft nodes before linearized reading' (duration: 213.226882ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:23.49735Z","caller":"traceutil/trace.go:171","msg":"trace[1049349459] transaction","detail":"{read_only:false; response_revision:10518; number_of_response:1; }","duration":"127.54639ms","start":"2026-09-09T06:37:23.369757Z","end":"2026-09-09T06:37:23.497303Z","steps":["trace[1049349459] 'process raft request' (duration: 94.554683ms)","trace[1049349459] 'compare' (duration: 32.788482ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:24.224872Z","caller":"traceutil/trace.go:171","msg":"trace[1902560003] transaction","detail":"{read_only:false; response_revision:10520; number_of_response:1; }","duration":"145.931003ms","start":"2026-09-09T06:37:24.078893Z","end":"2026-09-09T06:37:24.224824Z","steps":["trace[1902560003] 'process raft request' (duration: 145.643756ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:24.717864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.635207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-drg2d\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-09T06:37:24.717949Z","caller":"traceutil/trace.go:171","msg":"trace[1334157799] range","detail":"{range_begin:/registry/pods/openstack/keepalived-drg2d; range_end:; response_count:1; response_revision:10521; }","duration":"152.767629ms","start":"2026-09-09T06:37:24.565161Z","end":"2026-09-09T06:37:24.717929Z","steps":["trace[1334157799] 'range keys from in-memory index tree' (duration: 152.390921ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:24.718123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.965812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:37:24.718195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.429513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-09-09T06:37:24.718196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.994439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-09T06:37:24.718272Z","caller":"traceutil/trace.go:171","msg":"trace[1552607155] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:10521; }","duration":"189.556476ms","start":"2026-09-09T06:37:24.528696Z","end":"2026-09-09T06:37:24.718253Z","steps":["trace[1552607155] 'range keys from in-memory index tree' (duration: 189.27527ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:24.71827Z","caller":"traceutil/trace.go:171","msg":"trace[728683312] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:10521; }","duration":"166.147285ms","start":"2026-09-09T06:37:24.552081Z","end":"2026-09-09T06:37:24.718228Z","steps":["trace[728683312] 'count revisions from in-memory index tree' (duration: 165.86144ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:24.718262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.04901ms","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-09-09T06:37:24.718347Z","caller":"traceutil/trace.go:171","msg":"trace[2025606105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10521; }","duration":"164.214823ms","start":"2026-09-09T06:37:24.554113Z","end":"2026-09-09T06:37:24.718327Z","steps":["trace[2025606105] 'range keys from in-memory index tree' (duration: 163.775805ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:24.718286Z","caller":"traceutil/trace.go:171","msg":"trace[363049944] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10521; }","duration":"164.143902ms","start":"2026-09-09T06:37:24.554121Z","end":"2026-09-09T06:37:24.718265Z","steps":["trace[363049944] 'range keys from in-memory index tree' (duration: 163.752035ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:24.839084Z","caller":"traceutil/trace.go:171","msg":"trace[957238661] transaction","detail":"{read_only:false; response_revision:10522; number_of_response:1; }","duration":"112.575238ms","start":"2026-09-09T06:37:24.726471Z","end":"2026-09-09T06:37:24.839046Z","steps":["trace[957238661] 'process raft request' (duration: 112.294932ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:25.003724Z","caller":"traceutil/trace.go:171","msg":"trace[1100648628] transaction","detail":"{read_only:false; response_revision:10523; number_of_response:1; }","duration":"274.37406ms","start":"2026-09-09T06:37:24.729323Z","end":"2026-09-09T06:37:25.003697Z","steps":["trace[1100648628] 'process raft request' (duration: 274.160056ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:25.003976Z","caller":"traceutil/trace.go:171","msg":"trace[892517961] linearizableReadLoop","detail":"{readStateIndex:10891; appliedIndex:10889; }","duration":"154.576408ms","start":"2026-09-09T06:37:24.849338Z","end":"2026-09-09T06:37:25.003915Z","steps":["trace[892517961] 'read index received' (duration: 154.155549ms)","trace[892517961] 'applied index is now lower than readState.Index' (duration: 418.749µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:25.004066Z","caller":"traceutil/trace.go:171","msg":"trace[1868140655] transaction","detail":"{read_only:false; response_revision:10524; number_of_response:1; }","duration":"272.45895ms","start":"2026-09-09T06:37:24.731553Z","end":"2026-09-09T06:37:25.004012Z","steps":["trace[1868140655] 'process raft request' (duration: 272.106093ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:25.004266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.563669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-09T06:37:25.004296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.962877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7607"} {"level":"info","ts":"2026-09-09T06:37:25.00431Z","caller":"traceutil/trace.go:171","msg":"trace[460098528] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:10524; }","duration":"141.665411ms","start":"2026-09-09T06:37:24.862629Z","end":"2026-09-09T06:37:25.004294Z","steps":["trace[460098528] 'agreement among raft nodes before linearized reading' (duration: 141.566089ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:25.004339Z","caller":"traceutil/trace.go:171","msg":"trace[945156278] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:10524; }","duration":"155.061889ms","start":"2026-09-09T06:37:24.849264Z","end":"2026-09-09T06:37:25.004325Z","steps":["trace[945156278] 'agreement among raft nodes before linearized reading' (duration: 154.895405ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:25.422648Z","caller":"traceutil/trace.go:171","msg":"trace[1022645125] transaction","detail":"{read_only:false; response_revision:10527; number_of_response:1; }","duration":"230.838088ms","start":"2026-09-09T06:37:25.191752Z","end":"2026-09-09T06:37:25.42259Z","steps":["trace[1022645125] 'process raft request' (duration: 172.577662ms)","trace[1022645125] 'compare' (duration: 57.96679ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:25.632213Z","caller":"traceutil/trace.go:171","msg":"trace[776806392] transaction","detail":"{read_only:false; response_revision:10530; number_of_response:1; }","duration":"126.701281ms","start":"2026-09-09T06:37:25.505485Z","end":"2026-09-09T06:37:25.632186Z","steps":["trace[776806392] 'process raft request' (duration: 126.529817ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:25.806332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.5552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:37:25.806419Z","caller":"traceutil/trace.go:171","msg":"trace[44311895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10530; }","duration":"106.698163ms","start":"2026-09-09T06:37:25.699701Z","end":"2026-09-09T06:37:25.806399Z","steps":["trace[44311895] 'range keys from in-memory index tree' (duration: 106.477618ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:26.050769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.414166ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-09-09T06:37:26.051114Z","caller":"traceutil/trace.go:171","msg":"trace[1973743374] transaction","detail":"{read_only:false; response_revision:10532; number_of_response:1; }","duration":"163.777053ms","start":"2026-09-09T06:37:25.887319Z","end":"2026-09-09T06:37:26.051096Z","steps":["trace[1973743374] 'process raft request' (duration: 163.61753ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:26.361525Z","caller":"traceutil/trace.go:171","msg":"trace[1075052936] transaction","detail":"{read_only:false; response_revision:10534; number_of_response:1; }","duration":"269.294162ms","start":"2026-09-09T06:37:26.092206Z","end":"2026-09-09T06:37:26.3615Z","steps":["trace[1075052936] 'process raft request' (duration: 269.149839ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:26.417065Z","caller":"traceutil/trace.go:171","msg":"trace[830473775] linearizableReadLoop","detail":"{readStateIndex:10903; appliedIndex:10901; }","duration":"128.560081ms","start":"2026-09-09T06:37:26.288455Z","end":"2026-09-09T06:37:26.417015Z","steps":["trace[830473775] 'read index received' (duration: 72.951101ms)","trace[830473775] 'applied index is now lower than readState.Index' (duration: 55.60764ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:26.417121Z","caller":"traceutil/trace.go:171","msg":"trace[27767676] transaction","detail":"{read_only:false; response_revision:10535; number_of_response:1; }","duration":"186.47115ms","start":"2026-09-09T06:37:26.230627Z","end":"2026-09-09T06:37:26.417099Z","steps":["trace[27767676] 'process raft request' (duration: 186.263365ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:26.417227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.752395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T06:37:26.41727Z","caller":"traceutil/trace.go:171","msg":"trace[1502553641] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:10535; }","duration":"128.851107ms","start":"2026-09-09T06:37:26.288406Z","end":"2026-09-09T06:37:26.417257Z","steps":["trace[1502553641] 'agreement among raft nodes before linearized reading' (duration: 128.761955ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:26.56734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.525045ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:37:26.567433Z","caller":"traceutil/trace.go:171","msg":"trace[1767441936] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:10535; }","duration":"110.639948ms","start":"2026-09-09T06:37:26.45678Z","end":"2026-09-09T06:37:26.56742Z","steps":["trace[1767441936] 'range keys from in-memory index tree' (duration: 110.492534ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:26.567588Z","caller":"traceutil/trace.go:171","msg":"trace[1941367289] linearizableReadLoop","detail":"{readStateIndex:10904; appliedIndex:10903; }","duration":"150.416419ms","start":"2026-09-09T06:37:26.417141Z","end":"2026-09-09T06:37:26.567557Z","steps":["trace[1941367289] 'read index received' (duration: 66.780749ms)","trace[1941367289] 'applied index is now lower than readState.Index' (duration: 83.63421ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:26.5677Z","caller":"traceutil/trace.go:171","msg":"trace[1711647989] transaction","detail":"{read_only:false; response_revision:10536; number_of_response:1; }","duration":"196.389581ms","start":"2026-09-09T06:37:26.371283Z","end":"2026-09-09T06:37:26.567673Z","steps":["trace[1711647989] 'process raft request' (duration: 112.64591ms)","trace[1711647989] 'compare' (duration: 83.514887ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:26.567762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.169721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:20017"} {"level":"info","ts":"2026-09-09T06:37:26.568Z","caller":"traceutil/trace.go:171","msg":"trace[1965621004] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:10536; }","duration":"199.304094ms","start":"2026-09-09T06:37:26.36855Z","end":"2026-09-09T06:37:26.567854Z","steps":["trace[1965621004] 'agreement among raft nodes before linearized reading' (duration: 199.11662ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:26.568015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.794298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-09T06:37:26.568091Z","caller":"traceutil/trace.go:171","msg":"trace[124954405] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:10536; }","duration":"108.921811ms","start":"2026-09-09T06:37:26.459157Z","end":"2026-09-09T06:37:26.568079Z","steps":["trace[124954405] 'agreement among raft nodes before linearized reading' (duration: 108.543823ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:27.223401Z","caller":"traceutil/trace.go:171","msg":"trace[217367344] transaction","detail":"{read_only:false; response_revision:10539; number_of_response:1; }","duration":"146.674768ms","start":"2026-09-09T06:37:27.076698Z","end":"2026-09-09T06:37:27.223373Z","steps":["trace[217367344] 'process raft request' (duration: 95.286998ms)","trace[217367344] 'compare' (duration: 51.204636ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:27.223627Z","caller":"traceutil/trace.go:171","msg":"trace[1291407966] transaction","detail":"{read_only:false; response_revision:10540; number_of_response:1; }","duration":"146.869762ms","start":"2026-09-09T06:37:27.076718Z","end":"2026-09-09T06:37:27.223588Z","steps":["trace[1291407966] 'process raft request' (duration: 146.605457ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:27.581288Z","caller":"traceutil/trace.go:171","msg":"trace[1321586379] linearizableReadLoop","detail":"{readStateIndex:10910; appliedIndex:10909; }","duration":"131.850941ms","start":"2026-09-09T06:37:27.449408Z","end":"2026-09-09T06:37:27.581259Z","steps":["trace[1321586379] 'read index received' (duration: 131.420152ms)","trace[1321586379] 'applied index is now lower than readState.Index' (duration: 428.919µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:27.58135Z","caller":"traceutil/trace.go:171","msg":"trace[1811211859] transaction","detail":"{read_only:false; response_revision:10542; number_of_response:1; }","duration":"151.531462ms","start":"2026-09-09T06:37:27.429777Z","end":"2026-09-09T06:37:27.581308Z","steps":["trace[1811211859] 'process raft request' (duration: 151.215696ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:27.581534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.098986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T06:37:27.581586Z","caller":"traceutil/trace.go:171","msg":"trace[196670123] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:10542; }","duration":"132.217049ms","start":"2026-09-09T06:37:27.449357Z","end":"2026-09-09T06:37:27.581574Z","steps":["trace[196670123] 'agreement among raft nodes before linearized reading' (duration: 132.071526ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:27.892128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.512364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:37:27.892248Z","caller":"traceutil/trace.go:171","msg":"trace[259695005] linearizableReadLoop","detail":"{readStateIndex:10912; appliedIndex:10910; }","duration":"192.801186ms","start":"2026-09-09T06:37:27.699427Z","end":"2026-09-09T06:37:27.892228Z","steps":["trace[259695005] 'read index received' (duration: 10.891964ms)","trace[259695005] 'applied index is now lower than readState.Index' (duration: 181.908242ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:27.892335Z","caller":"traceutil/trace.go:171","msg":"trace[170803864] transaction","detail":"{read_only:false; response_revision:10544; number_of_response:1; }","duration":"247.505527ms","start":"2026-09-09T06:37:27.644819Z","end":"2026-09-09T06:37:27.892324Z","steps":["trace[170803864] 'process raft request' (duration: 108.696786ms)","trace[170803864] 'compare' (duration: 138.355411ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:27.892636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.197554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:37:27.892682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.651735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/rabbitmq-glance\" ","response":"range_response_count:1 size:900"} {"level":"info","ts":"2026-09-09T06:37:27.892728Z","caller":"traceutil/trace.go:171","msg":"trace[1762203186] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10544; }","duration":"193.335847ms","start":"2026-09-09T06:37:27.699372Z","end":"2026-09-09T06:37:27.892708Z","steps":["trace[1762203186] 'agreement among raft nodes before linearized reading' (duration: 193.209324ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:27.892749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.262977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/glance-ks-user\" ","response":"range_response_count:1 size:7440"} {"level":"warn","ts":"2026-09-09T06:37:27.89266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.181405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/glance-db-sync\" ","response":"range_response_count:1 size:5188"} {"level":"warn","ts":"2026-09-09T06:37:27.89282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.519301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/glance-ks-endpoints\" ","response":"range_response_count:1 size:12653"} {"level":"warn","ts":"2026-09-09T06:37:27.892823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.537261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-09-09T06:37:27.892852Z","caller":"traceutil/trace.go:171","msg":"trace[1942866049] range","detail":"{range_begin:/registry/jobs/openstack/glance-ks-endpoints; range_end:; response_count:1; response_revision:10544; }","duration":"150.595343ms","start":"2026-09-09T06:37:27.742249Z","end":"2026-09-09T06:37:27.892844Z","steps":["trace[1942866049] 'agreement among raft nodes before linearized reading' (duration: 150.312527ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:27.89285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.506892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/glance-storage-init\" ","response":"range_response_count:1 size:7496"} {"level":"info","ts":"2026-09-09T06:37:27.89289Z","caller":"traceutil/trace.go:171","msg":"trace[1576046255] range","detail":"{range_begin:/registry/jobs/openstack/glance-storage-init; range_end:; response_count:1; response_revision:10544; }","duration":"151.559862ms","start":"2026-09-09T06:37:27.74132Z","end":"2026-09-09T06:37:27.89288Z","steps":["trace[1576046255] 'agreement among raft nodes before linearized reading' (duration: 151.178124ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:27.892867Z","caller":"traceutil/trace.go:171","msg":"trace[741656647] range","detail":"{range_begin:/registry/jobs/openstack/glance-db-sync; range_end:; response_count:1; response_revision:10544; }","duration":"152.40817ms","start":"2026-09-09T06:37:27.740423Z","end":"2026-09-09T06:37:27.892831Z","steps":["trace[741656647] 'agreement among raft nodes before linearized reading' (duration: 151.998722ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:27.892896Z","caller":"traceutil/trace.go:171","msg":"trace[499328970] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:10544; }","duration":"150.627763ms","start":"2026-09-09T06:37:27.742246Z","end":"2026-09-09T06:37:27.892874Z","steps":["trace[499328970] 'agreement among raft nodes before linearized reading' (duration: 150.436659ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:27.892748Z","caller":"traceutil/trace.go:171","msg":"trace[1936623762] range","detail":"{range_begin:/registry/services/endpoints/openstack/rabbitmq-glance; range_end:; response_count:1; response_revision:10544; }","duration":"151.729076ms","start":"2026-09-09T06:37:27.741001Z","end":"2026-09-09T06:37:27.89273Z","steps":["trace[1936623762] 'agreement among raft nodes before linearized reading' (duration: 151.535942ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:27.89305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.668775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/glance-rabbit-init\" ","response":"range_response_count:1 size:5085"} {"level":"info","ts":"2026-09-09T06:37:27.893085Z","caller":"traceutil/trace.go:171","msg":"trace[1217091922] range","detail":"{range_begin:/registry/jobs/openstack/glance-rabbit-init; range_end:; response_count:1; response_revision:10544; }","duration":"151.754007ms","start":"2026-09-09T06:37:27.741324Z","end":"2026-09-09T06:37:27.893078Z","steps":["trace[1217091922] 'agreement among raft nodes before linearized reading' (duration: 151.127163ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:27.892647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.380858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:1148"} {"level":"info","ts":"2026-09-09T06:37:27.893184Z","caller":"traceutil/trace.go:171","msg":"trace[287020181] range","detail":"{range_begin:/registry/services/endpoints/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:10544; }","duration":"150.954709ms","start":"2026-09-09T06:37:27.742214Z","end":"2026-09-09T06:37:27.893168Z","steps":["trace[287020181] 'agreement among raft nodes before linearized reading' (duration: 150.230264ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:27.892788Z","caller":"traceutil/trace.go:171","msg":"trace[1991861107] range","detail":"{range_begin:/registry/jobs/openstack/glance-ks-user; range_end:; response_count:1; response_revision:10544; }","duration":"151.322527ms","start":"2026-09-09T06:37:27.741452Z","end":"2026-09-09T06:37:27.892775Z","steps":["trace[1991861107] 'agreement among raft nodes before linearized reading' (duration: 151.068382ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:28.100725Z","caller":"traceutil/trace.go:171","msg":"trace[873776653] transaction","detail":"{read_only:false; response_revision:10546; number_of_response:1; }","duration":"138.427881ms","start":"2026-09-09T06:37:27.962259Z","end":"2026-09-09T06:37:28.100687Z","steps":["trace[873776653] 'process raft request' (duration: 110.444763ms)","trace[873776653] 'compare' (duration: 27.261943ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:28.379389Z","caller":"traceutil/trace.go:171","msg":"trace[1171715275] transaction","detail":"{read_only:false; response_revision:10547; number_of_response:1; }","duration":"119.236911ms","start":"2026-09-09T06:37:28.260126Z","end":"2026-09-09T06:37:28.379362Z","steps":["trace[1171715275] 'process raft request' (duration: 119.053097ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:28.802492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.126706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:37:28.802592Z","caller":"traceutil/trace.go:171","msg":"trace[1144163799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10551; }","duration":"102.281139ms","start":"2026-09-09T06:37:28.700288Z","end":"2026-09-09T06:37:28.802569Z","steps":["trace[1144163799] 'range keys from in-memory index tree' (duration: 102.005073ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:29.3733Z","caller":"traceutil/trace.go:171","msg":"trace[1851837319] linearizableReadLoop","detail":"{readStateIndex:10922; appliedIndex:10921; }","duration":"100.147302ms","start":"2026-09-09T06:37:29.273125Z","end":"2026-09-09T06:37:29.373272Z","steps":["trace[1851837319] 'read index received' (duration: 75.050405ms)","trace[1851837319] 'applied index is now lower than readState.Index' (duration: 25.095457ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:29.37339Z","caller":"traceutil/trace.go:171","msg":"trace[875994834] transaction","detail":"{read_only:false; response_revision:10554; number_of_response:1; }","duration":"130.89093ms","start":"2026-09-09T06:37:29.242467Z","end":"2026-09-09T06:37:29.373358Z","steps":["trace[875994834] 'process raft request' (duration: 105.785053ms)","trace[875994834] 'compare' (duration: 24.884672ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:29.373483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.327316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:37:29.373555Z","caller":"traceutil/trace.go:171","msg":"trace[303255162] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:10554; }","duration":"100.450949ms","start":"2026-09-09T06:37:29.273086Z","end":"2026-09-09T06:37:29.373537Z","steps":["trace[303255162] 'agreement among raft nodes before linearized reading' (duration: 100.310696ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:37:30.125495Z","caller":"traceutil/trace.go:171","msg":"trace[671855614] linearizableReadLoop","detail":"{readStateIndex:10925; appliedIndex:10924; }","duration":"183.446835ms","start":"2026-09-09T06:37:29.942019Z","end":"2026-09-09T06:37:30.125465Z","steps":["trace[671855614] 'read index received' (duration: 183.154749ms)","trace[671855614] 'applied index is now lower than readState.Index' (duration: 290.776µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:30.125557Z","caller":"traceutil/trace.go:171","msg":"trace[613725150] transaction","detail":"{read_only:false; response_revision:10557; number_of_response:1; }","duration":"323.138714ms","start":"2026-09-09T06:37:29.802392Z","end":"2026-09-09T06:37:30.125531Z","steps":["trace[613725150] 'process raft request' (duration: 322.917389ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:30.125725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:29.802371Z","time spent":"323.247986ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:37:30.125726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.695691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-09T06:37:30.125817Z","caller":"traceutil/trace.go:171","msg":"trace[1350913277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10557; }","duration":"183.819784ms","start":"2026-09-09T06:37:29.941981Z","end":"2026-09-09T06:37:30.1258Z","steps":["trace[1350913277] 'agreement among raft nodes before linearized reading' (duration: 183.591009ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:37:30.475249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.178043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:37:30.475354Z","caller":"traceutil/trace.go:171","msg":"trace[1117735366] transaction","detail":"{read_only:false; response_revision:10558; number_of_response:1; }","duration":"345.315878ms","start":"2026-09-09T06:37:30.130018Z","end":"2026-09-09T06:37:30.475334Z","steps":["trace[1117735366] 'process raft request' (duration: 180.947001ms)","trace[1117735366] 'compare' (duration: 163.55899ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:37:30.475414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:37:30.129994Z","time spent":"345.389909ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:37:30.76394Z","caller":"traceutil/trace.go:171","msg":"trace[1629284888] transaction","detail":"{read_only:false; response_revision:10560; number_of_response:1; }","duration":"161.454615ms","start":"2026-09-09T06:37:30.602466Z","end":"2026-09-09T06:37:30.763921Z","steps":["trace[1629284888] 'process raft request' (duration: 108.289787ms)","trace[1629284888] 'compare' (duration: 53.021275ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:37:30.764106Z","caller":"traceutil/trace.go:171","msg":"trace[1807329023] transaction","detail":"{read_only:false; response_revision:10561; number_of_response:1; }","duration":"135.550811ms","start":"2026-09-09T06:37:30.628516Z","end":"2026-09-09T06:37:30.764067Z","steps":["trace[1807329023] 'process raft request' (duration: 135.371447ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:39:20.20046Z","caller":"traceutil/trace.go:171","msg":"trace[1333994124] linearizableReadLoop","detail":"{readStateIndex:11983; appliedIndex:11982; }","duration":"154.414774ms","start":"2026-09-09T06:39:20.046003Z","end":"2026-09-09T06:39:20.200417Z","steps":["trace[1333994124] 'read index received' (duration: 123.987712ms)","trace[1333994124] 'applied index is now lower than readState.Index' (duration: 30.425622ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:39:20.200813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.788842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-09T06:39:20.200851Z","caller":"traceutil/trace.go:171","msg":"trace[822946090] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11588; }","duration":"154.907514ms","start":"2026-09-09T06:39:20.045932Z","end":"2026-09-09T06:39:20.20084Z","steps":["trace[822946090] 'agreement among raft nodes before linearized reading' (duration: 154.629689ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:39:20.201125Z","caller":"traceutil/trace.go:171","msg":"trace[1008933722] transaction","detail":"{read_only:false; response_revision:11588; number_of_response:1; }","duration":"177.881556ms","start":"2026-09-09T06:39:20.023204Z","end":"2026-09-09T06:39:20.201086Z","steps":["trace[1008933722] 'process raft request' (duration: 146.738169ms)","trace[1008933722] 'compare' (duration: 29.936481ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:39:20.34999Z","caller":"traceutil/trace.go:171","msg":"trace[175214560] transaction","detail":"{read_only:false; response_revision:11589; number_of_response:1; }","duration":"143.212904ms","start":"2026-09-09T06:39:20.206741Z","end":"2026-09-09T06:39:20.349954Z","steps":["trace[175214560] 'process raft request' (duration: 135.438278ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:42:12.393322Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10423} {"level":"info","ts":"2026-09-09T06:42:12.485376Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10423,"took":"89.405666ms","hash":3421361948,"current-db-size-bytes":55046144,"current-db-size":"55 MB","current-db-size-in-use-bytes":27729920,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-09T06:42:12.485453Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3421361948,"revision":10423,"compact-revision":7996} {"level":"info","ts":"2026-09-09T06:45:13.100789Z","caller":"traceutil/trace.go:171","msg":"trace[1985468727] linearizableReadLoop","detail":"{readStateIndex:14472; appliedIndex:14471; }","duration":"107.701832ms","start":"2026-09-09T06:45:12.993065Z","end":"2026-09-09T06:45:13.100767Z","steps":["trace[1985468727] 'read index received' (duration: 107.494538ms)","trace[1985468727] 'applied index is now lower than readState.Index' (duration: 206.264µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:45:13.101114Z","caller":"traceutil/trace.go:171","msg":"trace[1898545696] transaction","detail":"{read_only:false; response_revision:13992; number_of_response:1; }","duration":"113.821486ms","start":"2026-09-09T06:45:12.987275Z","end":"2026-09-09T06:45:13.101097Z","steps":["trace[1898545696] 'process raft request' (duration: 113.362335ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:45:13.10145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.376787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-09T06:45:13.101498Z","caller":"traceutil/trace.go:171","msg":"trace[1203546906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13992; }","duration":"108.469129ms","start":"2026-09-09T06:45:12.993017Z","end":"2026-09-09T06:45:13.101486Z","steps":["trace[1203546906] 'agreement among raft nodes before linearized reading' (duration: 108.330826ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:45:16.095089Z","caller":"traceutil/trace.go:171","msg":"trace[899715041] transaction","detail":"{read_only:false; response_revision:14010; number_of_response:1; }","duration":"100.68404ms","start":"2026-09-09T06:45:15.994377Z","end":"2026-09-09T06:45:16.095061Z","steps":["trace[899715041] 'process raft request' (duration: 100.537007ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:45:56.358458Z","caller":"traceutil/trace.go:171","msg":"trace[341568236] linearizableReadLoop","detail":"{readStateIndex:14731; appliedIndex:14730; }","duration":"207.977704ms","start":"2026-09-09T06:45:56.150457Z","end":"2026-09-09T06:45:56.358435Z","steps":["trace[341568236] 'read index received' (duration: 207.739499ms)","trace[341568236] 'applied index is now lower than readState.Index' (duration: 237.215µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:45:56.358531Z","caller":"traceutil/trace.go:171","msg":"trace[871417357] transaction","detail":"{read_only:false; response_revision:14241; number_of_response:1; }","duration":"304.917815ms","start":"2026-09-09T06:45:56.053578Z","end":"2026-09-09T06:45:56.358496Z","steps":["trace[871417357] 'process raft request' (duration: 304.699811ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:45:56.358683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.411603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:45:56.358715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:45:56.053557Z","time spent":"305.064028ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:45:56.358749Z","caller":"traceutil/trace.go:171","msg":"trace[1848135939] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:14241; }","duration":"178.535965ms","start":"2026-09-09T06:45:56.180198Z","end":"2026-09-09T06:45:56.358734Z","steps":["trace[1848135939] 'agreement among raft nodes before linearized reading' (duration: 178.413213ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:45:56.3587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.221279ms","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-09-09T06:45:56.358817Z","caller":"traceutil/trace.go:171","msg":"trace[442651239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14241; }","duration":"208.379812ms","start":"2026-09-09T06:45:56.150409Z","end":"2026-09-09T06:45:56.358789Z","steps":["trace[442651239] 'agreement among raft nodes before linearized reading' (duration: 208.157728ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:45:56.359882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.071896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:86 size:929916"} {"level":"info","ts":"2026-09-09T06:45:56.35997Z","caller":"traceutil/trace.go:171","msg":"trace[1898918301] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:86; response_revision:14241; }","duration":"120.212259ms","start":"2026-09-09T06:45:56.23974Z","end":"2026-09-09T06:45:56.359952Z","steps":["trace[1898918301] 'agreement among raft nodes before linearized reading' (duration: 118.8927ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:45:56.813955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.62993ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:45:56.81407Z","caller":"traceutil/trace.go:171","msg":"trace[1369620192] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:14241; }","duration":"356.755203ms","start":"2026-09-09T06:45:56.457297Z","end":"2026-09-09T06:45:56.814052Z","steps":["trace[1369620192] 'range keys from in-memory index tree' (duration: 356.61784ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:45:56.814615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.350481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:45:56.814899Z","caller":"traceutil/trace.go:171","msg":"trace[1181399176] linearizableReadLoop","detail":"{readStateIndex:14732; appliedIndex:14731; }","duration":"443.762461ms","start":"2026-09-09T06:45:56.371093Z","end":"2026-09-09T06:45:56.814855Z","steps":["trace[1181399176] 'read index received' (duration: 220.579762ms)","trace[1181399176] 'applied index is now lower than readState.Index' (duration: 223.177979ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:45:56.814794Z","caller":"traceutil/trace.go:171","msg":"trace[394131725] transaction","detail":"{read_only:false; response_revision:14242; number_of_response:1; }","duration":"451.332553ms","start":"2026-09-09T06:45:56.363431Z","end":"2026-09-09T06:45:56.814763Z","steps":["trace[394131725] 'process raft request' (duration: 228.294077ms)","trace[394131725] 'compare' (duration: 222.039655ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:45:56.815134Z","caller":"traceutil/trace.go:171","msg":"trace[808126795] transaction","detail":"{read_only:false; response_revision:14243; number_of_response:1; }","duration":"133.694197ms","start":"2026-09-09T06:45:56.681422Z","end":"2026-09-09T06:45:56.815116Z","steps":["trace[808126795] 'process raft request' (duration: 133.3607ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:45:56.815263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:45:56.363411Z","time spent":"451.749732ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:45:56.815766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.6009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-09-09T06:45:56.815788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.387165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:45:56.815755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.587128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-09T06:45:56.815821Z","caller":"traceutil/trace.go:171","msg":"trace[35964127] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:14244; }","duration":"320.696662ms","start":"2026-09-09T06:45:56.495111Z","end":"2026-09-09T06:45:56.815808Z","steps":["trace[35964127] 'agreement among raft nodes before linearized reading' (duration: 320.506438ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:45:56.815833Z","caller":"traceutil/trace.go:171","msg":"trace[1987259591] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:14244; }","duration":"232.485757ms","start":"2026-09-09T06:45:56.583333Z","end":"2026-09-09T06:45:56.815819Z","steps":["trace[1987259591] 'agreement among raft nodes before linearized reading' (duration: 232.405325ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:45:56.815858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:45:56.49509Z","time spent":"320.760913ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":583,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-09-09T06:45:56.815917Z","caller":"traceutil/trace.go:171","msg":"trace[129082814] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14243; }","duration":"444.808903ms","start":"2026-09-09T06:45:56.371037Z","end":"2026-09-09T06:45:56.815846Z","steps":["trace[129082814] 'agreement among raft nodes before linearized reading' (duration: 443.985035ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:45:56.816041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:45:56.371019Z","time spent":"445.007887ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-09T06:45:56.816068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.964932ms","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-09-09T06:45:56.816096Z","caller":"traceutil/trace.go:171","msg":"trace[1404902057] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14244; }","duration":"317.010993ms","start":"2026-09-09T06:45:56.499076Z","end":"2026-09-09T06:45:56.816087Z","steps":["trace[1404902057] 'agreement among raft nodes before linearized reading' (duration: 316.929161ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:45:56.816127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:45:56.499066Z","time spent":"317.055344ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-09T06:45:56.816311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.393927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:45:56.816376Z","caller":"traceutil/trace.go:171","msg":"trace[135590929] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:14244; }","duration":"109.498449ms","start":"2026-09-09T06:45:56.706863Z","end":"2026-09-09T06:45:56.816361Z","steps":["trace[135590929] 'agreement among raft nodes before linearized reading' (duration: 108.775084ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:47:12.40075Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12741} {"level":"info","ts":"2026-09-09T06:47:12.489626Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12741,"took":"86.195031ms","hash":2468518422,"current-db-size-bytes":55046144,"current-db-size":"55 MB","current-db-size-in-use-bytes":30912512,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-09T06:47:12.489704Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2468518422,"revision":12741,"compact-revision":10423} {"level":"warn","ts":"2026-09-09T06:47:37.863019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.910404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-8rplk\" ","response":"range_response_count:1 size:22121"} {"level":"warn","ts":"2026-09-09T06:47:37.863791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.267937ms","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-09-09T06:47:37.863922Z","caller":"traceutil/trace.go:171","msg":"trace[887146565] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15445; }","duration":"133.459081ms","start":"2026-09-09T06:47:37.730427Z","end":"2026-09-09T06:47:37.863886Z","steps":["trace[887146565] 'range keys from in-memory index tree' (duration: 133.010191ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:47:37.863957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.392345ms","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-09-09T06:47:37.864046Z","caller":"traceutil/trace.go:171","msg":"trace[1916741870] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15445; }","duration":"151.549218ms","start":"2026-09-09T06:47:37.712473Z","end":"2026-09-09T06:47:37.864022Z","steps":["trace[1916741870] 'range keys from in-memory index tree' (duration: 151.20252ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:47:37.864249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.348443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:47:37.864334Z","caller":"traceutil/trace.go:171","msg":"trace[859525419] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15445; }","duration":"151.439485ms","start":"2026-09-09T06:47:37.712872Z","end":"2026-09-09T06:47:37.864311Z","steps":["trace[859525419] 'range keys from in-memory index tree' (duration: 151.158189ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:47:37.864368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.070014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-09-09T06:47:37.86439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.412174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-8rplk\" ","response":"range_response_count:1 size:22121"} {"level":"info","ts":"2026-09-09T06:47:37.86443Z","caller":"traceutil/trace.go:171","msg":"trace[229877855] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15445; }","duration":"134.156505ms","start":"2026-09-09T06:47:37.730256Z","end":"2026-09-09T06:47:37.864413Z","steps":["trace[229877855] 'range keys from in-memory index tree' (duration: 133.92435ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:47:37.864463Z","caller":"traceutil/trace.go:171","msg":"trace[887054233] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-8rplk; range_end:; response_count:1; response_revision:15445; }","duration":"158.539107ms","start":"2026-09-09T06:47:37.705906Z","end":"2026-09-09T06:47:37.864445Z","steps":["trace[887054233] 'range keys from in-memory index tree' (duration: 158.21114ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:47:37.863212Z","caller":"traceutil/trace.go:171","msg":"trace[1399887090] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-8rplk; range_end:; response_count:1; response_revision:15445; }","duration":"202.168069ms","start":"2026-09-09T06:47:37.660989Z","end":"2026-09-09T06:47:37.863157Z","steps":["trace[1399887090] 'range keys from in-memory index tree' (duration: 201.573897ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:52:12.416344Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15310} {"level":"info","ts":"2026-09-09T06:52:12.52012Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15310,"took":"100.173843ms","hash":2145574207,"current-db-size-bytes":55046144,"current-db-size":"55 MB","current-db-size-in-use-bytes":32022528,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-09T06:52:12.520186Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2145574207,"revision":15310,"compact-revision":12741} {"level":"info","ts":"2026-09-09T06:53:19.074112Z","caller":"traceutil/trace.go:171","msg":"trace[1419411947] linearizableReadLoop","detail":"{readStateIndex:18607; appliedIndex:18606; }","duration":"141.099547ms","start":"2026-09-09T06:53:18.932979Z","end":"2026-09-09T06:53:19.074079Z","steps":["trace[1419411947] 'read index received' (duration: 140.841592ms)","trace[1419411947] 'applied index is now lower than readState.Index' (duration: 256.685µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:53:19.074242Z","caller":"traceutil/trace.go:171","msg":"trace[1317533027] transaction","detail":"{read_only:false; response_revision:17998; number_of_response:1; }","duration":"165.354908ms","start":"2026-09-09T06:53:18.908841Z","end":"2026-09-09T06:53:19.074195Z","steps":["trace[1317533027] 'process raft request' (duration: 165.064541ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:53:19.074646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.633689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-8rplk\" ","response":"range_response_count:1 size:22122"} {"level":"info","ts":"2026-09-09T06:53:19.074764Z","caller":"traceutil/trace.go:171","msg":"trace[805174286] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-8rplk; range_end:; response_count:1; response_revision:17998; }","duration":"141.810953ms","start":"2026-09-09T06:53:18.932927Z","end":"2026-09-09T06:53:19.074738Z","steps":["trace[805174286] 'agreement among raft nodes before linearized reading' (duration: 141.414295ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:53:21.798389Z","caller":"traceutil/trace.go:171","msg":"trace[1673469209] transaction","detail":"{read_only:false; response_revision:18018; number_of_response:1; }","duration":"143.311685ms","start":"2026-09-09T06:53:21.65503Z","end":"2026-09-09T06:53:21.798341Z","steps":["trace[1673469209] 'process raft request' (duration: 70.674732ms)","trace[1673469209] 'compare' (duration: 72.416109ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:53:21.809027Z","caller":"traceutil/trace.go:171","msg":"trace[450017587] linearizableReadLoop","detail":"{readStateIndex:18629; appliedIndex:18627; }","duration":"105.063757ms","start":"2026-09-09T06:53:21.703925Z","end":"2026-09-09T06:53:21.808988Z","steps":["trace[450017587] 'read index received' (duration: 21.783566ms)","trace[450017587] 'applied index is now lower than readState.Index' (duration: 83.278591ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:53:21.809291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.326203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T06:53:21.809328Z","caller":"traceutil/trace.go:171","msg":"trace[662306946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18019; }","duration":"105.439535ms","start":"2026-09-09T06:53:21.703876Z","end":"2026-09-09T06:53:21.809316Z","steps":["trace[662306946] 'agreement among raft nodes before linearized reading' (duration: 105.273302ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:53:21.809796Z","caller":"traceutil/trace.go:171","msg":"trace[1065380883] transaction","detail":"{read_only:false; response_revision:18019; number_of_response:1; }","duration":"108.965491ms","start":"2026-09-09T06:53:21.700816Z","end":"2026-09-09T06:53:21.809782Z","steps":["trace[1065380883] 'process raft request' (duration: 107.97423ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:53:25.028676Z","caller":"traceutil/trace.go:171","msg":"trace[84271027] transaction","detail":"{read_only:false; response_revision:18050; number_of_response:1; }","duration":"169.016285ms","start":"2026-09-09T06:53:24.859631Z","end":"2026-09-09T06:53:25.028647Z","steps":["trace[84271027] 'process raft request' (duration: 168.264139ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:53:25.28687Z","caller":"traceutil/trace.go:171","msg":"trace[1355029646] linearizableReadLoop","detail":"{readStateIndex:18662; appliedIndex:18661; }","duration":"109.063543ms","start":"2026-09-09T06:53:25.177746Z","end":"2026-09-09T06:53:25.28681Z","steps":["trace[1355029646] 'read index received' (duration: 108.765876ms)","trace[1355029646] 'applied index is now lower than readState.Index' (duration: 296.027µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:53:25.286951Z","caller":"traceutil/trace.go:171","msg":"trace[316856464] transaction","detail":"{read_only:false; response_revision:18052; number_of_response:1; }","duration":"152.589243ms","start":"2026-09-09T06:53:25.134321Z","end":"2026-09-09T06:53:25.28691Z","steps":["trace[316856464] 'process raft request' (duration: 152.277116ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:53:25.287244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.449501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-8rplk\" ","response":"range_response_count:1 size:22122"} {"level":"info","ts":"2026-09-09T06:53:25.287331Z","caller":"traceutil/trace.go:171","msg":"trace[1733816127] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-8rplk; range_end:; response_count:1; response_revision:18052; }","duration":"109.621764ms","start":"2026-09-09T06:53:25.177686Z","end":"2026-09-09T06:53:25.287308Z","steps":["trace[1733816127] 'agreement among raft nodes before linearized reading' (duration: 109.263607ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:55:52.847151Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8a5b61ed9120d6b3","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-09T06:55:52.856096Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-09T06:55:52.856225Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-09T06:57:12.423126Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17478} {"level":"info","ts":"2026-09-09T06:57:12.529672Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17478,"took":"100.148151ms","hash":2826939930,"current-db-size-bytes":55046144,"current-db-size":"55 MB","current-db-size-in-use-bytes":34963456,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-09-09T06:57:12.529745Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2826939930,"revision":17478,"compact-revision":15310} {"level":"warn","ts":"2026-09-09T06:58:50.216237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.841956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-09T06:58:50.216359Z","caller":"traceutil/trace.go:171","msg":"trace[1675050753] transaction","detail":"{read_only:false; response_revision:20544; number_of_response:1; }","duration":"230.738073ms","start":"2026-09-09T06:58:49.985597Z","end":"2026-09-09T06:58:50.216336Z","steps":["trace[1675050753] 'process raft request' (duration: 70.674122ms)","trace[1675050753] 'compare' (duration: 159.697263ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:58:50.647146Z","caller":"traceutil/trace.go:171","msg":"trace[198610206] linearizableReadLoop","detail":"{readStateIndex:21238; appliedIndex:21237; }","duration":"321.047282ms","start":"2026-09-09T06:58:50.326058Z","end":"2026-09-09T06:58:50.647105Z","steps":["trace[198610206] 'read index received' (duration: 320.681663ms)","trace[198610206] 'applied index is now lower than readState.Index' (duration: 363.649µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:58:50.647263Z","caller":"traceutil/trace.go:171","msg":"trace[2035590711] transaction","detail":"{read_only:false; response_revision:20545; number_of_response:1; }","duration":"464.146151ms","start":"2026-09-09T06:58:50.183046Z","end":"2026-09-09T06:58:50.647192Z","steps":["trace[2035590711] 'process raft request' (duration: 463.826073ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:58:50.647454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:58:50.183012Z","time spent":"464.337395ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:58:50.647989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.716947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-09T06:58:50.648068Z","caller":"traceutil/trace.go:171","msg":"trace[26699358] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20545; }","duration":"322.060204ms","start":"2026-09-09T06:58:50.325986Z","end":"2026-09-09T06:58:50.648047Z","steps":["trace[26699358] 'agreement among raft nodes before linearized reading' (duration: 321.316558ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:58:50.648125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:58:50.325961Z","time spent":"322.147875ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-09T06:58:50.649449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.48319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-09T06:58:50.649502Z","caller":"traceutil/trace.go:171","msg":"trace[467415482] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:20545; }","duration":"248.584132ms","start":"2026-09-09T06:58:50.400904Z","end":"2026-09-09T06:58:50.649488Z","steps":["trace[467415482] 'agreement among raft nodes before linearized reading' (duration: 246.444007ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:58:50.770237Z","caller":"traceutil/trace.go:171","msg":"trace[149330499] transaction","detail":"{read_only:false; response_revision:20546; number_of_response:1; }","duration":"117.093392ms","start":"2026-09-09T06:58:50.653045Z","end":"2026-09-09T06:58:50.770138Z","steps":["trace[149330499] 'process raft request' (duration: 71.866845ms)","trace[149330499] 'compare' (duration: 44.89865ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:58:51.237638Z","caller":"traceutil/trace.go:171","msg":"trace[1017322329] transaction","detail":"{read_only:false; response_revision:20548; number_of_response:1; }","duration":"124.760296ms","start":"2026-09-09T06:58:51.112844Z","end":"2026-09-09T06:58:51.237604Z","steps":["trace[1017322329] 'process raft request' (duration: 99.837313ms)","trace[1017322329] 'compare' (duration: 24.669527ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:58:51.75968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.100955ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-09-09T06:58:51.759812Z","caller":"traceutil/trace.go:171","msg":"trace[459436281] linearizableReadLoop","detail":"{readStateIndex:21243; appliedIndex:21242; }","duration":"146.88936ms","start":"2026-09-09T06:58:51.6129Z","end":"2026-09-09T06:58:51.75979Z","steps":["trace[459436281] 'read index received' (duration: 54.131µs)","trace[459436281] 'applied index is now lower than readState.Index' (duration: 146.833449ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:58:51.760372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.464212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-09T06:58:51.760421Z","caller":"traceutil/trace.go:171","msg":"trace[1234143590] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20549; }","duration":"147.555684ms","start":"2026-09-09T06:58:51.612849Z","end":"2026-09-09T06:58:51.760405Z","steps":["trace[1234143590] 'agreement among raft nodes before linearized reading' (duration: 147.317309ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:58:51.760721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.763583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-09T06:58:51.760838Z","caller":"traceutil/trace.go:171","msg":"trace[1438425592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20549; }","duration":"114.944526ms","start":"2026-09-09T06:58:51.645859Z","end":"2026-09-09T06:58:51.760804Z","steps":["trace[1438425592] 'agreement among raft nodes before linearized reading' (duration: 114.598639ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:58:51.916725Z","caller":"traceutil/trace.go:171","msg":"trace[1403599315] transaction","detail":"{read_only:false; response_revision:20550; number_of_response:1; }","duration":"151.13175ms","start":"2026-09-09T06:58:51.765563Z","end":"2026-09-09T06:58:51.916695Z","steps":["trace[1403599315] 'process raft request' (duration: 150.597159ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:58:52.047129Z","caller":"traceutil/trace.go:171","msg":"trace[351450878] linearizableReadLoop","detail":"{readStateIndex:21245; appliedIndex:21243; }","duration":"141.257109ms","start":"2026-09-09T06:58:51.905839Z","end":"2026-09-09T06:58:52.047096Z","steps":["trace[351450878] 'read index received' (duration: 10.27014ms)","trace[351450878] 'applied index is now lower than readState.Index' (duration: 130.985249ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:58:52.047189Z","caller":"traceutil/trace.go:171","msg":"trace[1424498650] transaction","detail":"{read_only:false; response_revision:20551; number_of_response:1; }","duration":"281.18407ms","start":"2026-09-09T06:58:51.765979Z","end":"2026-09-09T06:58:52.047163Z","steps":["trace[1424498650] 'process raft request' (duration: 280.396353ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:58:52.047639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.78919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-09T06:58:52.047682Z","caller":"traceutil/trace.go:171","msg":"trace[257870367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20551; }","duration":"141.910052ms","start":"2026-09-09T06:58:51.90576Z","end":"2026-09-09T06:58:52.04767Z","steps":["trace[257870367] 'agreement among raft nodes before linearized reading' (duration: 141.668137ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:02:12.431704Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19977} {"level":"info","ts":"2026-09-09T07:02:12.576621Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19977,"took":"140.863079ms","hash":1103431118,"current-db-size-bytes":55046144,"current-db-size":"55 MB","current-db-size-in-use-bytes":30912512,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-09T07:02:12.576695Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1103431118,"revision":19977,"compact-revision":17478} {"level":"info","ts":"2026-09-09T07:07:12.442217Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22257} {"level":"info","ts":"2026-09-09T07:07:12.563569Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22257,"took":"117.109881ms","hash":2313103408,"current-db-size-bytes":55046144,"current-db-size":"55 MB","current-db-size-in-use-bytes":43028480,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-09-09T07:07:12.563638Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2313103408,"revision":22257,"compact-revision":19977} {"level":"info","ts":"2026-09-09T07:12:12.449171Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25374} {"level":"info","ts":"2026-09-09T07:12:12.605515Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25374,"took":"151.476106ms","hash":1881646781,"current-db-size-bytes":55046144,"current-db-size":"55 MB","current-db-size-in-use-bytes":29700096,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-09T07:12:12.605599Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1881646781,"revision":25374,"compact-revision":22257}