{"level":"warn","ts":"2026-06-30T18:03:24.538728Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-30T18:03:24.538835Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129: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-06-30T18:03:24.5389Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-30T18:03:24.538912Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-06-30T18:03:24.538932Z","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-06-30T18:03:24.539542Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-06-30T18:03:24.539618Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129: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-06-30T18:03:24.541776Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.950578ms"} {"level":"info","ts":"2026-06-30T18:03:24.552533Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-06-30T18:03:24.552631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-06-30T18:03:24.552656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-06-30T18:03:24.55268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-30T18:03:24.552697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-06-30T18:03:24.552744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-06-30T18:03:24.558225Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-30T18:03:24.559733Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-30T18:03:24.560909Z","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-06-30T18:03:24.563694Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-30T18:03:24.563825Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-30T18:03:24.564084Z","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-06-30T18:03:24.564177Z","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-06-30T18:03:24.564192Z","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-06-30T18:03:24.56768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-06-30T18:03:24.567971Z","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-06-30T18:03:24.568049Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-06-30T18:03:24.568123Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-06-30T18:03:24.568181Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-06-30T18:03:24.56821Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-30T18:03:24.568265Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-30T18:03:25.253677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-06-30T18:03:25.253796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-06-30T18:03:25.253829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-06-30T18:03:25.25385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-06-30T18:03:25.253863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-06-30T18:03:25.253909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-06-30T18:03:25.253939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-06-30T18:03:25.25541Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T18:03:25.257111Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T18:03:25.257253Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T18:03:25.25731Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-30T18:03:25.257351Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-06-30T18:03:25.257634Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-30T18:03:25.258188Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-30T18:03:25.25845Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-30T18:03:25.258475Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-30T18:03:25.260007Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-06-30T18:03:25.261519Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-30T18:04:12.278208Z","caller":"traceutil/trace.go:171","msg":"trace[1898324020] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"164.276255ms","start":"2026-06-30T18:04:12.113906Z","end":"2026-06-30T18:04:12.278182Z","steps":["trace[1898324020] 'process raft request' (duration: 163.782595ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:04:12.876785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.5598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-30T18:04:12.877063Z","caller":"traceutil/trace.go:171","msg":"trace[995304023] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"528.206435ms","start":"2026-06-30T18:04:12.348806Z","end":"2026-06-30T18:04:12.877013Z","steps":["trace[995304023] 'process raft request' (duration: 280.592575ms)","trace[995304023] 'compare' (duration: 246.224733ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:04:12.877269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:04:12.34878Z","time spent":"528.375888ms","remote":"127.0.0.1:32976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-30T18:04:28.220427Z","caller":"traceutil/trace.go:171","msg":"trace[2014300791] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"122.432559ms","start":"2026-06-30T18:04:28.097962Z","end":"2026-06-30T18:04:28.220394Z","steps":["trace[2014300791] 'process raft request' (duration: 122.247766ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:05:47.080118Z","caller":"traceutil/trace.go:171","msg":"trace[317195348] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"138.685326ms","start":"2026-06-30T18:05:46.941393Z","end":"2026-06-30T18:05:47.080079Z","steps":["trace[317195348] 'process raft request' (duration: 111.193559ms)","trace[317195348] 'compare' (duration: 27.263593ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:05:59.6375Z","caller":"traceutil/trace.go:171","msg":"trace[84768276] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"100.238083ms","start":"2026-06-30T18:05:59.537231Z","end":"2026-06-30T18:05:59.637469Z","steps":["trace[84768276] 'process raft request' (duration: 82.455159ms)","trace[84768276] 'compare' (duration: 17.569309ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:05:59.783104Z","caller":"traceutil/trace.go:171","msg":"trace[919487528] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"141.078475ms","start":"2026-06-30T18:05:59.641997Z","end":"2026-06-30T18:05:59.783075Z","steps":["trace[919487528] 'process raft request' (duration: 98.996657ms)","trace[919487528] 'compare' (duration: 41.916104ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:05:59.783215Z","caller":"traceutil/trace.go:171","msg":"trace[205332526] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"140.84725ms","start":"2026-06-30T18:05:59.642348Z","end":"2026-06-30T18:05:59.783195Z","steps":["trace[205332526] 'process raft request' (duration: 140.688697ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:06:41.785694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.486306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-30T18:06:41.785791Z","caller":"traceutil/trace.go:171","msg":"trace[1121453460] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2224; }","duration":"143.65179ms","start":"2026-06-30T18:06:41.642119Z","end":"2026-06-30T18:06:41.785771Z","steps":["trace[1121453460] 'range keys from in-memory index tree' (duration: 143.294372ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:06:47.68432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.183614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T18:06:47.68444Z","caller":"traceutil/trace.go:171","msg":"trace[990030275] linearizableReadLoop","detail":"{readStateIndex:2341; appliedIndex:2340; }","duration":"167.62706ms","start":"2026-06-30T18:06:47.516797Z","end":"2026-06-30T18:06:47.684424Z","steps":["trace[990030275] 'read index received' (duration: 61.14201ms)","trace[990030275] 'applied index is now lower than readState.Index' (duration: 106.48379ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:06:47.684475Z","caller":"traceutil/trace.go:171","msg":"trace[1658392741] transaction","detail":"{read_only:false; response_revision:2254; number_of_response:1; }","duration":"168.158161ms","start":"2026-06-30T18:06:47.516287Z","end":"2026-06-30T18:06:47.684445Z","steps":["trace[1658392741] 'process raft request' (duration: 61.613949ms)","trace[1658392741] 'compare' (duration: 106.048292ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:06:47.684606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.810624ms","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-06-30T18:06:47.684664Z","caller":"traceutil/trace.go:171","msg":"trace[434946394] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2254; }","duration":"167.894416ms","start":"2026-06-30T18:06:47.516752Z","end":"2026-06-30T18:06:47.684647Z","steps":["trace[434946394] 'agreement among raft nodes before linearized reading' (duration: 167.727613ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:07:26.418129Z","caller":"traceutil/trace.go:171","msg":"trace[545431061] transaction","detail":"{read_only:false; response_revision:2640; number_of_response:1; }","duration":"141.934832ms","start":"2026-06-30T18:07:26.276167Z","end":"2026-06-30T18:07:26.418102Z","steps":["trace[545431061] 'process raft request' (duration: 141.785619ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:07:26.438323Z","caller":"traceutil/trace.go:171","msg":"trace[563967926] transaction","detail":"{read_only:false; response_revision:2641; number_of_response:1; }","duration":"159.198748ms","start":"2026-06-30T18:07:26.279068Z","end":"2026-06-30T18:07:26.438267Z","steps":["trace[563967926] 'process raft request' (duration: 159.063456ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:07:26.438358Z","caller":"traceutil/trace.go:171","msg":"trace[855884754] transaction","detail":"{read_only:false; response_revision:2642; number_of_response:1; }","duration":"159.134266ms","start":"2026-06-30T18:07:26.279165Z","end":"2026-06-30T18:07:26.438299Z","steps":["trace[855884754] 'process raft request' (duration: 159.045104ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:08:00.162927Z","caller":"traceutil/trace.go:171","msg":"trace[607500754] linearizableReadLoop","detail":"{readStateIndex:2951; appliedIndex:2950; }","duration":"111.947545ms","start":"2026-06-30T18:08:00.050946Z","end":"2026-06-30T18:08:00.162894Z","steps":["trace[607500754] 'read index received' (duration: 111.729021ms)","trace[607500754] 'applied index is now lower than readState.Index' (duration: 216.994µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:08:00.163025Z","caller":"traceutil/trace.go:171","msg":"trace[1010528498] transaction","detail":"{read_only:false; response_revision:2842; number_of_response:1; }","duration":"134.862443ms","start":"2026-06-30T18:08:00.028114Z","end":"2026-06-30T18:08:00.162976Z","steps":["trace[1010528498] 'process raft request' (duration: 134.589208ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:08:00.163182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.215181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-06-30T18:08:00.163231Z","caller":"traceutil/trace.go:171","msg":"trace[1234363565] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2842; }","duration":"112.323522ms","start":"2026-06-30T18:08:00.05089Z","end":"2026-06-30T18:08:00.163214Z","steps":["trace[1234363565] 'agreement among raft nodes before linearized reading' (duration: 112.139149ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:08:00.42558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.277467ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T18:08:00.425788Z","caller":"traceutil/trace.go:171","msg":"trace[2073942783] linearizableReadLoop","detail":"{readStateIndex:2953; appliedIndex:2951; }","duration":"207.90538ms","start":"2026-06-30T18:08:00.217865Z","end":"2026-06-30T18:08:00.425771Z","steps":["trace[2073942783] 'read index received' (duration: 63.348726ms)","trace[2073942783] 'applied index is now lower than readState.Index' (duration: 144.555324ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:08:00.425881Z","caller":"traceutil/trace.go:171","msg":"trace[985328612] transaction","detail":"{read_only:false; response_revision:2843; number_of_response:1; }","duration":"346.180101ms","start":"2026-06-30T18:08:00.079646Z","end":"2026-06-30T18:08:00.425826Z","steps":["trace[985328612] 'process raft request' (duration: 201.548726ms)","trace[985328612] 'compare' (duration: 144.141915ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:08:00.425929Z","caller":"traceutil/trace.go:171","msg":"trace[1168040282] transaction","detail":"{read_only:false; response_revision:2844; number_of_response:1; }","duration":"257.791844ms","start":"2026-06-30T18:08:00.168092Z","end":"2026-06-30T18:08:00.425884Z","steps":["trace[1168040282] 'process raft request' (duration: 257.613561ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:08:00.425962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.087124ms","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-06-30T18:08:00.425998Z","caller":"traceutil/trace.go:171","msg":"trace[120154262] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2844; }","duration":"208.187295ms","start":"2026-06-30T18:08:00.217801Z","end":"2026-06-30T18:08:00.425988Z","steps":["trace[120154262] 'agreement among raft nodes before linearized reading' (duration: 208.027792ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:08:00.426057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:08:00.079629Z","time spent":"346.334543ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:10:36.295614Z","caller":"traceutil/trace.go:171","msg":"trace[904593537] transaction","detail":"{read_only:false; response_revision:3867; number_of_response:1; }","duration":"135.468666ms","start":"2026-06-30T18:10:36.160123Z","end":"2026-06-30T18:10:36.295592Z","steps":["trace[904593537] 'process raft request' (duration: 135.325362ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:10:36.447708Z","caller":"traceutil/trace.go:171","msg":"trace[262199766] transaction","detail":"{read_only:false; response_revision:3868; number_of_response:1; }","duration":"140.187555ms","start":"2026-06-30T18:10:36.307491Z","end":"2026-06-30T18:10:36.447678Z","steps":["trace[262199766] 'process raft request' (duration: 120.404409ms)","trace[262199766] 'compare' (duration: 19.46704ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:11:59.504478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.579313ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T18:11:59.504759Z","caller":"traceutil/trace.go:171","msg":"trace[313684006] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"204.4276ms","start":"2026-06-30T18:11:59.300293Z","end":"2026-06-30T18:11:59.504721Z","steps":["trace[313684006] 'process raft request' (duration: 36.47924ms)","trace[313684006] 'compare' (duration: 167.43251ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:12:02.498391Z","caller":"traceutil/trace.go:171","msg":"trace[495609855] transaction","detail":"{read_only:false; response_revision:5101; number_of_response:1; }","duration":"117.467373ms","start":"2026-06-30T18:12:02.380889Z","end":"2026-06-30T18:12:02.498356Z","steps":["trace[495609855] 'process raft request' (duration: 81.39383ms)","trace[495609855] 'compare' (duration: 35.86432ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:12:02.498618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.103576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/goldpinger-p29z8\" ","response":"range_response_count:1 size:3945"} {"level":"info","ts":"2026-06-30T18:12:02.498392Z","caller":"traceutil/trace.go:171","msg":"trace[1844380799] transaction","detail":"{read_only:false; response_revision:5102; number_of_response:1; }","duration":"113.944487ms","start":"2026-06-30T18:12:02.384427Z","end":"2026-06-30T18:12:02.498372Z","steps":["trace[1844380799] 'process raft request' (duration: 113.880345ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:12:02.498674Z","caller":"traceutil/trace.go:171","msg":"trace[653057262] range","detail":"{range_begin:/registry/pods/monitoring/goldpinger-p29z8; range_end:; response_count:1; response_revision:5102; }","duration":"117.206228ms","start":"2026-06-30T18:12:02.381456Z","end":"2026-06-30T18:12:02.498662Z","steps":["trace[653057262] 'agreement among raft nodes before linearized reading' (duration: 116.949803ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:12:02.4984Z","caller":"traceutil/trace.go:171","msg":"trace[465818606] linearizableReadLoop","detail":"{readStateIndex:5294; appliedIndex:5293; }","duration":"116.917323ms","start":"2026-06-30T18:12:02.38146Z","end":"2026-06-30T18:12:02.498377Z","steps":["trace[465818606] 'read index received' (duration: 80.84266ms)","trace[465818606] 'applied index is now lower than readState.Index' (duration: 36.072103ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:12:13.351905Z","caller":"traceutil/trace.go:171","msg":"trace[1694232854] transaction","detail":"{read_only:false; response_revision:5301; number_of_response:1; }","duration":"171.794922ms","start":"2026-06-30T18:12:13.180084Z","end":"2026-06-30T18:12:13.351879Z","steps":["trace[1694232854] 'process raft request' (duration: 111.875528ms)","trace[1694232854] 'compare' (duration: 59.805022ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:13:25.285953Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2983} {"level":"info","ts":"2026-06-30T18:13:25.364095Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2983,"took":"75.798363ms","hash":2942442121,"current-db-size-bytes":58388480,"current-db-size":"58 MB","current-db-size-in-use-bytes":34152448,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-30T18:13:25.364173Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2942442121,"revision":2983,"compact-revision":-1} {"level":"info","ts":"2026-06-30T18:18:17.089002Z","caller":"traceutil/trace.go:171","msg":"trace[290794712] transaction","detail":"{read_only:false; response_revision:8234; number_of_response:1; }","duration":"306.937597ms","start":"2026-06-30T18:18:16.782045Z","end":"2026-06-30T18:18:17.088982Z","steps":["trace[290794712] 'process raft request' (duration: 306.801234ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:18:17.08914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:18:16.78201Z","time spent":"307.068019ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:18:17.138129Z","caller":"traceutil/trace.go:171","msg":"trace[307110908] linearizableReadLoop","detail":"{readStateIndex:8525; appliedIndex:8523; }","duration":"218.799033ms","start":"2026-06-30T18:18:16.919309Z","end":"2026-06-30T18:18:17.138108Z","steps":["trace[307110908] 'read index received' (duration: 169.474475ms)","trace[307110908] 'applied index is now lower than readState.Index' (duration: 49.323848ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:18:17.138172Z","caller":"traceutil/trace.go:171","msg":"trace[1714433398] transaction","detail":"{read_only:false; response_revision:8235; number_of_response:1; }","duration":"259.074347ms","start":"2026-06-30T18:18:16.879065Z","end":"2026-06-30T18:18:17.13814Z","steps":["trace[1714433398] 'process raft request' (duration: 258.905214ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:18:17.138341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.020688ms","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-06-30T18:18:17.138371Z","caller":"traceutil/trace.go:171","msg":"trace[1770688650] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8235; }","duration":"219.104499ms","start":"2026-06-30T18:18:16.91926Z","end":"2026-06-30T18:18:17.138364Z","steps":["trace[1770688650] 'agreement among raft nodes before linearized reading' (duration: 218.977397ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:18:25.292609Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6059} {"level":"info","ts":"2026-06-30T18:18:25.386706Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6059,"took":"92.219262ms","hash":119759749,"current-db-size-bytes":58388480,"current-db-size":"58 MB","current-db-size-in-use-bytes":23490560,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-06-30T18:18:25.386782Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":119759749,"revision":6059,"compact-revision":2983} {"level":"warn","ts":"2026-06-30T18:18:29.502341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.733625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-30T18:18:29.502492Z","caller":"traceutil/trace.go:171","msg":"trace[235517230] transaction","detail":"{read_only:false; response_revision:8304; number_of_response:1; }","duration":"288.789428ms","start":"2026-06-30T18:18:29.213668Z","end":"2026-06-30T18:18:29.502457Z","steps":["trace[235517230] 'process raft request' (duration: 98.840589ms)","trace[235517230] 'compare' (duration: 189.555502ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:18:58.328631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.802525ms","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-06-30T18:18:58.328774Z","caller":"traceutil/trace.go:171","msg":"trace[678332118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8510; }","duration":"190.026971ms","start":"2026-06-30T18:18:58.13872Z","end":"2026-06-30T18:18:58.328747Z","steps":["trace[678332118] 'range keys from in-memory index tree' (duration: 189.4927ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:18:58.328878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.174177ms","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-06-30T18:18:58.329016Z","caller":"traceutil/trace.go:171","msg":"trace[934730729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8510; }","duration":"174.378911ms","start":"2026-06-30T18:18:58.154609Z","end":"2026-06-30T18:18:58.328988Z","steps":["trace[934730729] 'range keys from in-memory index tree' (duration: 173.780819ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:18:58.329213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.482072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-06-30T18:18:58.329372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.849814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-30T18:18:58.329381Z","caller":"traceutil/trace.go:171","msg":"trace[184312329] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8510; }","duration":"314.790068ms","start":"2026-06-30T18:18:58.014562Z","end":"2026-06-30T18:18:58.329352Z","steps":["trace[184312329] 'range keys from in-memory index tree' (duration: 314.223897ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:18:58.329415Z","caller":"traceutil/trace.go:171","msg":"trace[1621492797] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:8510; }","duration":"219.900755ms","start":"2026-06-30T18:18:58.109503Z","end":"2026-06-30T18:18:58.329404Z","steps":["trace[1621492797] 'count revisions from in-memory index tree' (duration: 219.736482ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:18:58.32944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:18:58.014526Z","time spent":"314.89515ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-06-30T18:19:00.581644Z","caller":"traceutil/trace.go:171","msg":"trace[253746893] transaction","detail":"{read_only:false; response_revision:8522; number_of_response:1; }","duration":"182.200821ms","start":"2026-06-30T18:19:00.399417Z","end":"2026-06-30T18:19:00.581618Z","steps":["trace[253746893] 'process raft request' (duration: 182.031097ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:19:00.605348Z","caller":"traceutil/trace.go:171","msg":"trace[1314169543] transaction","detail":"{read_only:false; response_revision:8523; number_of_response:1; }","duration":"205.139012ms","start":"2026-06-30T18:19:00.400184Z","end":"2026-06-30T18:19:00.605323Z","steps":["trace[1314169543] 'process raft request' (duration: 204.209434ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:19:00.60551Z","caller":"traceutil/trace.go:171","msg":"trace[687976104] transaction","detail":"{read_only:false; response_revision:8524; number_of_response:1; }","duration":"204.308285ms","start":"2026-06-30T18:19:00.401173Z","end":"2026-06-30T18:19:00.605482Z","steps":["trace[687976104] 'process raft request' (duration: 204.10643ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:19:00.605663Z","caller":"traceutil/trace.go:171","msg":"trace[443106690] transaction","detail":"{read_only:false; response_revision:8525; number_of_response:1; }","duration":"202.795797ms","start":"2026-06-30T18:19:00.402845Z","end":"2026-06-30T18:19:00.605641Z","steps":["trace[443106690] 'process raft request' (duration: 202.601873ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:19:10.667542Z","caller":"traceutil/trace.go:171","msg":"trace[226998171] transaction","detail":"{read_only:false; response_revision:8618; number_of_response:1; }","duration":"143.509626ms","start":"2026-06-30T18:19:10.524008Z","end":"2026-06-30T18:19:10.667518Z","steps":["trace[226998171] 'process raft request' (duration: 143.370614ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:19:11.463Z","caller":"traceutil/trace.go:171","msg":"trace[1040011325] transaction","detail":"{read_only:false; response_revision:8624; number_of_response:1; }","duration":"103.736193ms","start":"2026-06-30T18:19:11.359232Z","end":"2026-06-30T18:19:11.462968Z","steps":["trace[1040011325] 'process raft request' (duration: 85.002653ms)","trace[1040011325] 'compare' (duration: 18.607858ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:19:12.073581Z","caller":"traceutil/trace.go:171","msg":"trace[2101598029] transaction","detail":"{read_only:false; response_revision:8629; number_of_response:1; }","duration":"121.041894ms","start":"2026-06-30T18:19:11.952509Z","end":"2026-06-30T18:19:12.073551Z","steps":["trace[2101598029] 'process raft request' (duration: 107.779871ms)","trace[2101598029] 'compare' (duration: 13.006988ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:19:32.162943Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023ec.wal"} {"level":"info","ts":"2026-06-30T18:20:25.248249Z","caller":"traceutil/trace.go:171","msg":"trace[1323586724] transaction","detail":"{read_only:false; response_revision:9361; number_of_response:1; }","duration":"165.513644ms","start":"2026-06-30T18:20:25.082703Z","end":"2026-06-30T18:20:25.248217Z","steps":["trace[1323586724] 'process raft request' (duration: 116.799222ms)","trace[1323586724] 'compare' (duration: 48.514828ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:21:05.119729Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3cec112083e8d3ff","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-30T18:21:05.128344Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-30T18:21:05.128499Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-30T18:23:25.299446Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8279} {"level":"info","ts":"2026-06-30T18:23:25.379343Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8279,"took":"77.400392ms","hash":2465604949,"current-db-size-bytes":58388480,"current-db-size":"58 MB","current-db-size-in-use-bytes":26525696,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-30T18:23:25.379424Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2465604949,"revision":8279,"compact-revision":6059} {"level":"info","ts":"2026-06-30T18:24:11.513644Z","caller":"traceutil/trace.go:171","msg":"trace[1065137808] linearizableReadLoop","detail":"{readStateIndex:11602; appliedIndex:11601; }","duration":"171.281426ms","start":"2026-06-30T18:24:11.342317Z","end":"2026-06-30T18:24:11.513599Z","steps":["trace[1065137808] 'read index received' (duration: 152.361817ms)","trace[1065137808] 'applied index is now lower than readState.Index' (duration: 18.917419ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:24:11.513677Z","caller":"traceutil/trace.go:171","msg":"trace[690842316] transaction","detail":"{read_only:false; response_revision:11217; number_of_response:1; }","duration":"282.591785ms","start":"2026-06-30T18:24:11.231022Z","end":"2026-06-30T18:24:11.513613Z","steps":["trace[690842316] 'process raft request' (duration: 263.757698ms)","trace[690842316] 'compare' (duration: 18.572503ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:24:11.513856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.527141ms","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-06-30T18:24:11.513943Z","caller":"traceutil/trace.go:171","msg":"trace[401149252] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11217; }","duration":"171.675084ms","start":"2026-06-30T18:24:11.342243Z","end":"2026-06-30T18:24:11.513919Z","steps":["trace[401149252] 'agreement among raft nodes before linearized reading' (duration: 171.43952ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:28:25.309262Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10770} {"level":"info","ts":"2026-06-30T18:28:25.390882Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10770,"took":"79.428726ms","hash":3478968938,"current-db-size-bytes":58388480,"current-db-size":"58 MB","current-db-size-in-use-bytes":28164096,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-30T18:28:25.390935Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3478968938,"revision":10770,"compact-revision":8279} {"level":"info","ts":"2026-06-30T18:29:55.42244Z","caller":"traceutil/trace.go:171","msg":"trace[1264130341] linearizableReadLoop","detail":"{readStateIndex:14042; appliedIndex:14041; }","duration":"129.824471ms","start":"2026-06-30T18:29:55.292572Z","end":"2026-06-30T18:29:55.422396Z","steps":["trace[1264130341] 'read index received' (duration: 129.694129ms)","trace[1264130341] 'applied index is now lower than readState.Index' (duration: 129.352µs)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:29:55.422752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.156878ms","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-06-30T18:29:55.422852Z","caller":"traceutil/trace.go:171","msg":"trace[1662314961] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13576; }","duration":"130.292381ms","start":"2026-06-30T18:29:55.292537Z","end":"2026-06-30T18:29:55.422829Z","steps":["trace[1662314961] 'agreement among raft nodes before linearized reading' (duration: 129.977976ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:29:55.423236Z","caller":"traceutil/trace.go:171","msg":"trace[1331034266] transaction","detail":"{read_only:false; response_revision:13576; number_of_response:1; }","duration":"131.733108ms","start":"2026-06-30T18:29:55.291468Z","end":"2026-06-30T18:29:55.423202Z","steps":["trace[1331034266] 'process raft request' (duration: 130.762869ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:29:55.427594Z","caller":"traceutil/trace.go:171","msg":"trace[627500215] transaction","detail":"{read_only:false; response_revision:13577; number_of_response:1; }","duration":"133.194916ms","start":"2026-06-30T18:29:55.294384Z","end":"2026-06-30T18:29:55.427579Z","steps":["trace[627500215] 'process raft request' (duration: 132.681727ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:30:17.271993Z","caller":"traceutil/trace.go:171","msg":"trace[1409136224] transaction","detail":"{read_only:false; response_revision:13693; number_of_response:1; }","duration":"131.998463ms","start":"2026-06-30T18:30:17.139963Z","end":"2026-06-30T18:30:17.271961Z","steps":["trace[1409136224] 'process raft request' (duration: 131.774729ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:30:41.59466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.485111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/ovn-northd-fc978744-ppgwh\" ","response":"range_response_count:1 size:7032"} {"level":"info","ts":"2026-06-30T18:30:41.594858Z","caller":"traceutil/trace.go:171","msg":"trace[2041232454] range","detail":"{range_begin:/registry/pods/openstack/ovn-northd-fc978744-ppgwh; range_end:; response_count:1; response_revision:13954; }","duration":"104.699136ms","start":"2026-06-30T18:30:41.490052Z","end":"2026-06-30T18:30:41.594751Z","steps":["trace[2041232454] 'range keys from in-memory index tree' (duration: 104.247936ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:30:41.877382Z","caller":"traceutil/trace.go:171","msg":"trace[1578968666] transaction","detail":"{read_only:false; response_revision:13955; number_of_response:1; }","duration":"273.550177ms","start":"2026-06-30T18:30:41.603802Z","end":"2026-06-30T18:30:41.877353Z","steps":["trace[1578968666] 'process raft request' (duration: 273.391174ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:30:42.057097Z","caller":"traceutil/trace.go:171","msg":"trace[1168263816] linearizableReadLoop","detail":"{readStateIndex:14434; appliedIndex:14432; }","duration":"387.231304ms","start":"2026-06-30T18:30:41.66983Z","end":"2026-06-30T18:30:42.057062Z","steps":["trace[1168263816] 'read index received' (duration: 207.368392ms)","trace[1168263816] 'applied index is now lower than readState.Index' (duration: 179.861862ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:30:42.057162Z","caller":"traceutil/trace.go:171","msg":"trace[1282756134] transaction","detail":"{read_only:false; response_revision:13956; number_of_response:1; }","duration":"393.644276ms","start":"2026-06-30T18:30:41.66346Z","end":"2026-06-30T18:30:42.057104Z","steps":["trace[1282756134] 'process raft request' (duration: 373.461462ms)","trace[1282756134] 'compare' (duration: 19.99895ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:30:42.057335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:30:41.663439Z","time spent":"393.814758ms","remote":"127.0.0.1:60764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":854,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-30T18:30:42.057412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.56311ms","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-06-30T18:30:42.057459Z","caller":"traceutil/trace.go:171","msg":"trace[539356389] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13956; }","duration":"387.669422ms","start":"2026-06-30T18:30:41.669776Z","end":"2026-06-30T18:30:42.057445Z","steps":["trace[539356389] 'agreement among raft nodes before linearized reading' (duration: 387.439418ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:30:42.057502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:30:41.669753Z","time spent":"387.741003ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-30T18:30:42.357886Z","caller":"traceutil/trace.go:171","msg":"trace[1935592909] linearizableReadLoop","detail":"{readStateIndex:14436; appliedIndex:14434; }","duration":"300.674033ms","start":"2026-06-30T18:30:42.057191Z","end":"2026-06-30T18:30:42.357865Z","steps":["trace[1935592909] 'read index received' (duration: 285.622787ms)","trace[1935592909] 'applied index is now lower than readState.Index' (duration: 15.050096ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:30:42.358025Z","caller":"traceutil/trace.go:171","msg":"trace[1961827019] transaction","detail":"{read_only:false; response_revision:13957; number_of_response:1; }","duration":"354.85343ms","start":"2026-06-30T18:30:42.003151Z","end":"2026-06-30T18:30:42.358005Z","steps":["trace[1961827019] 'process raft request' (duration: 339.650302ms)","trace[1961827019] 'compare' (duration: 14.719308ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:30:42.35811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.347553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-q82xs\" ","response":"range_response_count:1 size:12928"} {"level":"warn","ts":"2026-06-30T18:30:42.358166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:30:42.003094Z","time spent":"354.987162ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:30:42.358181Z","caller":"traceutil/trace.go:171","msg":"trace[1222205365] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-q82xs; range_end:; response_count:1; response_revision:13958; }","duration":"477.461734ms","start":"2026-06-30T18:30:41.880705Z","end":"2026-06-30T18:30:42.358167Z","steps":["trace[1222205365] 'agreement among raft nodes before linearized reading' (duration: 477.277581ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:30:42.358222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:30:41.880686Z","time spent":"477.527796ms","remote":"127.0.0.1:60882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":12950,"request content":"key:\"/registry/pods/openstack/openvswitch-q82xs\" "} {"level":"info","ts":"2026-06-30T18:30:42.358207Z","caller":"traceutil/trace.go:171","msg":"trace[997857033] transaction","detail":"{read_only:false; response_revision:13958; number_of_response:1; }","duration":"352.105948ms","start":"2026-06-30T18:30:42.006063Z","end":"2026-06-30T18:30:42.358169Z","steps":["trace[997857033] 'process raft request' (duration: 351.728831ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:30:42.358255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.283578ms","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-06-30T18:30:42.358308Z","caller":"traceutil/trace.go:171","msg":"trace[361763162] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13958; }","duration":"682.37328ms","start":"2026-06-30T18:30:41.67592Z","end":"2026-06-30T18:30:42.358294Z","steps":["trace[361763162] 'agreement among raft nodes before linearized reading' (duration: 682.201457ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:30:42.358335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:30:41.675896Z","time spent":"682.434251ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-30T18:30:42.358358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:30:42.006044Z","time spent":"352.22652ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:30:42.358406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.776533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-30T18:30:42.358461Z","caller":"traceutil/trace.go:171","msg":"trace[1817412576] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13958; }","duration":"687.888945ms","start":"2026-06-30T18:30:41.670557Z","end":"2026-06-30T18:30:42.358446Z","steps":["trace[1817412576] 'agreement among raft nodes before linearized reading' (duration: 687.741402ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:30:42.358503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:30:41.670545Z","time spent":"687.946106ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-30T18:30:42.365843Z","caller":"traceutil/trace.go:171","msg":"trace[1980847392] transaction","detail":"{read_only:false; response_revision:13959; number_of_response:1; }","duration":"304.884541ms","start":"2026-06-30T18:30:42.060931Z","end":"2026-06-30T18:30:42.365815Z","steps":["trace[1980847392] 'process raft request' (duration: 304.751378ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:30:42.365995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:30:42.060901Z","time spent":"305.014743ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:30:42.366879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.328552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-4qlnz.18bdef640f01bd16\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-06-30T18:30:42.366973Z","caller":"traceutil/trace.go:171","msg":"trace[1428153666] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-4qlnz.18bdef640f01bd16; range_end:; response_count:1; response_revision:13959; }","duration":"303.484745ms","start":"2026-06-30T18:30:42.06347Z","end":"2026-06-30T18:30:42.366954Z","steps":["trace[1428153666] 'agreement among raft nodes before linearized reading' (duration: 302.63313ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:30:42.367013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:30:42.063453Z","time spent":"303.551926ms","remote":"127.0.0.1:60764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":805,"request content":"key:\"/registry/events/openstack/ovn-controller-default-4qlnz.18bdef640f01bd16\" "} {"level":"warn","ts":"2026-06-30T18:30:42.366976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.530846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4vqnv\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-06-30T18:30:42.367071Z","caller":"traceutil/trace.go:171","msg":"trace[1280758590] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4vqnv; range_end:; response_count:1; response_revision:13959; }","duration":"274.670838ms","start":"2026-06-30T18:30:42.092376Z","end":"2026-06-30T18:30:42.367047Z","steps":["trace[1280758590] 'agreement among raft nodes before linearized reading' (duration: 274.338442ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:33:25.325006Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12932} {"level":"info","ts":"2026-06-30T18:33:25.398856Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12932,"took":"71.717078ms","hash":2586991342,"current-db-size-bytes":58388480,"current-db-size":"58 MB","current-db-size-in-use-bytes":31232000,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-30T18:33:25.398919Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2586991342,"revision":12932,"compact-revision":10770} {"level":"info","ts":"2026-06-30T18:37:58.802427Z","caller":"traceutil/trace.go:171","msg":"trace[1481112861] transaction","detail":"{read_only:false; response_revision:17635; number_of_response:1; }","duration":"173.750713ms","start":"2026-06-30T18:37:58.62865Z","end":"2026-06-30T18:37:58.802401Z","steps":["trace[1481112861] 'process raft request' (duration: 173.60562ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:37:58.803292Z","caller":"traceutil/trace.go:171","msg":"trace[516238749] linearizableReadLoop","detail":"{readStateIndex:18234; appliedIndex:18233; }","duration":"169.927581ms","start":"2026-06-30T18:37:58.633331Z","end":"2026-06-30T18:37:58.803258Z","steps":["trace[516238749] 'read index received' (duration: 169.095295ms)","trace[516238749] 'applied index is now lower than readState.Index' (duration: 831.096µs)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:37:58.803543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.196656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-30T18:37:58.803588Z","caller":"traceutil/trace.go:171","msg":"trace[159894381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17637; }","duration":"170.282697ms","start":"2026-06-30T18:37:58.633293Z","end":"2026-06-30T18:37:58.803575Z","steps":["trace[159894381] 'agreement among raft nodes before linearized reading' (duration: 170.079954ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:37:58.803712Z","caller":"traceutil/trace.go:171","msg":"trace[1472059136] transaction","detail":"{read_only:false; response_revision:17636; number_of_response:1; }","duration":"173.62705ms","start":"2026-06-30T18:37:58.630064Z","end":"2026-06-30T18:37:58.803691Z","steps":["trace[1472059136] 'process raft request' (duration: 172.967327ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:38:04.838525Z","caller":"traceutil/trace.go:171","msg":"trace[55031089] linearizableReadLoop","detail":"{readStateIndex:18278; appliedIndex:18277; }","duration":"204.18592ms","start":"2026-06-30T18:38:04.634315Z","end":"2026-06-30T18:38:04.838501Z","steps":["trace[55031089] 'read index received' (duration: 204.148759ms)","trace[55031089] 'applied index is now lower than readState.Index' (duration: 36.001µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:38:04.838655Z","caller":"traceutil/trace.go:171","msg":"trace[762683120] transaction","detail":"{read_only:false; response_revision:17679; number_of_response:1; }","duration":"251.899674ms","start":"2026-06-30T18:38:04.586709Z","end":"2026-06-30T18:38:04.838608Z","steps":["trace[762683120] 'process raft request' (duration: 251.190051ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:38:04.838838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.11565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-30T18:38:04.838885Z","caller":"traceutil/trace.go:171","msg":"trace[1322253634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17679; }","duration":"202.211572ms","start":"2026-06-30T18:38:04.63666Z","end":"2026-06-30T18:38:04.838872Z","steps":["trace[1322253634] 'agreement among raft nodes before linearized reading' (duration: 202.070199ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:38:04.838812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.469175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-30T18:38:04.839028Z","caller":"traceutil/trace.go:171","msg":"trace[30607891] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17679; }","duration":"204.6911ms","start":"2026-06-30T18:38:04.634273Z","end":"2026-06-30T18:38:04.838964Z","steps":["trace[30607891] 'agreement among raft nodes before linearized reading' (duration: 204.377044ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:38:04.964841Z","caller":"traceutil/trace.go:171","msg":"trace[201383247] transaction","detail":"{read_only:false; response_revision:17681; number_of_response:1; }","duration":"116.837544ms","start":"2026-06-30T18:38:04.84798Z","end":"2026-06-30T18:38:04.964817Z","steps":["trace[201383247] 'process raft request' (duration: 106.814925ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:38:04.965066Z","caller":"traceutil/trace.go:171","msg":"trace[327273112] transaction","detail":"{read_only:false; response_revision:17682; number_of_response:1; }","duration":"115.814296ms","start":"2026-06-30T18:38:04.849224Z","end":"2026-06-30T18:38:04.965039Z","steps":["trace[327273112] 'process raft request' (duration: 115.5483ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:38:04.965269Z","caller":"traceutil/trace.go:171","msg":"trace[1977765981] transaction","detail":"{read_only:false; response_revision:17683; number_of_response:1; }","duration":"115.608262ms","start":"2026-06-30T18:38:04.849638Z","end":"2026-06-30T18:38:04.965246Z","steps":["trace[1977765981] 'process raft request' (duration: 115.352847ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:38:04.965298Z","caller":"traceutil/trace.go:171","msg":"trace[320709620] transaction","detail":"{read_only:false; response_revision:17684; number_of_response:1; }","duration":"115.568461ms","start":"2026-06-30T18:38:04.849715Z","end":"2026-06-30T18:38:04.965283Z","steps":["trace[320709620] 'process raft request' (duration: 115.461239ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:38:05.254771Z","caller":"traceutil/trace.go:171","msg":"trace[952219953] transaction","detail":"{read_only:false; response_revision:17685; number_of_response:1; }","duration":"295.398878ms","start":"2026-06-30T18:38:04.959351Z","end":"2026-06-30T18:38:05.25475Z","steps":["trace[952219953] 'process raft request' (duration: 295.247436ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:38:05.291052Z","caller":"traceutil/trace.go:171","msg":"trace[924354313] linearizableReadLoop","detail":"{readStateIndex:18286; appliedIndex:18283; }","duration":"228.634493ms","start":"2026-06-30T18:38:05.062392Z","end":"2026-06-30T18:38:05.291026Z","steps":["trace[924354313] 'read index received' (duration: 192.238453ms)","trace[924354313] 'applied index is now lower than readState.Index' (duration: 36.39482ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:38:05.291097Z","caller":"traceutil/trace.go:171","msg":"trace[1968423684] transaction","detail":"{read_only:false; response_revision:17686; number_of_response:1; }","duration":"319.171559ms","start":"2026-06-30T18:38:04.971894Z","end":"2026-06-30T18:38:05.291066Z","steps":["trace[1968423684] 'process raft request' (duration: 318.946895ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:38:05.291158Z","caller":"traceutil/trace.go:171","msg":"trace[2077597565] transaction","detail":"{read_only:false; response_revision:17687; number_of_response:1; }","duration":"307.419856ms","start":"2026-06-30T18:38:04.983704Z","end":"2026-06-30T18:38:05.291124Z","steps":["trace[2077597565] 'process raft request' (duration: 307.279983ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:38:05.291262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:38:04.971867Z","time spent":"319.305822ms","remote":"127.0.0.1:60964","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-06-30T18:38:05.291336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:38:04.983682Z","time spent":"307.555988ms","remote":"127.0.0.1:60964","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-06-30T18:38:05.291509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.755402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18754"} {"level":"info","ts":"2026-06-30T18:38:05.291553Z","caller":"traceutil/trace.go:171","msg":"trace[1537039332] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:17687; }","duration":"137.835613ms","start":"2026-06-30T18:38:05.153705Z","end":"2026-06-30T18:38:05.291541Z","steps":["trace[1537039332] 'agreement among raft nodes before linearized reading' (duration: 137.730881ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:38:05.291513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.105602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-k7sl8\" ","response":"range_response_count:1 size:20756"} {"level":"info","ts":"2026-06-30T18:38:05.291636Z","caller":"traceutil/trace.go:171","msg":"trace[733007082] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-k7sl8; range_end:; response_count:1; response_revision:17687; }","duration":"229.277476ms","start":"2026-06-30T18:38:05.062346Z","end":"2026-06-30T18:38:05.291623Z","steps":["trace[733007082] 'agreement among raft nodes before linearized reading' (duration: 229.025841ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:38:05.705774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.150794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:38:05.706013Z","caller":"traceutil/trace.go:171","msg":"trace[9050306] linearizableReadLoop","detail":"{readStateIndex:18288; appliedIndex:18287; }","duration":"361.758728ms","start":"2026-06-30T18:38:05.344208Z","end":"2026-06-30T18:38:05.705967Z","steps":["trace[9050306] 'read index received' (duration: 238.207275ms)","trace[9050306] 'applied index is now lower than readState.Index' (duration: 123.548403ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:38:05.706079Z","caller":"traceutil/trace.go:171","msg":"trace[958217957] transaction","detail":"{read_only:false; response_revision:17689; number_of_response:1; }","duration":"394.149092ms","start":"2026-06-30T18:38:05.311875Z","end":"2026-06-30T18:38:05.706024Z","steps":["trace[958217957] 'process raft request' (duration: 270.572829ms)","trace[958217957] 'compare' (duration: 120.971713ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:38:05.70619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:38:05.311842Z","time spent":"394.291634ms","remote":"127.0.0.1:60882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20871,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:38:05.706211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.995002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-bgdmf\" ","response":"range_response_count:1 size:22126"} {"level":"info","ts":"2026-06-30T18:38:05.706278Z","caller":"traceutil/trace.go:171","msg":"trace[1969289452] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-bgdmf; range_end:; response_count:1; response_revision:17689; }","duration":"362.107524ms","start":"2026-06-30T18:38:05.34415Z","end":"2026-06-30T18:38:05.706258Z","steps":["trace[1969289452] 'agreement among raft nodes before linearized reading' (duration: 361.934701ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:38:05.706307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:38:05.344131Z","time spent":"362.169785ms","remote":"127.0.0.1:60882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22148,"request content":"key:\"/registry/pods/openstack/nova-compute-default-bgdmf\" "} {"level":"warn","ts":"2026-06-30T18:38:05.706408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.641074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:38:05.706441Z","caller":"traceutil/trace.go:171","msg":"trace[1193255645] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:17689; }","duration":"284.680346ms","start":"2026-06-30T18:38:05.421739Z","end":"2026-06-30T18:38:05.70642Z","steps":["trace[1193255645] 'agreement among raft nodes before linearized reading' (duration: 284.645655ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:38:05.706453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.179718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-lwbsj\" ","response":"range_response_count:1 size:9797"} {"level":"info","ts":"2026-06-30T18:38:05.706495Z","caller":"traceutil/trace.go:171","msg":"trace[837044427] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-lwbsj; range_end:; response_count:1; response_revision:17689; }","duration":"311.2944ms","start":"2026-06-30T18:38:05.395188Z","end":"2026-06-30T18:38:05.706483Z","steps":["trace[837044427] 'agreement among raft nodes before linearized reading' (duration: 310.969494ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:38:05.710862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:38:05.395164Z","time spent":"315.678313ms","remote":"127.0.0.1:60882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9819,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-lwbsj\" "} {"level":"info","ts":"2026-06-30T18:38:25.332007Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15471} {"level":"info","ts":"2026-06-30T18:38:25.425853Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15471,"took":"90.995314ms","hash":1623403574,"current-db-size-bytes":58388480,"current-db-size":"58 MB","current-db-size-in-use-bytes":33796096,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-30T18:38:25.42593Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1623403574,"revision":15471,"compact-revision":12932} {"level":"info","ts":"2026-06-30T18:41:29.675034Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3cec112083e8d3ff","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-30T18:41:29.679605Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-30T18:41:29.679737Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-06-30T18:43:19.017097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.573998ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:43:19.01732Z","caller":"traceutil/trace.go:171","msg":"trace[121679084] linearizableReadLoop","detail":"{readStateIndex:20779; appliedIndex:20777; }","duration":"118.779093ms","start":"2026-06-30T18:43:18.89852Z","end":"2026-06-30T18:43:19.017299Z","steps":["trace[121679084] 'read index received' (duration: 17.527463ms)","trace[121679084] 'applied index is now lower than readState.Index' (duration: 101.25076ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:43:19.017519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.008258ms","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-06-30T18:43:19.017557Z","caller":"traceutil/trace.go:171","msg":"trace[2048406571] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20101; }","duration":"119.09569ms","start":"2026-06-30T18:43:18.898447Z","end":"2026-06-30T18:43:19.017543Z","steps":["trace[2048406571] 'agreement among raft nodes before linearized reading' (duration: 118.928457ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:43:19.017791Z","caller":"traceutil/trace.go:171","msg":"trace[1953831366] transaction","detail":"{read_only:false; response_revision:20100; number_of_response:1; }","duration":"368.414391ms","start":"2026-06-30T18:43:18.649364Z","end":"2026-06-30T18:43:19.017778Z","steps":["trace[1953831366] 'process raft request' (duration: 217.060218ms)","trace[1953831366] 'compare' (duration: 150.107189ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:43:19.017955Z","caller":"traceutil/trace.go:171","msg":"trace[1880877173] transaction","detail":"{read_only:false; response_revision:20101; number_of_response:1; }","duration":"367.908521ms","start":"2026-06-30T18:43:18.650026Z","end":"2026-06-30T18:43:19.017935Z","steps":["trace[1880877173] 'process raft request' (duration: 367.185387ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:43:19.018008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:43:18.649998Z","time spent":"367.984622ms","remote":"127.0.0.1:60964","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-06-30T18:43:19.018292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:43:18.649343Z","time spent":"368.478672ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:43:19.162796Z","caller":"traceutil/trace.go:171","msg":"trace[1215771022] transaction","detail":"{read_only:false; response_revision:20102; number_of_response:1; }","duration":"135.605414ms","start":"2026-06-30T18:43:19.027165Z","end":"2026-06-30T18:43:19.16277Z","steps":["trace[1215771022] 'process raft request' (duration: 132.966863ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:43:19.376431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.516032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:43:19.376516Z","caller":"traceutil/trace.go:171","msg":"trace[120776612] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20103; }","duration":"105.650415ms","start":"2026-06-30T18:43:19.270847Z","end":"2026-06-30T18:43:19.376497Z","steps":["trace[120776612] 'range keys from in-memory index tree' (duration: 105.36038ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:43:19.638251Z","caller":"traceutil/trace.go:171","msg":"trace[1549590123] transaction","detail":"{read_only:false; response_revision:20104; number_of_response:1; }","duration":"189.128478ms","start":"2026-06-30T18:43:19.449086Z","end":"2026-06-30T18:43:19.638214Z","steps":["trace[1549590123] 'process raft request' (duration: 188.948515ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:43:19.936997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.849802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:43:19.937097Z","caller":"traceutil/trace.go:171","msg":"trace[1105801] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:20104; }","duration":"249.015545ms","start":"2026-06-30T18:43:19.688058Z","end":"2026-06-30T18:43:19.937074Z","steps":["trace[1105801] 'count revisions from in-memory index tree' (duration: 248.76788ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:43:25.353765Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18034} {"level":"info","ts":"2026-06-30T18:43:25.481169Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18034,"took":"123.931162ms","hash":4097093489,"current-db-size-bytes":58388480,"current-db-size":"58 MB","current-db-size-in-use-bytes":29917184,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-30T18:43:25.48124Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4097093489,"revision":18034,"compact-revision":15471} {"level":"info","ts":"2026-06-30T18:47:05.042659Z","caller":"traceutil/trace.go:171","msg":"trace[316516471] transaction","detail":"{read_only:false; response_revision:22503; number_of_response:1; }","duration":"252.929869ms","start":"2026-06-30T18:47:04.7897Z","end":"2026-06-30T18:47:05.04263Z","steps":["trace[316516471] 'process raft request' (duration: 252.740525ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:48:25.359323Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20134} {"level":"info","ts":"2026-06-30T18:48:25.46384Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20134,"took":"100.276053ms","hash":2498957159,"current-db-size-bytes":60080128,"current-db-size":"60 MB","current-db-size-in-use-bytes":43192320,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-06-30T18:48:25.463907Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2498957159,"revision":20134,"compact-revision":18034} {"level":"info","ts":"2026-06-30T18:50:25.943929Z","caller":"traceutil/trace.go:171","msg":"trace[1298507024] transaction","detail":"{read_only:false; response_revision:24570; number_of_response:1; }","duration":"201.097561ms","start":"2026-06-30T18:50:25.742799Z","end":"2026-06-30T18:50:25.943897Z","steps":["trace[1298507024] 'process raft request' (duration: 200.939679ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:26.134553Z","caller":"traceutil/trace.go:171","msg":"trace[1669799994] transaction","detail":"{read_only:false; response_revision:24571; number_of_response:1; }","duration":"390.824075ms","start":"2026-06-30T18:50:25.743697Z","end":"2026-06-30T18:50:26.134521Z","steps":["trace[1669799994] 'process raft request' (duration: 362.671492ms)","trace[1669799994] 'compare' (duration: 27.439269ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:26.134663Z","caller":"traceutil/trace.go:171","msg":"trace[1003216908] linearizableReadLoop","detail":"{readStateIndex:25372; appliedIndex:25370; }","duration":"266.110902ms","start":"2026-06-30T18:50:25.868518Z","end":"2026-06-30T18:50:26.134629Z","steps":["trace[1003216908] 'read index received' (duration: 75.292017ms)","trace[1003216908] 'applied index is now lower than readState.Index' (duration: 190.815805ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:26.134812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:25.743672Z","time spent":"391.04376ms","remote":"127.0.0.1:60882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8447,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:50:26.134801Z","caller":"traceutil/trace.go:171","msg":"trace[1506990890] transaction","detail":"{read_only:false; response_revision:24572; number_of_response:1; }","duration":"233.973624ms","start":"2026-06-30T18:50:25.90078Z","end":"2026-06-30T18:50:26.134754Z","steps":["trace[1506990890] 'process raft request' (duration: 233.174078ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:26.134856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.533588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:50:26.134921Z","caller":"traceutil/trace.go:171","msg":"trace[1209876909] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:24573; }","duration":"221.610509ms","start":"2026-06-30T18:50:25.913293Z","end":"2026-06-30T18:50:26.134904Z","steps":["trace[1209876909] 'agreement among raft nodes before linearized reading' (duration: 221.503197ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:26.134856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.310606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:50:26.13499Z","caller":"traceutil/trace.go:171","msg":"trace[181702611] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:24573; }","duration":"266.491869ms","start":"2026-06-30T18:50:25.868475Z","end":"2026-06-30T18:50:26.134967Z","steps":["trace[181702611] 'agreement among raft nodes before linearized reading' (duration: 266.305486ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:26.335071Z","caller":"traceutil/trace.go:171","msg":"trace[997779511] transaction","detail":"{read_only:false; response_revision:24574; number_of_response:1; }","duration":"193.186181ms","start":"2026-06-30T18:50:26.141857Z","end":"2026-06-30T18:50:26.335043Z","steps":["trace[997779511] 'process raft request' (duration: 142.220005ms)","trace[997779511] 'compare' (duration: 50.833364ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:26.550641Z","caller":"traceutil/trace.go:171","msg":"trace[728049871] linearizableReadLoop","detail":"{readStateIndex:25378; appliedIndex:25375; }","duration":"192.895115ms","start":"2026-06-30T18:50:26.357728Z","end":"2026-06-30T18:50:26.550623Z","steps":["trace[728049871] 'read index received' (duration: 109.367352ms)","trace[728049871] 'applied index is now lower than readState.Index' (duration: 83.527123ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:26.550666Z","caller":"traceutil/trace.go:171","msg":"trace[764766223] transaction","detail":"{read_only:false; response_revision:24576; number_of_response:1; }","duration":"407.779677ms","start":"2026-06-30T18:50:26.142856Z","end":"2026-06-30T18:50:26.550636Z","steps":["trace[764766223] 'process raft request' (duration: 407.616524ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:26.550748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.016157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1989"} {"level":"info","ts":"2026-06-30T18:50:26.550776Z","caller":"traceutil/trace.go:171","msg":"trace[1746562056] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:24577; }","duration":"193.093519ms","start":"2026-06-30T18:50:26.357672Z","end":"2026-06-30T18:50:26.550765Z","steps":["trace[1746562056] 'agreement among raft nodes before linearized reading' (duration: 193.018467ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:26.550818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:26.14285Z","time spent":"407.891989ms","remote":"127.0.0.1:32954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7536,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:50:26.550989Z","caller":"traceutil/trace.go:171","msg":"trace[340495118] transaction","detail":"{read_only:false; response_revision:24577; number_of_response:1; }","duration":"407.771746ms","start":"2026-06-30T18:50:26.143208Z","end":"2026-06-30T18:50:26.550979Z","steps":["trace[340495118] 'process raft request' (duration: 407.351309ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:26.551017Z","caller":"traceutil/trace.go:171","msg":"trace[815374324] transaction","detail":"{read_only:false; response_revision:24575; number_of_response:1; }","duration":"408.453479ms","start":"2026-06-30T18:50:26.14255Z","end":"2026-06-30T18:50:26.551004Z","steps":["trace[815374324] 'process raft request' (duration: 324.545809ms)","trace[815374324] 'compare' (duration: 83.065515ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:26.551058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:26.143201Z","time spent":"407.811197ms","remote":"127.0.0.1:60858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":652,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:50:26.551068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:26.142537Z","time spent":"408.503591ms","remote":"127.0.0.1:60974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1167,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:50:31.238679Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103393794135164,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-30T18:50:31.256854Z","caller":"traceutil/trace.go:171","msg":"trace[1833898183] transaction","detail":"{read_only:false; response_revision:24613; number_of_response:1; }","duration":"535.236542ms","start":"2026-06-30T18:50:30.721589Z","end":"2026-06-30T18:50:31.256826Z","steps":["trace[1833898183] 'process raft request' (duration: 534.986208ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:31.257166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:30.721566Z","time spent":"535.358984ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:50:31.519312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.253649ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:50:31.519399Z","caller":"traceutil/trace.go:171","msg":"trace[29273310] linearizableReadLoop","detail":"{readStateIndex:25416; appliedIndex:25414; }","duration":"781.394907ms","start":"2026-06-30T18:50:30.737991Z","end":"2026-06-30T18:50:31.519385Z","steps":["trace[29273310] 'read index received' (duration: 518.574376ms)","trace[29273310] 'applied index is now lower than readState.Index' (duration: 262.819491ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:31.51943Z","caller":"traceutil/trace.go:171","msg":"trace[1603945510] transaction","detail":"{read_only:false; response_revision:24614; number_of_response:1; }","duration":"759.250137ms","start":"2026-06-30T18:50:30.760149Z","end":"2026-06-30T18:50:31.519399Z","steps":["trace[1603945510] 'process raft request' (duration: 609.833816ms)","trace[1603945510] 'compare' (duration: 148.944443ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:31.519595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:30.760133Z","time spent":"759.381479ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:50:31.519608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.595661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-30T18:50:31.519672Z","caller":"traceutil/trace.go:171","msg":"trace[1318719337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24614; }","duration":"781.703642ms","start":"2026-06-30T18:50:30.73795Z","end":"2026-06-30T18:50:31.519653Z","steps":["trace[1318719337] 'agreement among raft nodes before linearized reading' (duration: 781.484458ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:31.519729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:30.737933Z","time spent":"781.781334ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-30T18:50:31.926801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.160738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:50:31.926957Z","caller":"traceutil/trace.go:171","msg":"trace[1736054333] linearizableReadLoop","detail":"{readStateIndex:25418; appliedIndex:25416; }","duration":"407.527912ms","start":"2026-06-30T18:50:31.519418Z","end":"2026-06-30T18:50:31.926945Z","steps":["trace[1736054333] 'read index received' (duration: 145.171571ms)","trace[1736054333] 'applied index is now lower than readState.Index' (duration: 262.355601ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:31.927129Z","caller":"traceutil/trace.go:171","msg":"trace[1528823591] transaction","detail":"{read_only:false; response_revision:24615; number_of_response:1; }","duration":"669.023799ms","start":"2026-06-30T18:50:31.258096Z","end":"2026-06-30T18:50:31.927119Z","steps":["trace[1528823591] 'process raft request' (duration: 406.478954ms)","trace[1528823591] 'compare' (duration: 261.544855ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:31.927179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:31.258068Z","time spent":"669.085039ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:50:31.927318Z","caller":"traceutil/trace.go:171","msg":"trace[1643247200] transaction","detail":"{read_only:false; response_revision:24616; number_of_response:1; }","duration":"512.172256ms","start":"2026-06-30T18:50:31.415121Z","end":"2026-06-30T18:50:31.927294Z","steps":["trace[1643247200] 'process raft request' (duration: 511.755248ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:31.927352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:31.415096Z","time spent":"512.237007ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:50:31.927436Z","caller":"traceutil/trace.go:171","msg":"trace[789420612] transaction","detail":"{read_only:false; response_revision:24617; number_of_response:1; }","duration":"403.017577ms","start":"2026-06-30T18:50:31.524412Z","end":"2026-06-30T18:50:31.92743Z","steps":["trace[789420612] 'process raft request' (duration: 402.505708ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:31.927468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:31.524394Z","time spent":"403.055649ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:50:31.927594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.200529ms","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-06-30T18:50:31.927614Z","caller":"traceutil/trace.go:171","msg":"trace[739712113] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24617; }","duration":"680.25434ms","start":"2026-06-30T18:50:31.247351Z","end":"2026-06-30T18:50:31.927606Z","steps":["trace[739712113] 'agreement among raft nodes before linearized reading' (duration: 680.184559ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:31.927632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:31.247331Z","time spent":"680.296671ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-30T18:50:31.927794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.178019663s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-30T18:50:31.927856Z","caller":"traceutil/trace.go:171","msg":"trace[411130758] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24617; }","duration":"1.178123736s","start":"2026-06-30T18:50:30.749717Z","end":"2026-06-30T18:50:31.927841Z","steps":["trace[411130758] 'agreement among raft nodes before linearized reading' (duration: 1.177947262s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:31.927884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:30.749698Z","time spent":"1.178179896s","remote":"127.0.0.1:60964","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-06-30T18:50:31.927985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.050987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:50:31.928006Z","caller":"traceutil/trace.go:171","msg":"trace[1316846052] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:24617; }","duration":"746.103629ms","start":"2026-06-30T18:50:31.181894Z","end":"2026-06-30T18:50:31.927997Z","steps":["trace[1316846052] 'agreement among raft nodes before linearized reading' (duration: 746.059098ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:31.928025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:31.181882Z","time spent":"746.136319ms","remote":"127.0.0.1:57542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true "} {"level":"warn","ts":"2026-06-30T18:50:31.928152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.781914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:50:31.928174Z","caller":"traceutil/trace.go:171","msg":"trace[409614742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24617; }","duration":"848.829635ms","start":"2026-06-30T18:50:31.079335Z","end":"2026-06-30T18:50:31.928165Z","steps":["trace[409614742] 'agreement among raft nodes before linearized reading' (duration: 848.797834ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:31.928191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:31.07932Z","time spent":"848.866355ms","remote":"127.0.0.1:60648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-30T18:50:31.928298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.402081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:50:31.928317Z","caller":"traceutil/trace.go:171","msg":"trace[685308385] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:24617; }","duration":"940.463762ms","start":"2026-06-30T18:50:30.987847Z","end":"2026-06-30T18:50:31.928311Z","steps":["trace[685308385] 'agreement among raft nodes before linearized reading' (duration: 940.426082ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:31.928334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:30.987826Z","time spent":"940.502953ms","remote":"127.0.0.1:32870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-06-30T18:50:31.928474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124191483s","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-06-30T18:50:31.92851Z","caller":"traceutil/trace.go:171","msg":"trace[215152514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24617; }","duration":"1.124269255s","start":"2026-06-30T18:50:30.804231Z","end":"2026-06-30T18:50:31.9285Z","steps":["trace[215152514] 'agreement among raft nodes before linearized reading' (duration: 1.124171443s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:31.928532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:30.804209Z","time spent":"1.124317545s","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-30T18:50:31.928525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.62333ms","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-06-30T18:50:31.928588Z","caller":"traceutil/trace.go:171","msg":"trace[1404208341] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24617; }","duration":"402.738362ms","start":"2026-06-30T18:50:31.525832Z","end":"2026-06-30T18:50:31.92857Z","steps":["trace[1404208341] 'agreement among raft nodes before linearized reading' (duration: 402.558479ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:31.928618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:31.525808Z","time spent":"402.802534ms","remote":"127.0.0.1:60964","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-06-30T18:50:31.928611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.149340239s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-30T18:50:31.928681Z","caller":"traceutil/trace.go:171","msg":"trace[1766658034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24617; }","duration":"1.149462902s","start":"2026-06-30T18:50:30.7792Z","end":"2026-06-30T18:50:31.928663Z","steps":["trace[1766658034] 'agreement among raft nodes before linearized reading' (duration: 1.149251468s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:31.928719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:30.779175Z","time spent":"1.149536374s","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-30T18:50:32.562015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.762328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:50:32.562111Z","caller":"traceutil/trace.go:171","msg":"trace[1901344147] transaction","detail":"{read_only:false; response_revision:24618; number_of_response:1; }","duration":"630.10547ms","start":"2026-06-30T18:50:31.931988Z","end":"2026-06-30T18:50:32.562094Z","steps":["trace[1901344147] 'process raft request' (duration: 442.194699ms)","trace[1901344147] 'compare' (duration: 187.220188ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:32.562151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:31.931967Z","time spent":"630.165521ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:50:33.053585Z","caller":"traceutil/trace.go:171","msg":"trace[1699924178] transaction","detail":"{read_only:false; response_revision:24619; number_of_response:1; }","duration":"1.121278618s","start":"2026-06-30T18:50:31.932281Z","end":"2026-06-30T18:50:33.05356Z","steps":["trace[1699924178] 'process raft request' (duration: 1.024241138s)","trace[1699924178] 'compare' (duration: 96.868165ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:33.053693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:31.932274Z","time spent":"1.121364519s","remote":"127.0.0.1:60964","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-06-30T18:50:33.053748Z","caller":"traceutil/trace.go:171","msg":"trace[227887891] linearizableReadLoop","detail":"{readStateIndex:25427; appliedIndex:25419; }","duration":"861.324202ms","start":"2026-06-30T18:50:32.192402Z","end":"2026-06-30T18:50:33.053726Z","steps":["trace[227887891] 'read index received' (duration: 181.795114ms)","trace[227887891] 'applied index is now lower than readState.Index' (duration: 679.528128ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:33.053822Z","caller":"traceutil/trace.go:171","msg":"trace[1466829824] transaction","detail":"{read_only:false; response_revision:24622; number_of_response:1; }","duration":"1.120217108s","start":"2026-06-30T18:50:31.933584Z","end":"2026-06-30T18:50:33.053801Z","steps":["trace[1466829824] 'process raft request' (duration: 1.120005944s)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:33.053866Z","caller":"traceutil/trace.go:171","msg":"trace[650496946] transaction","detail":"{read_only:false; response_revision:24623; number_of_response:1; }","duration":"1.089786182s","start":"2026-06-30T18:50:31.964068Z","end":"2026-06-30T18:50:33.053854Z","steps":["trace[650496946] 'process raft request' (duration: 1.089554428s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:33.053933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:31.933564Z","time spent":"1.120298979s","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:50:33.05394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:31.964048Z","time spent":"1.089840733s","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:50:33.054032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.637047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-30T18:50:33.054058Z","caller":"traceutil/trace.go:171","msg":"trace[1956203813] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:24625; }","duration":"861.702079ms","start":"2026-06-30T18:50:32.192348Z","end":"2026-06-30T18:50:33.05405Z","steps":["trace[1956203813] 'agreement among raft nodes before linearized reading' (duration: 861.655048ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:33.054082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:32.192326Z","time spent":"861.74661ms","remote":"127.0.0.1:34172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":30,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"warn","ts":"2026-06-30T18:50:33.054077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.11576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:50:33.054112Z","caller":"traceutil/trace.go:171","msg":"trace[1728170626] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:24625; }","duration":"803.18506ms","start":"2026-06-30T18:50:32.250914Z","end":"2026-06-30T18:50:33.054099Z","steps":["trace[1728170626] 'agreement among raft nodes before linearized reading' (duration: 803.131169ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:33.05412Z","caller":"traceutil/trace.go:171","msg":"trace[1163061733] transaction","detail":"{read_only:false; response_revision:24620; number_of_response:1; }","duration":"1.121784337s","start":"2026-06-30T18:50:31.932324Z","end":"2026-06-30T18:50:33.054108Z","steps":["trace[1163061733] 'process raft request' (duration: 1.121184436s)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:33.054142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:32.250894Z","time spent":"803.240732ms","remote":"127.0.0.1:57382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true "} {"level":"info","ts":"2026-06-30T18:50:33.054178Z","caller":"traceutil/trace.go:171","msg":"trace[1085590800] transaction","detail":"{read_only:false; response_revision:24624; number_of_response:1; }","duration":"893.473881ms","start":"2026-06-30T18:50:32.160698Z","end":"2026-06-30T18:50:33.054172Z","steps":["trace[1085590800] 'process raft request' (duration: 892.955961ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:33.054177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:31.932319Z","time spent":"1.121825588s","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:50:33.054214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:32.160661Z","time spent":"893.532012ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:50:33.054269Z","caller":"traceutil/trace.go:171","msg":"trace[696301914] transaction","detail":"{read_only:false; response_revision:24621; number_of_response:1; }","duration":"1.121682455s","start":"2026-06-30T18:50:31.932577Z","end":"2026-06-30T18:50:33.05426Z","steps":["trace[696301914] 'process raft request' (duration: 1.120975322s)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:33.054279Z","caller":"traceutil/trace.go:171","msg":"trace[139061216] transaction","detail":"{read_only:false; response_revision:24625; number_of_response:1; }","duration":"890.066227ms","start":"2026-06-30T18:50:32.164208Z","end":"2026-06-30T18:50:33.054274Z","steps":["trace[139061216] 'process raft request' (duration: 889.480896ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:33.054306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:32.164195Z","time spent":"890.095987ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:50:33.054315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:31.93257Z","time spent":"1.121720276s","remote":"127.0.0.1:60964","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-06-30T18:50:33.054633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.322136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:50:33.054718Z","caller":"traceutil/trace.go:171","msg":"trace[1968717765] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:24625; }","duration":"562.460907ms","start":"2026-06-30T18:50:32.492233Z","end":"2026-06-30T18:50:33.054694Z","steps":["trace[1968717765] 'agreement among raft nodes before linearized reading' (duration: 562.287464ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:33.054759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:32.492209Z","time spent":"562.538649ms","remote":"127.0.0.1:34624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-06-30T18:50:33.056394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.953654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:50:33.056451Z","caller":"traceutil/trace.go:171","msg":"trace[1384855621] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:24625; }","duration":"653.069846ms","start":"2026-06-30T18:50:32.403369Z","end":"2026-06-30T18:50:33.056439Z","steps":["trace[1384855621] 'agreement among raft nodes before linearized reading' (duration: 652.948254ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:33.056476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:32.403345Z","time spent":"653.126218ms","remote":"127.0.0.1:60922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-06-30T18:50:33.364457Z","caller":"traceutil/trace.go:171","msg":"trace[164460932] transaction","detail":"{read_only:false; response_revision:24626; number_of_response:1; }","duration":"100.898022ms","start":"2026-06-30T18:50:33.263524Z","end":"2026-06-30T18:50:33.364422Z","steps":["trace[164460932] 'process raft request' (duration: 100.762309ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:33.736766Z","caller":"traceutil/trace.go:171","msg":"trace[1647997614] linearizableReadLoop","detail":"{readStateIndex:25429; appliedIndex:25428; }","duration":"198.385709ms","start":"2026-06-30T18:50:33.538354Z","end":"2026-06-30T18:50:33.73674Z","steps":["trace[1647997614] 'read index received' (duration: 178.745657ms)","trace[1647997614] 'applied index is now lower than readState.Index' (duration: 19.638632ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:33.736877Z","caller":"traceutil/trace.go:171","msg":"trace[1628797607] transaction","detail":"{read_only:false; response_revision:24627; number_of_response:1; }","duration":"213.589988ms","start":"2026-06-30T18:50:33.523253Z","end":"2026-06-30T18:50:33.736843Z","steps":["trace[1628797607] 'process raft request' (duration: 193.924056ms)","trace[1628797607] 'compare' (duration: 19.258164ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:33.736941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.563964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:50:33.737037Z","caller":"traceutil/trace.go:171","msg":"trace[1866278502] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24627; }","duration":"198.665175ms","start":"2026-06-30T18:50:33.53834Z","end":"2026-06-30T18:50:33.737005Z","steps":["trace[1866278502] 'agreement among raft nodes before linearized reading' (duration: 198.497132ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:33.974145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.374627ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-30T18:50:33.974455Z","caller":"traceutil/trace.go:171","msg":"trace[537533311] linearizableReadLoop","detail":"{readStateIndex:25431; appliedIndex:25429; }","duration":"237.598032ms","start":"2026-06-30T18:50:33.736844Z","end":"2026-06-30T18:50:33.974442Z","steps":["trace[537533311] 'read index received' (duration: 36.871439ms)","trace[537533311] 'applied index is now lower than readState.Index' (duration: 200.725963ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:33.97454Z","caller":"traceutil/trace.go:171","msg":"trace[213351962] transaction","detail":"{read_only:false; response_revision:24628; number_of_response:1; }","duration":"387.347029ms","start":"2026-06-30T18:50:33.587162Z","end":"2026-06-30T18:50:33.974509Z","steps":["trace[213351962] 'process raft request' (duration: 387.067204ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:33.974622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.654998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-06-30T18:50:33.97466Z","caller":"traceutil/trace.go:171","msg":"trace[152557199] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24628; }","duration":"270.73197ms","start":"2026-06-30T18:50:33.703916Z","end":"2026-06-30T18:50:33.974648Z","steps":["trace[152557199] 'agreement among raft nodes before linearized reading' (duration: 270.587617ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:33.974724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:33.587142Z","time spent":"387.500143ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:50:34.167094Z","caller":"traceutil/trace.go:171","msg":"trace[665462468] linearizableReadLoop","detail":"{readStateIndex:25433; appliedIndex:25431; }","duration":"192.60092ms","start":"2026-06-30T18:50:33.974481Z","end":"2026-06-30T18:50:34.167082Z","steps":["trace[665462468] 'read index received' (duration: 191.897496ms)","trace[665462468] 'applied index is now lower than readState.Index' (duration: 702.664µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:34.167207Z","caller":"traceutil/trace.go:171","msg":"trace[1076886962] transaction","detail":"{read_only:false; response_revision:24630; number_of_response:1; }","duration":"232.018947ms","start":"2026-06-30T18:50:33.935165Z","end":"2026-06-30T18:50:34.167184Z","steps":["trace[1076886962] 'process raft request' (duration: 231.817473ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:34.167207Z","caller":"traceutil/trace.go:171","msg":"trace[427139055] transaction","detail":"{read_only:false; response_revision:24631; number_of_response:1; }","duration":"188.540123ms","start":"2026-06-30T18:50:33.978612Z","end":"2026-06-30T18:50:34.167152Z","steps":["trace[427139055] 'process raft request' (duration: 188.433101ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:34.167227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.401752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-30T18:50:34.167326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.513206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-30T18:50:34.167357Z","caller":"traceutil/trace.go:171","msg":"trace[2133160633] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24631; }","duration":"424.613586ms","start":"2026-06-30T18:50:33.74273Z","end":"2026-06-30T18:50:34.167344Z","steps":["trace[2133160633] 'agreement among raft nodes before linearized reading' (duration: 424.440773ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:34.167378Z","caller":"traceutil/trace.go:171","msg":"trace[1406343196] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24631; }","duration":"233.612977ms","start":"2026-06-30T18:50:33.93375Z","end":"2026-06-30T18:50:34.167363Z","steps":["trace[1406343196] 'agreement among raft nodes before linearized reading' (duration: 233.400373ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:34.167402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:33.742709Z","time spent":"424.671007ms","remote":"127.0.0.1:60898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-06-30T18:50:34.166885Z","caller":"traceutil/trace.go:171","msg":"trace[1123567469] transaction","detail":"{read_only:false; response_revision:24629; number_of_response:1; }","duration":"233.607257ms","start":"2026-06-30T18:50:33.933255Z","end":"2026-06-30T18:50:34.166862Z","steps":["trace[1123567469] 'process raft request' (duration: 233.044566ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:35.008243Z","caller":"traceutil/trace.go:171","msg":"trace[1353323576] linearizableReadLoop","detail":"{readStateIndex:25437; appliedIndex:25436; }","duration":"381.5278ms","start":"2026-06-30T18:50:34.626685Z","end":"2026-06-30T18:50:35.008213Z","steps":["trace[1353323576] 'read index received' (duration: 381.285255ms)","trace[1353323576] 'applied index is now lower than readState.Index' (duration: 241.345µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:35.008364Z","caller":"traceutil/trace.go:171","msg":"trace[893554029] transaction","detail":"{read_only:false; response_revision:24634; number_of_response:1; }","duration":"435.312139ms","start":"2026-06-30T18:50:34.573007Z","end":"2026-06-30T18:50:35.008319Z","steps":["trace[893554029] 'process raft request' (duration: 435.031974ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:35.008464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.747664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-30T18:50:35.008525Z","caller":"traceutil/trace.go:171","msg":"trace[286254547] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:24634; }","duration":"381.859987ms","start":"2026-06-30T18:50:34.626644Z","end":"2026-06-30T18:50:35.008504Z","steps":["trace[286254547] 'agreement among raft nodes before linearized reading' (duration: 381.740304ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:35.008522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:34.572987Z","time spent":"435.452101ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:50:35.008567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.880592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:50:35.008626Z","caller":"traceutil/trace.go:171","msg":"trace[1737876407] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:24634; }","duration":"271.974103ms","start":"2026-06-30T18:50:34.736634Z","end":"2026-06-30T18:50:35.008609Z","steps":["trace[1737876407] 'agreement among raft nodes before linearized reading' (duration: 271.876372ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:35.008575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:34.626623Z","time spent":"381.935978ms","remote":"127.0.0.1:34198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":30,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"warn","ts":"2026-06-30T18:50:35.512626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.953352ms","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-06-30T18:50:35.51271Z","caller":"traceutil/trace.go:171","msg":"trace[1321956001] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24634; }","duration":"454.067813ms","start":"2026-06-30T18:50:35.058619Z","end":"2026-06-30T18:50:35.512687Z","steps":["trace[1321956001] 'range keys from in-memory index tree' (duration: 453.818989ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:35.512781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:35.058574Z","time spent":"454.189046ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-30T18:50:35.513111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.451291ms","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-06-30T18:50:35.513148Z","caller":"traceutil/trace.go:171","msg":"trace[721382074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24634; }","duration":"454.513821ms","start":"2026-06-30T18:50:35.058623Z","end":"2026-06-30T18:50:35.513137Z","steps":["trace[721382074] 'range keys from in-memory index tree' (duration: 454.348259ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:35.513176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:35.058615Z","time spent":"454.552533ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-30T18:50:35.513212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.330318ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"warn","ts":"2026-06-30T18:50:35.513226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.179055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-06-30T18:50:35.513224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.810879ms","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-06-30T18:50:35.513313Z","caller":"traceutil/trace.go:171","msg":"trace[3389783] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24634; }","duration":"454.286237ms","start":"2026-06-30T18:50:35.059008Z","end":"2026-06-30T18:50:35.513294Z","steps":["trace[3389783] 'range keys from in-memory index tree' (duration: 454.034823ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:35.513377Z","caller":"traceutil/trace.go:171","msg":"trace[877228872] transaction","detail":"{read_only:false; response_revision:24635; number_of_response:1; }","duration":"453.977913ms","start":"2026-06-30T18:50:35.059383Z","end":"2026-06-30T18:50:35.513361Z","steps":["trace[877228872] 'process raft request' (duration: 122.405871ms)","trace[877228872] 'compare' (duration: 330.297328ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:35.513369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:35.059002Z","time spent":"454.357098ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-30T18:50:35.513502Z","caller":"traceutil/trace.go:171","msg":"trace[1452379770] linearizableReadLoop","detail":"{readStateIndex:25440; appliedIndex:25437; }","duration":"436.39579ms","start":"2026-06-30T18:50:35.077088Z","end":"2026-06-30T18:50:35.513484Z","steps":["trace[1452379770] 'read index received' (duration: 104.718025ms)","trace[1452379770] 'applied index is now lower than readState.Index' (duration: 331.676765ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:35.513321Z","caller":"traceutil/trace.go:171","msg":"trace[1727670151] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24634; }","duration":"453.934832ms","start":"2026-06-30T18:50:35.05937Z","end":"2026-06-30T18:50:35.513305Z","steps":["trace[1727670151] 'range keys from in-memory index tree' (duration: 453.591205ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:35.513586Z","caller":"traceutil/trace.go:171","msg":"trace[912878752] transaction","detail":"{read_only:false; response_revision:24636; number_of_response:1; }","duration":"454.137576ms","start":"2026-06-30T18:50:35.059436Z","end":"2026-06-30T18:50:35.513574Z","steps":["trace[912878752] 'process raft request' (duration: 453.874501ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:35.513567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:35.059365Z","time spent":"454.193607ms","remote":"127.0.0.1:60964","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-06-30T18:50:35.51365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:35.059426Z","time spent":"454.184197ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:50:35.513674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:35.059369Z","time spent":"454.038563ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:50:35.513653Z","caller":"traceutil/trace.go:171","msg":"trace[1847379154] transaction","detail":"{read_only:false; response_revision:24637; number_of_response:1; }","duration":"453.32284ms","start":"2026-06-30T18:50:35.060308Z","end":"2026-06-30T18:50:35.513631Z","steps":["trace[1847379154] 'process raft request' (duration: 453.106056ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:35.513811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.717406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:50:35.51385Z","caller":"traceutil/trace.go:171","msg":"trace[901950966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24637; }","duration":"436.776747ms","start":"2026-06-30T18:50:35.077062Z","end":"2026-06-30T18:50:35.513839Z","steps":["trace[901950966] 'agreement among raft nodes before linearized reading' (duration: 436.710826ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:35.51388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:35.077051Z","time spent":"436.822928ms","remote":"127.0.0.1:60648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-30T18:50:35.513879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:35.060301Z","time spent":"453.517174ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:50:35.519027Z","caller":"traceutil/trace.go:171","msg":"trace[653541560] transaction","detail":"{read_only:false; response_revision:24638; number_of_response:1; }","duration":"137.570407ms","start":"2026-06-30T18:50:35.38144Z","end":"2026-06-30T18:50:35.519011Z","steps":["trace[653541560] 'process raft request' (duration: 137.410234ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:35.519131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.544455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-30T18:50:35.519183Z","caller":"traceutil/trace.go:171","msg":"trace[1334775052] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:24638; }","duration":"376.645497ms","start":"2026-06-30T18:50:35.142523Z","end":"2026-06-30T18:50:35.519169Z","steps":["trace[1334775052] 'agreement among raft nodes before linearized reading' (duration: 376.509714ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:35.51922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:35.142502Z","time spent":"376.707838ms","remote":"127.0.0.1:32968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":40,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-06-30T18:50:35.997993Z","caller":"traceutil/trace.go:171","msg":"trace[1802569160] transaction","detail":"{read_only:false; response_revision:24643; number_of_response:1; }","duration":"252.371693ms","start":"2026-06-30T18:50:35.745598Z","end":"2026-06-30T18:50:35.99797Z","steps":["trace[1802569160] 'process raft request' (duration: 252.326632ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:35.998057Z","caller":"traceutil/trace.go:171","msg":"trace[1549593777] transaction","detail":"{read_only:false; response_revision:24640; number_of_response:1; }","duration":"478.57299ms","start":"2026-06-30T18:50:35.519444Z","end":"2026-06-30T18:50:35.998017Z","steps":["trace[1549593777] 'process raft request' (duration: 456.233736ms)","trace[1549593777] 'compare' (duration: 19.967558ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:35.998231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:35.519428Z","time spent":"478.721432ms","remote":"127.0.0.1:60964","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-06-30T18:50:35.998476Z","caller":"traceutil/trace.go:171","msg":"trace[1684214690] transaction","detail":"{read_only:false; response_revision:24641; number_of_response:1; }","duration":"478.845584ms","start":"2026-06-30T18:50:35.519616Z","end":"2026-06-30T18:50:35.998462Z","steps":["trace[1684214690] 'process raft request' (duration: 478.15598ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:35.998656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:35.519592Z","time spent":"478.954146ms","remote":"127.0.0.1:60964","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-06-30T18:50:35.998828Z","caller":"traceutil/trace.go:171","msg":"trace[1050099281] linearizableReadLoop","detail":"{readStateIndex:25445; appliedIndex:25442; }","duration":"451.839152ms","start":"2026-06-30T18:50:35.546972Z","end":"2026-06-30T18:50:35.998811Z","steps":["trace[1050099281] 'read index received' (duration: 428.618093ms)","trace[1050099281] 'applied index is now lower than readState.Index' (duration: 23.219529ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:35.998991Z","caller":"traceutil/trace.go:171","msg":"trace[1019230017] transaction","detail":"{read_only:false; response_revision:24642; number_of_response:1; }","duration":"479.119779ms","start":"2026-06-30T18:50:35.519859Z","end":"2026-06-30T18:50:35.998979Z","steps":["trace[1019230017] 'process raft request' (duration: 478.005748ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:35.999071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:35.519843Z","time spent":"479.18114ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:50:35.999233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.03511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-30T18:50:35.999287Z","caller":"traceutil/trace.go:171","msg":"trace[888958182] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:24643; }","duration":"476.104393ms","start":"2026-06-30T18:50:35.52317Z","end":"2026-06-30T18:50:35.999274Z","steps":["trace[888958182] 'agreement among raft nodes before linearized reading' (duration: 476.009571ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:35.999331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:35.523163Z","time spent":"476.156524ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-30T18:50:35.999631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.148698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-06-30T18:50:35.999676Z","caller":"traceutil/trace.go:171","msg":"trace[580612665] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24643; }","duration":"205.22347ms","start":"2026-06-30T18:50:35.794441Z","end":"2026-06-30T18:50:35.999665Z","steps":["trace[580612665] 'agreement among raft nodes before linearized reading' (duration: 205.085537ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:51.394921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.725073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:50:51.395036Z","caller":"traceutil/trace.go:171","msg":"trace[841889358] transaction","detail":"{read_only:false; response_revision:24809; number_of_response:1; }","duration":"272.082955ms","start":"2026-06-30T18:50:51.122932Z","end":"2026-06-30T18:50:51.395015Z","steps":["trace[841889358] 'process raft request' (duration: 128.171589ms)","trace[841889358] 'compare' (duration: 143.367936ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:51.718097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.010116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:50:51.718182Z","caller":"traceutil/trace.go:171","msg":"trace[83030801] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:24809; }","duration":"222.146429ms","start":"2026-06-30T18:50:51.496016Z","end":"2026-06-30T18:50:51.718162Z","steps":["trace[83030801] 'count revisions from in-memory index tree' (duration: 221.913425ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:51.718443Z","caller":"traceutil/trace.go:171","msg":"trace[1382545813] transaction","detail":"{read_only:false; response_revision:24810; number_of_response:1; }","duration":"141.610443ms","start":"2026-06-30T18:50:51.576788Z","end":"2026-06-30T18:50:51.718399Z","steps":["trace[1382545813] 'process raft request' (duration: 87.702982ms)","trace[1382545813] 'compare' (duration: 53.669947ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:51.806531Z","caller":"traceutil/trace.go:171","msg":"trace[1957357839] transaction","detail":"{read_only:false; response_revision:24812; number_of_response:1; }","duration":"209.462369ms","start":"2026-06-30T18:50:51.597039Z","end":"2026-06-30T18:50:51.806501Z","steps":["trace[1957357839] 'process raft request' (duration: 209.342506ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:51.806566Z","caller":"traceutil/trace.go:171","msg":"trace[1049627762] transaction","detail":"{read_only:false; response_revision:24811; number_of_response:1; }","duration":"209.878387ms","start":"2026-06-30T18:50:51.596653Z","end":"2026-06-30T18:50:51.806532Z","steps":["trace[1049627762] 'process raft request' (duration: 209.567572ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:51.80684Z","caller":"traceutil/trace.go:171","msg":"trace[1881032114] linearizableReadLoop","detail":"{readStateIndex:25619; appliedIndex:25615; }","duration":"182.090262ms","start":"2026-06-30T18:50:51.624733Z","end":"2026-06-30T18:50:51.806824Z","steps":["trace[1881032114] 'read index received' (duration: 39.763464ms)","trace[1881032114] 'applied index is now lower than readState.Index' (duration: 142.324078ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:51.807059Z","caller":"traceutil/trace.go:171","msg":"trace[1780705629] transaction","detail":"{read_only:false; response_revision:24813; number_of_response:1; }","duration":"209.869357ms","start":"2026-06-30T18:50:51.597161Z","end":"2026-06-30T18:50:51.80703Z","steps":["trace[1780705629] 'process raft request' (duration: 209.286616ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:51.807431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.672262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-30T18:50:51.807582Z","caller":"traceutil/trace.go:171","msg":"trace[367254244] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24813; }","duration":"182.793485ms","start":"2026-06-30T18:50:51.624694Z","end":"2026-06-30T18:50:51.807487Z","steps":["trace[367254244] 'agreement among raft nodes before linearized reading' (duration: 182.426758ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:52.015305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.247685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:50:52.015445Z","caller":"traceutil/trace.go:171","msg":"trace[1585784452] transaction","detail":"{read_only:false; response_revision:24815; number_of_response:1; }","duration":"197.791478ms","start":"2026-06-30T18:50:51.817641Z","end":"2026-06-30T18:50:52.015432Z","steps":["trace[1585784452] 'process raft request' (duration: 197.745757ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:52.015473Z","caller":"traceutil/trace.go:171","msg":"trace[786867721] transaction","detail":"{read_only:false; response_revision:24814; number_of_response:1; }","duration":"264.638645ms","start":"2026-06-30T18:50:51.750771Z","end":"2026-06-30T18:50:52.01541Z","steps":["trace[786867721] 'process raft request' (duration: 103.223127ms)","trace[786867721] 'compare' (duration: 161.141623ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:52.015636Z","caller":"traceutil/trace.go:171","msg":"trace[1571603733] linearizableReadLoop","detail":"{readStateIndex:25620; appliedIndex:25619; }","duration":"208.717395ms","start":"2026-06-30T18:50:51.806888Z","end":"2026-06-30T18:50:52.015605Z","steps":["trace[1571603733] 'read index received' (duration: 47.118212ms)","trace[1571603733] 'applied index is now lower than readState.Index' (duration: 161.45004ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:52.015783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.258786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-86b3815d-7daf-4b8b-b0f7-22a033cece7f\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-06-30T18:50:52.015843Z","caller":"traceutil/trace.go:171","msg":"trace[1585219025] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-86b3815d-7daf-4b8b-b0f7-22a033cece7f; range_end:; response_count:1; response_revision:24815; }","duration":"247.376188ms","start":"2026-06-30T18:50:51.768451Z","end":"2026-06-30T18:50:52.015827Z","steps":["trace[1585219025] 'agreement among raft nodes before linearized reading' (duration: 247.142543ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:52.381123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.99733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:50:52.38187Z","caller":"traceutil/trace.go:171","msg":"trace[1503499282] transaction","detail":"{read_only:false; response_revision:24816; number_of_response:1; }","duration":"357.690408ms","start":"2026-06-30T18:50:52.024132Z","end":"2026-06-30T18:50:52.381822Z","steps":["trace[1503499282] 'process raft request' (duration: 203.908164ms)","trace[1503499282] 'compare' (duration: 152.581151ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:52.381829Z","caller":"traceutil/trace.go:171","msg":"trace[1067727613] transaction","detail":"{read_only:false; response_revision:24817; number_of_response:1; }","duration":"330.673936ms","start":"2026-06-30T18:50:52.051137Z","end":"2026-06-30T18:50:52.381811Z","steps":["trace[1067727613] 'process raft request' (duration: 330.448732ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:52.381984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:52.051118Z","time spent":"330.823899ms","remote":"127.0.0.1:60964","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-06-30T18:50:52.382069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.710463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:50:52.382151Z","caller":"traceutil/trace.go:171","msg":"trace[136580954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24817; }","duration":"305.818955ms","start":"2026-06-30T18:50:52.076315Z","end":"2026-06-30T18:50:52.382133Z","steps":["trace[136580954] 'agreement among raft nodes before linearized reading' (duration: 305.678292ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:52.382216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:52.0763Z","time spent":"305.904886ms","remote":"127.0.0.1:60648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-30T18:50:52.382078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:52.024108Z","time spent":"357.83609ms","remote":"127.0.0.1:60882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3746,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:50:52.381697Z","caller":"traceutil/trace.go:171","msg":"trace[1084844155] linearizableReadLoop","detail":"{readStateIndex:25623; appliedIndex:25621; }","duration":"305.321955ms","start":"2026-06-30T18:50:52.076359Z","end":"2026-06-30T18:50:52.381681Z","steps":["trace[1084844155] 'read index received' (duration: 151.688724ms)","trace[1084844155] 'applied index is now lower than readState.Index' (duration: 153.632441ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:52.648385Z","caller":"traceutil/trace.go:171","msg":"trace[714040124] linearizableReadLoop","detail":"{readStateIndex:25625; appliedIndex:25623; }","duration":"265.817497ms","start":"2026-06-30T18:50:52.382537Z","end":"2026-06-30T18:50:52.648355Z","steps":["trace[714040124] 'read index received' (duration: 175.22165ms)","trace[714040124] 'applied index is now lower than readState.Index' (duration: 90.594567ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:52.648519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.884577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-30T18:50:52.648559Z","caller":"traceutil/trace.go:171","msg":"trace[238307993] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24819; }","duration":"305.974969ms","start":"2026-06-30T18:50:52.342573Z","end":"2026-06-30T18:50:52.648547Z","steps":["trace[238307993] 'agreement among raft nodes before linearized reading' (duration: 305.831206ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:52.648587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:52.34256Z","time spent":"306.020769ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-30T18:50:52.648633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.984779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-06-30T18:50:52.648686Z","caller":"traceutil/trace.go:171","msg":"trace[708925236] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:24819; }","duration":"353.076141ms","start":"2026-06-30T18:50:52.29559Z","end":"2026-06-30T18:50:52.648666Z","steps":["trace[708925236] 'agreement among raft nodes before linearized reading' (duration: 352.928258ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:52.648732Z","caller":"traceutil/trace.go:171","msg":"trace[1566590079] transaction","detail":"{read_only:false; response_revision:24818; number_of_response:1; }","duration":"368.068215ms","start":"2026-06-30T18:50:52.280651Z","end":"2026-06-30T18:50:52.648719Z","steps":["trace[1566590079] 'process raft request' (duration: 277.120331ms)","trace[1566590079] 'compare' (duration: 90.2726ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:52.648748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:52.295563Z","time spent":"353.154682ms","remote":"127.0.0.1:60844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1886,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" "} {"level":"warn","ts":"2026-06-30T18:50:52.648808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:52.280635Z","time spent":"368.117905ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:50:52.648814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.928185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-30T18:50:52.64894Z","caller":"traceutil/trace.go:171","msg":"trace[754905901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24819; }","duration":"555.070068ms","start":"2026-06-30T18:50:52.093837Z","end":"2026-06-30T18:50:52.648907Z","steps":["trace[754905901] 'agreement among raft nodes before linearized reading' (duration: 554.776093ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:52.648968Z","caller":"traceutil/trace.go:171","msg":"trace[379753591] transaction","detail":"{read_only:false; response_revision:24819; number_of_response:1; }","duration":"310.324271ms","start":"2026-06-30T18:50:52.338628Z","end":"2026-06-30T18:50:52.648952Z","steps":["trace[379753591] 'process raft request' (duration: 309.610497ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:52.648983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:52.093826Z","time spent":"555.146709ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-30T18:50:52.649037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.560512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-30T18:50:52.649059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:52.338609Z","time spent":"310.396272ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:50:52.6491Z","caller":"traceutil/trace.go:171","msg":"trace[1212475596] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24819; }","duration":"265.654324ms","start":"2026-06-30T18:50:52.383426Z","end":"2026-06-30T18:50:52.649081Z","steps":["trace[1212475596] 'agreement among raft nodes before linearized reading' (duration: 265.549342ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:52.649107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.419765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-30T18:50:52.649136Z","caller":"traceutil/trace.go:171","msg":"trace[534346578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24819; }","duration":"565.489336ms","start":"2026-06-30T18:50:52.083638Z","end":"2026-06-30T18:50:52.649127Z","steps":["trace[534346578] 'agreement among raft nodes before linearized reading' (duration: 565.413205ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:52.649133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.2811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-06-30T18:50:52.649202Z","caller":"traceutil/trace.go:171","msg":"trace[1569286274] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24819; }","duration":"491.409592ms","start":"2026-06-30T18:50:52.157778Z","end":"2026-06-30T18:50:52.649187Z","steps":["trace[1569286274] 'agreement among raft nodes before linearized reading' (duration: 491.181028ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:52.649241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:52.157755Z","time spent":"491.478733ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-30T18:50:52.64913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.587489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-86b3815d-7daf-4b8b-b0f7-22a033cece7f\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-06-30T18:50:52.649341Z","caller":"traceutil/trace.go:171","msg":"trace[480556623] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-86b3815d-7daf-4b8b-b0f7-22a033cece7f; range_end:; response_count:1; response_revision:24819; }","duration":"525.853924ms","start":"2026-06-30T18:50:52.123469Z","end":"2026-06-30T18:50:52.649323Z","steps":["trace[480556623] 'agreement among raft nodes before linearized reading' (duration: 525.524268ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:52.649158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:52.083616Z","time spent":"565.536857ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-30T18:50:52.64941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:52.12344Z","time spent":"525.957836ms","remote":"127.0.0.1:60882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3786,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-86b3815d-7daf-4b8b-b0f7-22a033cece7f\" "} {"level":"info","ts":"2026-06-30T18:50:52.865498Z","caller":"traceutil/trace.go:171","msg":"trace[1782356091] transaction","detail":"{read_only:false; response_revision:24821; number_of_response:1; }","duration":"212.322524ms","start":"2026-06-30T18:50:52.653143Z","end":"2026-06-30T18:50:52.865466Z","steps":["trace[1782356091] 'process raft request' (duration: 142.060562ms)","trace[1782356091] 'compare' (duration: 70.045487ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:52.865562Z","caller":"traceutil/trace.go:171","msg":"trace[2071688206] transaction","detail":"{read_only:false; response_revision:24823; number_of_response:1; }","duration":"211.020729ms","start":"2026-06-30T18:50:52.654511Z","end":"2026-06-30T18:50:52.865531Z","steps":["trace[2071688206] 'process raft request' (duration: 210.940498ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:52.86565Z","caller":"traceutil/trace.go:171","msg":"trace[2046504878] transaction","detail":"{read_only:false; response_revision:24822; number_of_response:1; }","duration":"211.920806ms","start":"2026-06-30T18:50:52.653711Z","end":"2026-06-30T18:50:52.865632Z","steps":["trace[2046504878] 'process raft request' (duration: 211.681182ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:53.765899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.247995ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-30T18:50:53.766014Z","caller":"traceutil/trace.go:171","msg":"trace[1776427420] linearizableReadLoop","detail":"{readStateIndex:25631; appliedIndex:25630; }","duration":"186.148248ms","start":"2026-06-30T18:50:53.579847Z","end":"2026-06-30T18:50:53.765995Z","steps":["trace[1776427420] 'read index received' (duration: 47.691µs)","trace[1776427420] 'applied index is now lower than readState.Index' (duration: 186.099157ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:53.766147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.28111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:50:53.76623Z","caller":"traceutil/trace.go:171","msg":"trace[1642034879] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24824; }","duration":"186.403473ms","start":"2026-06-30T18:50:53.579808Z","end":"2026-06-30T18:50:53.766212Z","steps":["trace[1642034879] 'agreement among raft nodes before linearized reading' (duration: 186.2712ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:54.011921Z","caller":"traceutil/trace.go:171","msg":"trace[1366066000] linearizableReadLoop","detail":"{readStateIndex:25632; appliedIndex:25631; }","duration":"245.854528ms","start":"2026-06-30T18:50:53.766046Z","end":"2026-06-30T18:50:54.0119Z","steps":["trace[1366066000] 'read index received' (duration: 245.717635ms)","trace[1366066000] 'applied index is now lower than readState.Index' (duration: 136.253µs)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:54.012003Z","caller":"traceutil/trace.go:171","msg":"trace[1127375030] transaction","detail":"{read_only:false; response_revision:24825; number_of_response:1; }","duration":"287.943016ms","start":"2026-06-30T18:50:53.724045Z","end":"2026-06-30T18:50:54.011988Z","steps":["trace[1127375030] 'process raft request' (duration: 287.735562ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:54.012101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.73575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-86b3815d-7daf-4b8b-b0f7-22a033cece7f\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-06-30T18:50:54.012127Z","caller":"traceutil/trace.go:171","msg":"trace[1727917021] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-86b3815d-7daf-4b8b-b0f7-22a033cece7f; range_end:; response_count:1; response_revision:24825; }","duration":"356.806752ms","start":"2026-06-30T18:50:53.655312Z","end":"2026-06-30T18:50:54.012119Z","steps":["trace[1727917021] 'agreement among raft nodes before linearized reading' (duration: 356.679578ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:54.012151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:53.655296Z","time spent":"356.848092ms","remote":"127.0.0.1:60882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3786,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-86b3815d-7daf-4b8b-b0f7-22a033cece7f\" "} {"level":"warn","ts":"2026-06-30T18:50:54.353487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.33713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:50:54.35369Z","caller":"traceutil/trace.go:171","msg":"trace[444905542] transaction","detail":"{read_only:false; response_revision:24826; number_of_response:1; }","duration":"538.100227ms","start":"2026-06-30T18:50:53.81557Z","end":"2026-06-30T18:50:54.35367Z","steps":["trace[444905542] 'process raft request' (duration: 331.500512ms)","trace[444905542] 'compare' (duration: 206.090415ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:54.353754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:53.815543Z","time spent":"538.179499ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:50:54.353933Z","caller":"traceutil/trace.go:171","msg":"trace[209339720] transaction","detail":"{read_only:false; response_revision:24827; number_of_response:1; }","duration":"537.78955ms","start":"2026-06-30T18:50:53.816116Z","end":"2026-06-30T18:50:54.353906Z","steps":["trace[209339720] 'process raft request' (duration: 537.478124ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:54.35405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:53.816077Z","time spent":"537.905383ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:50:54.354247Z","caller":"traceutil/trace.go:171","msg":"trace[2056561081] transaction","detail":"{read_only:false; response_revision:24828; number_of_response:1; }","duration":"532.219145ms","start":"2026-06-30T18:50:53.821993Z","end":"2026-06-30T18:50:54.354212Z","steps":["trace[2056561081] 'process raft request' (duration: 531.827678ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:54.35436Z","caller":"traceutil/trace.go:171","msg":"trace[1807104529] linearizableReadLoop","detail":"{readStateIndex:25635; appliedIndex:25632; }","duration":"342.358208ms","start":"2026-06-30T18:50:54.01197Z","end":"2026-06-30T18:50:54.354328Z","steps":["trace[1807104529] 'read index received' (duration: 135.128541ms)","trace[1807104529] 'applied index is now lower than readState.Index' (duration: 207.227677ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:54.354377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:53.821984Z","time spent":"532.320077ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:50:54.354349Z","caller":"traceutil/trace.go:171","msg":"trace[1569458553] transaction","detail":"{read_only:false; response_revision:24829; number_of_response:1; }","duration":"134.652881ms","start":"2026-06-30T18:50:54.219666Z","end":"2026-06-30T18:50:54.354319Z","steps":["trace[1569458553] 'process raft request' (duration: 134.512698ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:54.35446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.105165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:50:54.354535Z","caller":"traceutil/trace.go:171","msg":"trace[2109313870] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:24829; }","duration":"473.229808ms","start":"2026-06-30T18:50:53.881285Z","end":"2026-06-30T18:50:54.354515Z","steps":["trace[2109313870] 'agreement among raft nodes before linearized reading' (duration: 473.106335ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:54.354555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.399755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-06-30T18:50:54.354543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.770272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-06-30T18:50:54.354597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:53.881265Z","time spent":"473.302938ms","remote":"127.0.0.1:36418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true "} {"level":"info","ts":"2026-06-30T18:50:54.354615Z","caller":"traceutil/trace.go:171","msg":"trace[519970969] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24829; }","duration":"336.492796ms","start":"2026-06-30T18:50:54.018108Z","end":"2026-06-30T18:50:54.354601Z","steps":["trace[519970969] 'agreement among raft nodes before linearized reading' (duration: 336.338753ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:54.354623Z","caller":"traceutil/trace.go:171","msg":"trace[1222539225] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24829; }","duration":"584.892373ms","start":"2026-06-30T18:50:53.769717Z","end":"2026-06-30T18:50:54.35461Z","steps":["trace[1222539225] 'agreement among raft nodes before linearized reading' (duration: 584.71335ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:54.35462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.159957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-30T18:50:54.354648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:54.018094Z","time spent":"336.546287ms","remote":"127.0.0.1:60964","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-06-30T18:50:54.354657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:53.769692Z","time spent":"584.957935ms","remote":"127.0.0.1:60882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-30T18:50:54.354672Z","caller":"traceutil/trace.go:171","msg":"trace[1373076101] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24829; }","duration":"100.29824ms","start":"2026-06-30T18:50:54.254359Z","end":"2026-06-30T18:50:54.354657Z","steps":["trace[1373076101] 'agreement among raft nodes before linearized reading' (duration: 100.170968ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:54.354808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.966106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:50:54.354834Z","caller":"traceutil/trace.go:171","msg":"trace[1129161117] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24829; }","duration":"278.991166ms","start":"2026-06-30T18:50:54.075834Z","end":"2026-06-30T18:50:54.354825Z","steps":["trace[1129161117] 'agreement among raft nodes before linearized reading' (duration: 278.951645ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:54.633251Z","caller":"traceutil/trace.go:171","msg":"trace[1799955923] linearizableReadLoop","detail":"{readStateIndex:25637; appliedIndex:25636; }","duration":"270.001117ms","start":"2026-06-30T18:50:54.363227Z","end":"2026-06-30T18:50:54.633228Z","steps":["trace[1799955923] 'read index received' (duration: 256.982521ms)","trace[1799955923] 'applied index is now lower than readState.Index' (duration: 13.017556ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:50:54.633511Z","caller":"traceutil/trace.go:171","msg":"trace[1616737116] transaction","detail":"{read_only:false; response_revision:24830; number_of_response:1; }","duration":"273.995612ms","start":"2026-06-30T18:50:54.359498Z","end":"2026-06-30T18:50:54.633494Z","steps":["trace[1616737116] 'process raft request' (duration: 260.594378ms)","trace[1616737116] 'compare' (duration: 12.922535ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:54.633636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.395194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:50:54.633695Z","caller":"traceutil/trace.go:171","msg":"trace[1425972794] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24830; }","duration":"270.537377ms","start":"2026-06-30T18:50:54.363144Z","end":"2026-06-30T18:50:54.633682Z","steps":["trace[1425972794] 'agreement among raft nodes before linearized reading' (duration: 270.407424ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:54.638604Z","caller":"traceutil/trace.go:171","msg":"trace[985432117] transaction","detail":"{read_only:false; response_revision:24831; number_of_response:1; }","duration":"252.000946ms","start":"2026-06-30T18:50:54.386587Z","end":"2026-06-30T18:50:54.638587Z","steps":["trace[985432117] 'process raft request' (duration: 251.767961ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:54.63884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.546844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-06-30T18:50:54.638916Z","caller":"traceutil/trace.go:171","msg":"trace[860361089] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24832; }","duration":"272.659017ms","start":"2026-06-30T18:50:54.366239Z","end":"2026-06-30T18:50:54.638898Z","steps":["trace[860361089] 'agreement among raft nodes before linearized reading' (duration: 272.331701ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:54.639146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.273101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-30T18:50:54.639184Z","caller":"traceutil/trace.go:171","msg":"trace[602443102] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:24832; }","duration":"204.353052ms","start":"2026-06-30T18:50:54.43482Z","end":"2026-06-30T18:50:54.639173Z","steps":["trace[602443102] 'agreement among raft nodes before linearized reading' (duration: 204.157679ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:54.931576Z","caller":"traceutil/trace.go:171","msg":"trace[200214331] linearizableReadLoop","detail":"{readStateIndex:25641; appliedIndex:25639; }","duration":"273.469052ms","start":"2026-06-30T18:50:54.658086Z","end":"2026-06-30T18:50:54.931555Z","steps":["trace[200214331] 'read index received' (duration: 52.597498ms)","trace[200214331] 'applied index is now lower than readState.Index' (duration: 220.870564ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:54.931887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.779988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:50:54.931972Z","caller":"traceutil/trace.go:171","msg":"trace[200822279] transaction","detail":"{read_only:false; response_revision:24834; number_of_response:1; }","duration":"277.338416ms","start":"2026-06-30T18:50:54.65462Z","end":"2026-06-30T18:50:54.931959Z","steps":["trace[200822279] 'process raft request' (duration: 275.403489ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:54.931989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.181953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-30T18:50:54.932007Z","caller":"traceutil/trace.go:171","msg":"trace[1203849980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24834; }","duration":"273.928721ms","start":"2026-06-30T18:50:54.658059Z","end":"2026-06-30T18:50:54.931988Z","steps":["trace[1203849980] 'agreement among raft nodes before linearized reading' (duration: 273.652495ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:54.932058Z","caller":"traceutil/trace.go:171","msg":"trace[1841325567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24834; }","duration":"268.278804ms","start":"2026-06-30T18:50:54.663762Z","end":"2026-06-30T18:50:54.932041Z","steps":["trace[1841325567] 'agreement among raft nodes before linearized reading' (duration: 268.008838ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:55.509818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.20006ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:50:55.509946Z","caller":"traceutil/trace.go:171","msg":"trace[552070619] transaction","detail":"{read_only:false; response_revision:24835; number_of_response:1; }","duration":"573.442756ms","start":"2026-06-30T18:50:54.93648Z","end":"2026-06-30T18:50:55.509923Z","steps":["trace[552070619] 'process raft request' (duration: 332.035022ms)","trace[552070619] 'compare' (duration: 241.064948ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:55.510031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:54.936467Z","time spent":"573.523817ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:50:55.513181Z","caller":"traceutil/trace.go:171","msg":"trace[2108921142] transaction","detail":"{read_only:false; response_revision:24836; number_of_response:1; }","duration":"576.576276ms","start":"2026-06-30T18:50:54.936568Z","end":"2026-06-30T18:50:55.513145Z","steps":["trace[2108921142] 'process raft request' (duration: 576.251189ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:50:55.513181Z","caller":"traceutil/trace.go:171","msg":"trace[704257175] linearizableReadLoop","detail":"{readStateIndex:25645; appliedIndex:25641; }","duration":"575.17685ms","start":"2026-06-30T18:50:54.93798Z","end":"2026-06-30T18:50:55.513157Z","steps":["trace[704257175] 'read index received' (duration: 330.555424ms)","trace[704257175] 'applied index is now lower than readState.Index' (duration: 244.620336ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:50:55.513349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:54.936353Z","time spent":"576.908832ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:50:55.51338Z","caller":"traceutil/trace.go:171","msg":"trace[286594432] transaction","detail":"{read_only:false; response_revision:24837; number_of_response:1; }","duration":"576.769369ms","start":"2026-06-30T18:50:54.936571Z","end":"2026-06-30T18:50:55.51334Z","steps":["trace[286594432] 'process raft request' (duration: 576.432343ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:55.513445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.454705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:50:55.513491Z","caller":"traceutil/trace.go:171","msg":"trace[1682756750] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:24838; }","duration":"575.514146ms","start":"2026-06-30T18:50:54.937963Z","end":"2026-06-30T18:50:55.513477Z","steps":["trace[1682756750] 'agreement among raft nodes before linearized reading' (duration: 575.419514ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:55.51352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:54.937954Z","time spent":"575.559086ms","remote":"127.0.0.1:57408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true "} {"level":"warn","ts":"2026-06-30T18:50:55.513516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:54.936555Z","time spent":"576.856051ms","remote":"127.0.0.1:60964","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-06-30T18:50:55.513564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.230892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-86b3815d-7daf-4b8b-b0f7-22a033cece7f\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-06-30T18:50:55.51364Z","caller":"traceutil/trace.go:171","msg":"trace[427719523] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-86b3815d-7daf-4b8b-b0f7-22a033cece7f; range_end:; response_count:1; response_revision:24838; }","duration":"498.343083ms","start":"2026-06-30T18:50:55.015275Z","end":"2026-06-30T18:50:55.513618Z","steps":["trace[427719523] 'agreement among raft nodes before linearized reading' (duration: 498.15287ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:55.513691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:55.015261Z","time spent":"498.420555ms","remote":"127.0.0.1:60882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3786,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-86b3815d-7daf-4b8b-b0f7-22a033cece7f\" "} {"level":"info","ts":"2026-06-30T18:50:55.513707Z","caller":"traceutil/trace.go:171","msg":"trace[1916710887] transaction","detail":"{read_only:false; response_revision:24838; number_of_response:1; }","duration":"576.529366ms","start":"2026-06-30T18:50:54.937165Z","end":"2026-06-30T18:50:55.513695Z","steps":["trace[1916710887] 'process raft request' (duration: 575.917034ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:55.513794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:54.937146Z","time spent":"576.611327ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:50:55.513877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.88855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:50:55.513938Z","caller":"traceutil/trace.go:171","msg":"trace[478221563] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24838; }","duration":"435.970281ms","start":"2026-06-30T18:50:55.077953Z","end":"2026-06-30T18:50:55.513923Z","steps":["trace[478221563] 'agreement among raft nodes before linearized reading' (duration: 435.864339ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:55.513978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:55.077945Z","time spent":"436.025372ms","remote":"127.0.0.1:60656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-30T18:50:55.51396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.234135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-30T18:50:55.514032Z","caller":"traceutil/trace.go:171","msg":"trace[1744096876] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:24838; }","duration":"437.349697ms","start":"2026-06-30T18:50:55.076664Z","end":"2026-06-30T18:50:55.514014Z","steps":["trace[1744096876] 'agreement among raft nodes before linearized reading' (duration: 437.198255ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:50:55.514087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:50:55.076646Z","time spent":"437.430699ms","remote":"127.0.0.1:60838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":16,"response size":30,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-06-30T18:51:00.668686Z","caller":"traceutil/trace.go:171","msg":"trace[263261822] transaction","detail":"{read_only:false; response_revision:24889; number_of_response:1; }","duration":"296.118652ms","start":"2026-06-30T18:51:00.372534Z","end":"2026-06-30T18:51:00.668653Z","steps":["trace[263261822] 'process raft request' (duration: 295.948119ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:00.762568Z","caller":"traceutil/trace.go:171","msg":"trace[2042358700] transaction","detail":"{read_only:false; response_revision:24890; number_of_response:1; }","duration":"387.725997ms","start":"2026-06-30T18:51:00.374813Z","end":"2026-06-30T18:51:00.762539Z","steps":["trace[2042358700] 'process raft request' (duration: 386.209988ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:00.762823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:00.374789Z","time spent":"387.919361ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:00.763014Z","caller":"traceutil/trace.go:171","msg":"trace[194301663] transaction","detail":"{read_only:false; response_revision:24891; number_of_response:1; }","duration":"387.641655ms","start":"2026-06-30T18:51:00.375345Z","end":"2026-06-30T18:51:00.762987Z","steps":["trace[194301663] 'process raft request' (duration: 387.141416ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:00.763178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:00.375333Z","time spent":"387.769478ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:11.737592Z","caller":"traceutil/trace.go:171","msg":"trace[827311555] transaction","detail":"{read_only:false; response_revision:24983; number_of_response:1; }","duration":"118.396333ms","start":"2026-06-30T18:51:11.619167Z","end":"2026-06-30T18:51:11.737563Z","steps":["trace[827311555] 'process raft request' (duration: 118.23255ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:11.872075Z","caller":"traceutil/trace.go:171","msg":"trace[559113396] linearizableReadLoop","detail":"{readStateIndex:25795; appliedIndex:25793; }","duration":"251.485845ms","start":"2026-06-30T18:51:11.620564Z","end":"2026-06-30T18:51:11.87205Z","steps":["trace[559113396] 'read index received' (duration: 116.902374ms)","trace[559113396] 'applied index is now lower than readState.Index' (duration: 134.582081ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:11.872217Z","caller":"traceutil/trace.go:171","msg":"trace[447559141] transaction","detail":"{read_only:false; response_revision:24984; number_of_response:1; }","duration":"252.420303ms","start":"2026-06-30T18:51:11.619784Z","end":"2026-06-30T18:51:11.872204Z","steps":["trace[447559141] 'process raft request' (duration: 249.195082ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:11.872457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.16655ms","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":"warn","ts":"2026-06-30T18:51:11.87246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.893524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-30T18:51:11.8725Z","caller":"traceutil/trace.go:171","msg":"trace[443880282] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24984; }","duration":"251.242202ms","start":"2026-06-30T18:51:11.621245Z","end":"2026-06-30T18:51:11.872487Z","steps":["trace[443880282] 'agreement among raft nodes before linearized reading' (duration: 251.110759ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:11.872471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.346544ms","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-06-30T18:51:11.872531Z","caller":"traceutil/trace.go:171","msg":"trace[319984301] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24984; }","duration":"252.019516ms","start":"2026-06-30T18:51:11.620488Z","end":"2026-06-30T18:51:11.872508Z","steps":["trace[319984301] 'agreement among raft nodes before linearized reading' (duration: 251.827793ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:11.872567Z","caller":"traceutil/trace.go:171","msg":"trace[318755977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24984; }","duration":"251.474056ms","start":"2026-06-30T18:51:11.621069Z","end":"2026-06-30T18:51:11.872543Z","steps":["trace[318755977] 'agreement among raft nodes before linearized reading' (duration: 251.199901ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:12.165823Z","caller":"traceutil/trace.go:171","msg":"trace[1355669920] transaction","detail":"{read_only:false; response_revision:24985; number_of_response:1; }","duration":"289.144179ms","start":"2026-06-30T18:51:11.876637Z","end":"2026-06-30T18:51:12.165781Z","steps":["trace[1355669920] 'process raft request' (duration: 288.935085ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:12.426875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.40759ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:51:12.427166Z","caller":"traceutil/trace.go:171","msg":"trace[583825585] linearizableReadLoop","detail":"{readStateIndex:25798; appliedIndex:25795; }","duration":"345.597099ms","start":"2026-06-30T18:51:12.081551Z","end":"2026-06-30T18:51:12.427148Z","steps":["trace[583825585] 'read index received' (duration: 84.017522ms)","trace[583825585] 'applied index is now lower than readState.Index' (duration: 261.578297ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:12.427266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.722571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:51:12.4273Z","caller":"traceutil/trace.go:171","msg":"trace[609390237] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24988; }","duration":"345.783883ms","start":"2026-06-30T18:51:12.081504Z","end":"2026-06-30T18:51:12.427288Z","steps":["trace[609390237] 'agreement among raft nodes before linearized reading' (duration: 345.699101ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:12.427338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:12.081483Z","time spent":"345.847803ms","remote":"127.0.0.1:60648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-30T18:51:12.427313Z","caller":"traceutil/trace.go:171","msg":"trace[1879575630] transaction","detail":"{read_only:false; response_revision:24987; number_of_response:1; }","duration":"545.553748ms","start":"2026-06-30T18:51:11.881727Z","end":"2026-06-30T18:51:12.427281Z","steps":["trace[1879575630] 'process raft request' (duration: 545.244873ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:12.427341Z","caller":"traceutil/trace.go:171","msg":"trace[197611531] transaction","detail":"{read_only:false; response_revision:24986; number_of_response:1; }","duration":"550.217197ms","start":"2026-06-30T18:51:11.877072Z","end":"2026-06-30T18:51:12.427289Z","steps":["trace[197611531] 'process raft request' (duration: 418.304807ms)","trace[197611531] 'compare' (duration: 130.774798ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:12.427369Z","caller":"traceutil/trace.go:171","msg":"trace[1742212580] transaction","detail":"{read_only:false; response_revision:24988; number_of_response:1; }","duration":"336.410335ms","start":"2026-06-30T18:51:12.090888Z","end":"2026-06-30T18:51:12.427298Z","steps":["trace[1742212580] 'process raft request' (duration: 336.17369ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:12.4275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:11.881719Z","time spent":"545.692961ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:51:12.427564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:11.877062Z","time spent":"550.386879ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:51:12.427587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:12.090869Z","time spent":"336.626478ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:12.88105Z","caller":"traceutil/trace.go:171","msg":"trace[1334471360] transaction","detail":"{read_only:false; response_revision:24989; number_of_response:1; }","duration":"144.391706ms","start":"2026-06-30T18:51:12.73663Z","end":"2026-06-30T18:51:12.881022Z","steps":["trace[1334471360] 'process raft request' (duration: 144.213742ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:13.141553Z","caller":"traceutil/trace.go:171","msg":"trace[1338730731] transaction","detail":"{read_only:false; response_revision:24990; number_of_response:1; }","duration":"340.638095ms","start":"2026-06-30T18:51:12.800897Z","end":"2026-06-30T18:51:13.141535Z","steps":["trace[1338730731] 'process raft request' (duration: 340.502342ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:13.14168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:12.800874Z","time spent":"340.732026ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:13.141773Z","caller":"traceutil/trace.go:171","msg":"trace[193761642] transaction","detail":"{read_only:false; response_revision:24991; number_of_response:1; }","duration":"333.580941ms","start":"2026-06-30T18:51:12.808186Z","end":"2026-06-30T18:51:13.141767Z","steps":["trace[193761642] 'process raft request' (duration: 333.320246ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:13.141812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:12.808177Z","time spent":"333.615711ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:13.141847Z","caller":"traceutil/trace.go:171","msg":"trace[1896726627] transaction","detail":"{read_only:false; response_revision:24992; number_of_response:1; }","duration":"325.315984ms","start":"2026-06-30T18:51:12.816517Z","end":"2026-06-30T18:51:13.141833Z","steps":["trace[1896726627] 'process raft request' (duration: 325.138181ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:13.141913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:12.81648Z","time spent":"325.403596ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:13.141907Z","caller":"traceutil/trace.go:171","msg":"trace[2134383375] transaction","detail":"{read_only:false; response_revision:24993; number_of_response:1; }","duration":"322.858407ms","start":"2026-06-30T18:51:12.819019Z","end":"2026-06-30T18:51:13.141878Z","steps":["trace[2134383375] 'process raft request' (duration: 322.743235ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:13.14183Z","caller":"traceutil/trace.go:171","msg":"trace[1331949724] linearizableReadLoop","detail":"{readStateIndex:25804; appliedIndex:25800; }","duration":"246.764956ms","start":"2026-06-30T18:51:12.895043Z","end":"2026-06-30T18:51:13.141808Z","steps":["trace[1331949724] 'read index received' (duration: 246.44592ms)","trace[1331949724] 'applied index is now lower than readState.Index' (duration: 318.076µs)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:13.142007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.944269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-30T18:51:13.142057Z","caller":"traceutil/trace.go:171","msg":"trace[853771982] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24993; }","duration":"247.008421ms","start":"2026-06-30T18:51:12.895037Z","end":"2026-06-30T18:51:13.142045Z","steps":["trace[853771982] 'agreement among raft nodes before linearized reading' (duration: 246.839737ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:13.142322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:12.818999Z","time spent":"322.97709ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:15.40604Z","caller":"traceutil/trace.go:171","msg":"trace[1291378825] transaction","detail":"{read_only:false; response_revision:25007; number_of_response:1; }","duration":"258.425196ms","start":"2026-06-30T18:51:15.147589Z","end":"2026-06-30T18:51:15.406014Z","steps":["trace[1291378825] 'process raft request' (duration: 258.229023ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:15.406314Z","caller":"traceutil/trace.go:171","msg":"trace[485779879] transaction","detail":"{read_only:false; response_revision:25008; number_of_response:1; }","duration":"258.57963ms","start":"2026-06-30T18:51:15.147713Z","end":"2026-06-30T18:51:15.406293Z","steps":["trace[485779879] 'process raft request' (duration: 258.254913ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:15.406557Z","caller":"traceutil/trace.go:171","msg":"trace[1012741962] transaction","detail":"{read_only:false; response_revision:25009; number_of_response:1; }","duration":"258.457438ms","start":"2026-06-30T18:51:15.148064Z","end":"2026-06-30T18:51:15.406521Z","steps":["trace[1012741962] 'process raft request' (duration: 258.186603ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:15.406692Z","caller":"traceutil/trace.go:171","msg":"trace[1160197673] transaction","detail":"{read_only:false; response_revision:25010; number_of_response:1; }","duration":"237.760235ms","start":"2026-06-30T18:51:15.168907Z","end":"2026-06-30T18:51:15.406667Z","steps":["trace[1160197673] 'process raft request' (duration: 237.50911ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:15.406634Z","caller":"traceutil/trace.go:171","msg":"trace[1872244064] linearizableReadLoop","detail":"{readStateIndex:25821; appliedIndex:25818; }","duration":"251.100248ms","start":"2026-06-30T18:51:15.155519Z","end":"2026-06-30T18:51:15.406619Z","steps":["trace[1872244064] 'read index received' (duration: 51.833573ms)","trace[1872244064] 'applied index is now lower than readState.Index' (duration: 199.265725ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:15.40675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.19933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-30T18:51:15.40682Z","caller":"traceutil/trace.go:171","msg":"trace[1180671890] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:25010; }","duration":"251.314482ms","start":"2026-06-30T18:51:15.155492Z","end":"2026-06-30T18:51:15.406807Z","steps":["trace[1180671890] 'agreement among raft nodes before linearized reading' (duration: 251.170149ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:15.407049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.222152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-30T18:51:15.407145Z","caller":"traceutil/trace.go:171","msg":"trace[1898201216] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25010; }","duration":"220.353115ms","start":"2026-06-30T18:51:15.186764Z","end":"2026-06-30T18:51:15.407117Z","steps":["trace[1898201216] 'agreement among raft nodes before linearized reading' (duration: 220.092891ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:15.643631Z","caller":"traceutil/trace.go:171","msg":"trace[1674606292] transaction","detail":"{read_only:false; response_revision:25011; number_of_response:1; }","duration":"232.358534ms","start":"2026-06-30T18:51:15.411242Z","end":"2026-06-30T18:51:15.6436Z","steps":["trace[1674606292] 'process raft request' (duration: 142.206386ms)","trace[1674606292] 'compare' (duration: 88.886004ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:18.613035Z","caller":"traceutil/trace.go:171","msg":"trace[2064707711] transaction","detail":"{read_only:false; response_revision:25029; number_of_response:1; }","duration":"141.315378ms","start":"2026-06-30T18:51:18.4717Z","end":"2026-06-30T18:51:18.613015Z","steps":["trace[2064707711] 'process raft request' (duration: 141.226177ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:18.613202Z","caller":"traceutil/trace.go:171","msg":"trace[1077511526] linearizableReadLoop","detail":"{readStateIndex:25841; appliedIndex:25839; }","duration":"163.401996ms","start":"2026-06-30T18:51:18.449794Z","end":"2026-06-30T18:51:18.613196Z","steps":["trace[1077511526] 'read index received' (duration: 20.809264ms)","trace[1077511526] 'applied index is now lower than readState.Index' (duration: 142.591852ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:18.613448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.638011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-30T18:51:18.613471Z","caller":"traceutil/trace.go:171","msg":"trace[579428722] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25029; }","duration":"163.705592ms","start":"2026-06-30T18:51:18.44976Z","end":"2026-06-30T18:51:18.613465Z","steps":["trace[579428722] 'agreement among raft nodes before linearized reading' (duration: 163.60068ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:18.61347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.527869ms","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-06-30T18:51:18.613546Z","caller":"traceutil/trace.go:171","msg":"trace[806047003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25029; }","duration":"163.656452ms","start":"2026-06-30T18:51:18.449874Z","end":"2026-06-30T18:51:18.61353Z","steps":["trace[806047003] 'agreement among raft nodes before linearized reading' (duration: 163.398867ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:18.613682Z","caller":"traceutil/trace.go:171","msg":"trace[809701793] transaction","detail":"{read_only:false; response_revision:25028; number_of_response:1; }","duration":"165.409494ms","start":"2026-06-30T18:51:18.447542Z","end":"2026-06-30T18:51:18.612952Z","steps":["trace[809701793] 'process raft request' (duration: 135.319423ms)","trace[809701793] 'compare' (duration: 29.701784ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:18.807829Z","caller":"traceutil/trace.go:171","msg":"trace[51437145] transaction","detail":"{read_only:false; response_revision:25030; number_of_response:1; }","duration":"190.689423ms","start":"2026-06-30T18:51:18.617119Z","end":"2026-06-30T18:51:18.807809Z","steps":["trace[51437145] 'process raft request' (duration: 138.77603ms)","trace[51437145] 'compare' (duration: 51.75881ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:18.808219Z","caller":"traceutil/trace.go:171","msg":"trace[1621512566] transaction","detail":"{read_only:false; response_revision:25031; number_of_response:1; }","duration":"190.443928ms","start":"2026-06-30T18:51:18.617758Z","end":"2026-06-30T18:51:18.808202Z","steps":["trace[1621512566] 'process raft request' (duration: 190.0205ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:18.808223Z","caller":"traceutil/trace.go:171","msg":"trace[869495836] linearizableReadLoop","detail":"{readStateIndex:25844; appliedIndex:25842; }","duration":"190.253195ms","start":"2026-06-30T18:51:18.617949Z","end":"2026-06-30T18:51:18.808202Z","steps":["trace[869495836] 'read index received' (duration: 138.048727ms)","trace[869495836] 'applied index is now lower than readState.Index' (duration: 52.203668ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:18.808343Z","caller":"traceutil/trace.go:171","msg":"trace[821846279] transaction","detail":"{read_only:false; response_revision:25032; number_of_response:1; }","duration":"107.801273ms","start":"2026-06-30T18:51:18.700536Z","end":"2026-06-30T18:51:18.808338Z","steps":["trace[821846279] 'process raft request' (duration: 107.577459ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:18.808508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.540161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-30T18:51:18.808558Z","caller":"traceutil/trace.go:171","msg":"trace[242506950] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:25032; }","duration":"190.642393ms","start":"2026-06-30T18:51:18.617904Z","end":"2026-06-30T18:51:18.808547Z","steps":["trace[242506950] 'agreement among raft nodes before linearized reading' (duration: 190.366947ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:30.115657Z","caller":"traceutil/trace.go:171","msg":"trace[1929535503] transaction","detail":"{read_only:false; response_revision:25130; number_of_response:1; }","duration":"161.147863ms","start":"2026-06-30T18:51:29.954491Z","end":"2026-06-30T18:51:30.115639Z","steps":["trace[1929535503] 'process raft request' (duration: 160.99185ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:35.752254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.299076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:51:35.752314Z","caller":"traceutil/trace.go:171","msg":"trace[722981277] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25165; }","duration":"304.403777ms","start":"2026-06-30T18:51:35.447895Z","end":"2026-06-30T18:51:35.752299Z","steps":["trace[722981277] 'range keys from in-memory index tree' (duration: 304.207864ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:35.752364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:35.447872Z","time spent":"304.48496ms","remote":"127.0.0.1:32918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-06-30T18:51:35.752587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.673365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:51:35.752697Z","caller":"traceutil/trace.go:171","msg":"trace[1497868160] transaction","detail":"{read_only:false; response_revision:25166; number_of_response:1; }","duration":"276.921266ms","start":"2026-06-30T18:51:35.475755Z","end":"2026-06-30T18:51:35.752676Z","steps":["trace[1497868160] 'process raft request' (duration: 40.059178ms)","trace[1497868160] 'compare' (duration: 236.307488ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:36.019549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.763132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:51:36.019683Z","caller":"traceutil/trace.go:171","msg":"trace[236046930] linearizableReadLoop","detail":"{readStateIndex:25985; appliedIndex:25982; }","duration":"263.014674ms","start":"2026-06-30T18:51:35.756659Z","end":"2026-06-30T18:51:36.019674Z","steps":["trace[236046930] 'read index received' (duration: 145.08274ms)","trace[236046930] 'applied index is now lower than readState.Index' (duration: 117.931424ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:36.019742Z","caller":"traceutil/trace.go:171","msg":"trace[851807413] transaction","detail":"{read_only:false; response_revision:25167; number_of_response:1; }","duration":"529.962642ms","start":"2026-06-30T18:51:35.489774Z","end":"2026-06-30T18:51:36.019737Z","steps":["trace[851807413] 'process raft request' (duration: 411.960007ms)","trace[851807413] 'compare' (duration: 117.450475ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:36.019775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:35.489753Z","time spent":"530.005733ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:36.019874Z","caller":"traceutil/trace.go:171","msg":"trace[1336362956] transaction","detail":"{read_only:false; response_revision:25169; number_of_response:1; }","duration":"515.962446ms","start":"2026-06-30T18:51:35.503888Z","end":"2026-06-30T18:51:36.01985Z","steps":["trace[1336362956] 'process raft request' (duration: 515.760263ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:36.019976Z","caller":"traceutil/trace.go:171","msg":"trace[577646407] transaction","detail":"{read_only:false; response_revision:25168; number_of_response:1; }","duration":"523.782255ms","start":"2026-06-30T18:51:35.496173Z","end":"2026-06-30T18:51:36.019955Z","steps":["trace[577646407] 'process raft request' (duration: 523.443539ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:36.020012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.646206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-06-30T18:51:36.020006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:35.503874Z","time spent":"516.062789ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:36.020048Z","caller":"traceutil/trace.go:171","msg":"trace[1230593509] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25169; }","duration":"234.698707ms","start":"2026-06-30T18:51:35.785337Z","end":"2026-06-30T18:51:36.020036Z","steps":["trace[1230593509] 'agreement among raft nodes before linearized reading' (duration: 234.522013ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:36.019964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.277899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:51:36.02008Z","caller":"traceutil/trace.go:171","msg":"trace[773618184] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25169; }","duration":"263.448242ms","start":"2026-06-30T18:51:35.756624Z","end":"2026-06-30T18:51:36.020073Z","steps":["trace[773618184] 'agreement among raft nodes before linearized reading' (duration: 263.232848ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:36.020109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:35.49615Z","time spent":"523.892537ms","remote":"127.0.0.1:60964","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-06-30T18:51:36.553347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.754108ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:51:36.553652Z","caller":"traceutil/trace.go:171","msg":"trace[348214848] transaction","detail":"{read_only:false; response_revision:25170; number_of_response:1; }","duration":"530.525013ms","start":"2026-06-30T18:51:36.023091Z","end":"2026-06-30T18:51:36.553616Z","steps":["trace[348214848] 'process raft request' (duration: 304.375508ms)","trace[348214848] 'compare' (duration: 224.746208ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:36.553703Z","caller":"traceutil/trace.go:171","msg":"trace[905940158] linearizableReadLoop","detail":"{readStateIndex:25987; appliedIndex:25985; }","duration":"530.244428ms","start":"2026-06-30T18:51:36.023429Z","end":"2026-06-30T18:51:36.553674Z","steps":["trace[905940158] 'read index received' (duration: 304.062281ms)","trace[905940158] 'applied index is now lower than readState.Index' (duration: 226.180847ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:36.553842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:36.023071Z","time spent":"530.668566ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:36.553921Z","caller":"traceutil/trace.go:171","msg":"trace[1090013027] transaction","detail":"{read_only:false; response_revision:25171; number_of_response:1; }","duration":"530.556374ms","start":"2026-06-30T18:51:36.023352Z","end":"2026-06-30T18:51:36.553908Z","steps":["trace[1090013027] 'process raft request' (duration: 530.178136ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:36.553994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:36.023344Z","time spent":"530.608565ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:51:36.554017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.07036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:51:36.554055Z","caller":"traceutil/trace.go:171","msg":"trace[589187736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25171; }","duration":"478.142471ms","start":"2026-06-30T18:51:36.075901Z","end":"2026-06-30T18:51:36.554043Z","steps":["trace[589187736] 'agreement among raft nodes before linearized reading' (duration: 478.07712ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:36.554084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:36.075871Z","time spent":"478.208503ms","remote":"127.0.0.1:60648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-30T18:51:36.554232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.099037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-06-30T18:51:36.554276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.379908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:51:36.554295Z","caller":"traceutil/trace.go:171","msg":"trace[1338267446] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25171; }","duration":"281.221289ms","start":"2026-06-30T18:51:36.273055Z","end":"2026-06-30T18:51:36.554277Z","steps":["trace[1338267446] 'agreement among raft nodes before linearized reading' (duration: 281.035606ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:36.554312Z","caller":"traceutil/trace.go:171","msg":"trace[1556392225] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:25171; }","duration":"437.442479ms","start":"2026-06-30T18:51:36.116859Z","end":"2026-06-30T18:51:36.554301Z","steps":["trace[1556392225] 'agreement among raft nodes before linearized reading' (duration: 437.389398ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:36.554347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:36.116847Z","time spent":"437.49124ms","remote":"127.0.0.1:60936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-06-30T18:51:36.554349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.829457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-30T18:51:36.554419Z","caller":"traceutil/trace.go:171","msg":"trace[1860241422] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25171; }","duration":"418.910968ms","start":"2026-06-30T18:51:36.135491Z","end":"2026-06-30T18:51:36.554402Z","steps":["trace[1860241422] 'agreement among raft nodes before linearized reading' (duration: 418.671744ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:36.554458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:36.135426Z","time spent":"419.02397ms","remote":"127.0.0.1:60964","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-06-30T18:51:36.554525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.092794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-06-30T18:51:36.554546Z","caller":"traceutil/trace.go:171","msg":"trace[585031287] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25171; }","duration":"531.127745ms","start":"2026-06-30T18:51:36.023411Z","end":"2026-06-30T18:51:36.554539Z","steps":["trace[585031287] 'agreement among raft nodes before linearized reading' (duration: 531.048723ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:36.554566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:36.023404Z","time spent":"531.156705ms","remote":"127.0.0.1:60882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-30T18:51:36.554744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.983997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-06-30T18:51:36.554789Z","caller":"traceutil/trace.go:171","msg":"trace[367142182] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25171; }","duration":"411.057219ms","start":"2026-06-30T18:51:36.143718Z","end":"2026-06-30T18:51:36.554775Z","steps":["trace[367142182] 'agreement among raft nodes before linearized reading' (duration: 410.948697ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:36.554819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:36.143699Z","time spent":"411.11366ms","remote":"127.0.0.1:60964","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":"warn","ts":"2026-06-30T18:51:36.924995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.958986ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:51:36.925149Z","caller":"traceutil/trace.go:171","msg":"trace[2062543358] transaction","detail":"{read_only:false; response_revision:25172; number_of_response:1; }","duration":"365.934374ms","start":"2026-06-30T18:51:36.559182Z","end":"2026-06-30T18:51:36.925116Z","steps":["trace[2062543358] 'process raft request' (duration: 181.712603ms)","trace[2062543358] 'compare' (duration: 183.66079ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:36.925283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:36.559167Z","time spent":"366.058107ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:36.98622Z","caller":"traceutil/trace.go:171","msg":"trace[1496190434] transaction","detail":"{read_only:false; response_revision:25173; number_of_response:1; }","duration":"426.287627ms","start":"2026-06-30T18:51:36.559905Z","end":"2026-06-30T18:51:36.986193Z","steps":["trace[1496190434] 'process raft request' (duration: 426.104634ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:36.986389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:36.559893Z","time spent":"426.40732ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:36.986627Z","caller":"traceutil/trace.go:171","msg":"trace[1551247911] linearizableReadLoop","detail":"{readStateIndex:25990; appliedIndex:25987; }","duration":"419.470889ms","start":"2026-06-30T18:51:36.567136Z","end":"2026-06-30T18:51:36.986607Z","steps":["trace[1551247911] 'read index received' (duration: 173.771642ms)","trace[1551247911] 'applied index is now lower than readState.Index' (duration: 245.698517ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:36.98663Z","caller":"traceutil/trace.go:171","msg":"trace[1232813298] transaction","detail":"{read_only:false; response_revision:25174; number_of_response:1; }","duration":"426.596654ms","start":"2026-06-30T18:51:36.560002Z","end":"2026-06-30T18:51:36.986599Z","steps":["trace[1232813298] 'process raft request' (duration: 426.147424ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:36.986849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.305284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:217"} {"level":"info","ts":"2026-06-30T18:51:36.986882Z","caller":"traceutil/trace.go:171","msg":"trace[1826599497] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:25175; }","duration":"392.403506ms","start":"2026-06-30T18:51:36.59447Z","end":"2026-06-30T18:51:36.986874Z","steps":["trace[1826599497] 'agreement among raft nodes before linearized reading' (duration: 392.260573ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:36.986916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:36.594439Z","time spent":"392.464337ms","remote":"127.0.0.1:60908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"warn","ts":"2026-06-30T18:51:36.986817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.667363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"warn","ts":"2026-06-30T18:51:36.986943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:36.559996Z","time spent":"426.781548ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:36.987019Z","caller":"traceutil/trace.go:171","msg":"trace[1747060018] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25175; }","duration":"419.899907ms","start":"2026-06-30T18:51:36.567104Z","end":"2026-06-30T18:51:36.987004Z","steps":["trace[1747060018] 'agreement among raft nodes before linearized reading' (duration: 419.582131ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:36.987048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:36.56706Z","time spent":"419.980599ms","remote":"127.0.0.1:54752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5674,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-06-30T18:51:36.986691Z","caller":"traceutil/trace.go:171","msg":"trace[395527734] transaction","detail":"{read_only:false; response_revision:25175; number_of_response:1; }","duration":"282.466703ms","start":"2026-06-30T18:51:36.704189Z","end":"2026-06-30T18:51:36.986656Z","steps":["trace[395527734] 'process raft request' (duration: 282.2977ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:40.682539Z","caller":"traceutil/trace.go:171","msg":"trace[1754492724] linearizableReadLoop","detail":"{readStateIndex:26019; appliedIndex:26018; }","duration":"113.490061ms","start":"2026-06-30T18:51:40.569024Z","end":"2026-06-30T18:51:40.682514Z","steps":["trace[1754492724] 'read index received' (duration: 113.299977ms)","trace[1754492724] 'applied index is now lower than readState.Index' (duration: 189.244µs)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:40.682795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.750117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-30T18:51:40.682838Z","caller":"traceutil/trace.go:171","msg":"trace[2105010864] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25202; }","duration":"113.845469ms","start":"2026-06-30T18:51:40.56898Z","end":"2026-06-30T18:51:40.682825Z","steps":["trace[2105010864] 'agreement among raft nodes before linearized reading' (duration: 113.662264ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:40.682972Z","caller":"traceutil/trace.go:171","msg":"trace[1017625714] transaction","detail":"{read_only:false; response_revision:25202; number_of_response:1; }","duration":"113.948121ms","start":"2026-06-30T18:51:40.568995Z","end":"2026-06-30T18:51:40.682943Z","steps":["trace[1017625714] 'process raft request' (duration: 113.385109ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:46.244134Z","caller":"traceutil/trace.go:171","msg":"trace[1668031190] linearizableReadLoop","detail":"{readStateIndex:26061; appliedIndex:26059; }","duration":"149.412982ms","start":"2026-06-30T18:51:46.094694Z","end":"2026-06-30T18:51:46.244107Z","steps":["trace[1668031190] 'read index received' (duration: 149.171996ms)","trace[1668031190] 'applied index is now lower than readState.Index' (duration: 239.665µs)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:46.244332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.565984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:51:46.244374Z","caller":"traceutil/trace.go:171","msg":"trace[1230210768] transaction","detail":"{read_only:false; response_revision:25242; number_of_response:1; }","duration":"164.401686ms","start":"2026-06-30T18:51:46.079939Z","end":"2026-06-30T18:51:46.244341Z","steps":["trace[1230210768] 'process raft request' (duration: 163.917846ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:46.244429Z","caller":"traceutil/trace.go:171","msg":"trace[688025830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25243; }","duration":"149.758438ms","start":"2026-06-30T18:51:46.094655Z","end":"2026-06-30T18:51:46.244413Z","steps":["trace[688025830] 'agreement among raft nodes before linearized reading' (duration: 149.552784ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:46.244648Z","caller":"traceutil/trace.go:171","msg":"trace[870873660] transaction","detail":"{read_only:false; response_revision:25243; number_of_response:1; }","duration":"164.203891ms","start":"2026-06-30T18:51:46.080384Z","end":"2026-06-30T18:51:46.244588Z","steps":["trace[870873660] 'process raft request' (duration: 163.671062ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:46.871145Z","caller":"traceutil/trace.go:171","msg":"trace[118583407] transaction","detail":"{read_only:false; response_revision:25244; number_of_response:1; }","duration":"159.496383ms","start":"2026-06-30T18:51:46.711615Z","end":"2026-06-30T18:51:46.871111Z","steps":["trace[118583407] 'process raft request' (duration: 159.212298ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:47.013968Z","caller":"traceutil/trace.go:171","msg":"trace[1852037059] transaction","detail":"{read_only:false; response_revision:25245; number_of_response:1; }","duration":"137.303381ms","start":"2026-06-30T18:51:46.876635Z","end":"2026-06-30T18:51:47.013938Z","steps":["trace[1852037059] 'process raft request' (duration: 133.52593ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:47.279226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.106062ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:51:47.279341Z","caller":"traceutil/trace.go:171","msg":"trace[278095341] transaction","detail":"{read_only:false; response_revision:25246; number_of_response:1; }","duration":"261.039816ms","start":"2026-06-30T18:51:47.018279Z","end":"2026-06-30T18:51:47.279319Z","steps":["trace[278095341] 'process raft request' (duration: 127.74752ms)","trace[278095341] 'compare' (duration: 132.851848ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:47.369029Z","caller":"traceutil/trace.go:171","msg":"trace[160945843] transaction","detail":"{read_only:false; response_revision:25247; number_of_response:1; }","duration":"350.182976ms","start":"2026-06-30T18:51:47.01882Z","end":"2026-06-30T18:51:47.369003Z","steps":["trace[160945843] 'process raft request' (duration: 349.966752ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:47.36918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:47.018795Z","time spent":"350.304338ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-30T18:51:47.369347Z","caller":"traceutil/trace.go:171","msg":"trace[1463917780] transaction","detail":"{read_only:false; response_revision:25248; number_of_response:1; }","duration":"322.342048ms","start":"2026-06-30T18:51:47.046982Z","end":"2026-06-30T18:51:47.369324Z","steps":["trace[1463917780] 'process raft request' (duration: 321.969991ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:47.36929Z","caller":"traceutil/trace.go:171","msg":"trace[713358668] linearizableReadLoop","detail":"{readStateIndex:26065; appliedIndex:26063; }","duration":"340.116456ms","start":"2026-06-30T18:51:47.029125Z","end":"2026-06-30T18:51:47.369242Z","steps":["trace[713358668] 'read index received' (duration: 116.872515ms)","trace[713358668] 'applied index is now lower than readState.Index' (duration: 223.240501ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:47.36946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:47.046966Z","time spent":"322.4398ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-30T18:51:47.369584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.463652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-30T18:51:47.369632Z","caller":"traceutil/trace.go:171","msg":"trace[652382145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25248; }","duration":"340.511743ms","start":"2026-06-30T18:51:47.029108Z","end":"2026-06-30T18:51:47.36962Z","steps":["trace[652382145] 'agreement among raft nodes before linearized reading' (duration: 340.35708ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:47.369663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:47.029076Z","time spent":"340.579664ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-30T18:51:47.369835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.278844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-06-30T18:51:47.369867Z","caller":"traceutil/trace.go:171","msg":"trace[1463663527] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25248; }","duration":"249.344615ms","start":"2026-06-30T18:51:47.120513Z","end":"2026-06-30T18:51:47.369858Z","steps":["trace[1463663527] 'agreement among raft nodes before linearized reading' (duration: 249.227753ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:47.37003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.573059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-06-30T18:51:47.370086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.694347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-30T18:51:47.370207Z","caller":"traceutil/trace.go:171","msg":"trace[705155796] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25248; }","duration":"340.86876ms","start":"2026-06-30T18:51:47.029309Z","end":"2026-06-30T18:51:47.370178Z","steps":["trace[705155796] 'agreement among raft nodes before linearized reading' (duration: 340.443392ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:47.370124Z","caller":"traceutil/trace.go:171","msg":"trace[199382866] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25248; }","duration":"336.696411ms","start":"2026-06-30T18:51:47.033405Z","end":"2026-06-30T18:51:47.370101Z","steps":["trace[199382866] 'agreement among raft nodes before linearized reading' (duration: 336.453717ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:47.370258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:47.029291Z","time spent":"340.959002ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-30T18:51:47.370282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:47.033395Z","time spent":"336.875074ms","remote":"127.0.0.1:60964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-30T18:51:47.657135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.568383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:51:47.657587Z","caller":"traceutil/trace.go:171","msg":"trace[445975222] transaction","detail":"{read_only:false; response_revision:25252; number_of_response:1; }","duration":"252.80127ms","start":"2026-06-30T18:51:47.404761Z","end":"2026-06-30T18:51:47.657563Z","steps":["trace[445975222] 'process raft request' (duration: 252.738159ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:47.657844Z","caller":"traceutil/trace.go:171","msg":"trace[69029586] transaction","detail":"{read_only:false; response_revision:25249; number_of_response:1; }","duration":"283.474291ms","start":"2026-06-30T18:51:47.374347Z","end":"2026-06-30T18:51:47.657821Z","steps":["trace[69029586] 'process raft request' (duration: 122.114064ms)","trace[69029586] 'compare' (duration: 160.175514ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:47.657902Z","caller":"traceutil/trace.go:171","msg":"trace[517993029] linearizableReadLoop","detail":"{readStateIndex:26067; appliedIndex:26066; }","duration":"282.904011ms","start":"2026-06-30T18:51:47.374972Z","end":"2026-06-30T18:51:47.657876Z","steps":["trace[517993029] 'read index received' (duration: 121.472882ms)","trace[517993029] 'applied index is now lower than readState.Index' (duration: 161.429279ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:47.657936Z","caller":"traceutil/trace.go:171","msg":"trace[1960111234] transaction","detail":"{read_only:false; response_revision:25251; number_of_response:1; }","duration":"282.720387ms","start":"2026-06-30T18:51:47.375171Z","end":"2026-06-30T18:51:47.657892Z","steps":["trace[1960111234] 'process raft request' (duration: 282.275549ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:47.657935Z","caller":"traceutil/trace.go:171","msg":"trace[863716724] transaction","detail":"{read_only:false; response_revision:25250; number_of_response:1; }","duration":"282.893061ms","start":"2026-06-30T18:51:47.375019Z","end":"2026-06-30T18:51:47.657912Z","steps":["trace[863716724] 'process raft request' (duration: 282.256259ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:47.658198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.205137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18434"} {"level":"info","ts":"2026-06-30T18:51:47.658279Z","caller":"traceutil/trace.go:171","msg":"trace[1162928852] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25252; }","duration":"283.33126ms","start":"2026-06-30T18:51:47.374931Z","end":"2026-06-30T18:51:47.658262Z","steps":["trace[1162928852] 'agreement among raft nodes before linearized reading' (duration: 283.090195ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:48.024139Z","caller":"traceutil/trace.go:171","msg":"trace[1147067243] transaction","detail":"{read_only:false; response_revision:25253; number_of_response:1; }","duration":"215.453492ms","start":"2026-06-30T18:51:47.808655Z","end":"2026-06-30T18:51:48.024108Z","steps":["trace[1147067243] 'process raft request' (duration: 215.199658ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:48.26391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.65774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-06-30T18:51:48.264123Z","caller":"traceutil/trace.go:171","msg":"trace[1843741204] transaction","detail":"{read_only:false; response_revision:25255; number_of_response:1; }","duration":"165.619987ms","start":"2026-06-30T18:51:48.098484Z","end":"2026-06-30T18:51:48.264104Z","steps":["trace[1843741204] 'process raft request' (duration: 165.528516ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:51:48.264116Z","caller":"traceutil/trace.go:171","msg":"trace[1054701487] transaction","detail":"{read_only:false; response_revision:25254; number_of_response:1; }","duration":"391.261843ms","start":"2026-06-30T18:51:47.872787Z","end":"2026-06-30T18:51:48.264049Z","steps":["trace[1054701487] 'process raft request' (duration: 170.390629ms)","trace[1054701487] 'compare' (duration: 220.441667ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:51:48.264341Z","caller":"traceutil/trace.go:171","msg":"trace[1935328392] linearizableReadLoop","detail":"{readStateIndex:26072; appliedIndex:26071; }","duration":"186.84785ms","start":"2026-06-30T18:51:48.077482Z","end":"2026-06-30T18:51:48.26433Z","steps":["trace[1935328392] 'read index received' (duration: 49.491µs)","trace[1935328392] 'applied index is now lower than readState.Index' (duration: 186.797379ms)"],"step_count":2} {"level":"warn","ts":"2026-06-30T18:51:48.264348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-30T18:51:47.872751Z","time spent":"391.487558ms","remote":"127.0.0.1:60764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":859,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-30T18:51:48.264578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.087545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:51:48.264644Z","caller":"traceutil/trace.go:171","msg":"trace[315018112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25255; }","duration":"187.195967ms","start":"2026-06-30T18:51:48.077438Z","end":"2026-06-30T18:51:48.264634Z","steps":["trace[315018112] 'agreement among raft nodes before linearized reading' (duration: 187.099755ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:51:48.285366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.264527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-30T18:51:48.285563Z","caller":"traceutil/trace.go:171","msg":"trace[531651103] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:25257; }","duration":"102.464962ms","start":"2026-06-30T18:51:48.182995Z","end":"2026-06-30T18:51:48.28546Z","steps":["trace[531651103] 'agreement among raft nodes before linearized reading' (duration: 102.261297ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:52:14.225788Z","caller":"traceutil/trace.go:171","msg":"trace[2037753384] transaction","detail":"{read_only:false; response_revision:25597; number_of_response:1; }","duration":"111.87423ms","start":"2026-06-30T18:52:14.113888Z","end":"2026-06-30T18:52:14.225762Z","steps":["trace[2037753384] 'process raft request' (duration: 111.716316ms)"],"step_count":1} {"level":"warn","ts":"2026-06-30T18:52:14.467097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.457038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-30T18:52:14.467198Z","caller":"traceutil/trace.go:171","msg":"trace[334727981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25597; }","duration":"124.640591ms","start":"2026-06-30T18:52:14.342535Z","end":"2026-06-30T18:52:14.467175Z","steps":["trace[334727981] 'range keys from in-memory index tree' (duration: 124.394117ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:52:14.467276Z","caller":"traceutil/trace.go:171","msg":"trace[1501308435] transaction","detail":"{read_only:false; response_revision:25598; number_of_response:1; }","duration":"115.137742ms","start":"2026-06-30T18:52:14.352122Z","end":"2026-06-30T18:52:14.46726Z","steps":["trace[1501308435] 'process raft request' (duration: 114.959379ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:52:14.477826Z","caller":"traceutil/trace.go:171","msg":"trace[1910783686] transaction","detail":"{read_only:false; response_revision:25599; number_of_response:1; }","duration":"106.845695ms","start":"2026-06-30T18:52:14.370953Z","end":"2026-06-30T18:52:14.477798Z","steps":["trace[1910783686] 'process raft request' (duration: 106.663321ms)"],"step_count":1} {"level":"info","ts":"2026-06-30T18:52:14.644564Z","caller":"traceutil/trace.go:171","msg":"trace[1435916517] transaction","detail":"{read_only:false; response_revision:25601; number_of_response:1; }","duration":"140.573675ms","start":"2026-06-30T18:52:14.503949Z","end":"2026-06-30T18:52:14.644523Z","steps":["trace[1435916517] 'process raft request' (duration: 73.148277ms)","trace[1435916517] 'compare' (duration: 66.920217ms)"],"step_count":2} {"level":"info","ts":"2026-06-30T18:52:19.479094Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000676a.wal"} {"level":"info","ts":"2026-06-30T18:53:25.365738Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23373} {"level":"info","ts":"2026-06-30T18:53:25.510547Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23373,"took":"140.099275ms","hash":529333046,"current-db-size-bytes":61345792,"current-db-size":"61 MB","current-db-size-in-use-bytes":35975168,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-06-30T18:53:25.510682Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":529333046,"revision":23373,"compact-revision":20134} {"level":"info","ts":"2026-06-30T18:58:25.372247Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26209} {"level":"info","ts":"2026-06-30T18:58:25.51144Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26209,"took":"135.075134ms","hash":1158679549,"current-db-size-bytes":61345792,"current-db-size":"61 MB","current-db-size-in-use-bytes":33869824,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-30T18:58:25.511507Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1158679549,"revision":26209,"compact-revision":23373} {"level":"info","ts":"2026-06-30T18:58:57.894699Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3cec112083e8d3ff","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-30T18:58:57.901686Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-30T18:58:57.90177Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-30T19:03:25.383876Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28844} {"level":"info","ts":"2026-06-30T19:03:25.521265Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28844,"took":"131.554707ms","hash":2800547293,"current-db-size-bytes":61345792,"current-db-size":"61 MB","current-db-size-in-use-bytes":31621120,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-30T19:03:25.521345Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2800547293,"revision":28844,"compact-revision":26209} {"level":"info","ts":"2026-06-30T19:08:25.390257Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31129} {"level":"info","ts":"2026-06-30T19:08:25.513168Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31129,"took":"119.295952ms","hash":1692192016,"current-db-size-bytes":61345792,"current-db-size":"61 MB","current-db-size-in-use-bytes":31637504,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-30T19:08:25.513226Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1692192016,"revision":31129,"compact-revision":28844} {"level":"info","ts":"2026-06-30T19:13:25.401685Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33427} {"level":"info","ts":"2026-06-30T19:13:25.550766Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33427,"took":"145.194423ms","hash":1316935662,"current-db-size-bytes":63229952,"current-db-size":"63 MB","current-db-size-in-use-bytes":32456704,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-30T19:13:25.550836Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1316935662,"revision":33427,"compact-revision":31129} {"level":"info","ts":"2026-06-30T19:18:25.407267Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35723} {"level":"info","ts":"2026-06-30T19:18:25.548918Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35723,"took":"137.921505ms","hash":1868727681,"current-db-size-bytes":64540672,"current-db-size":"64 MB","current-db-size-in-use-bytes":31866880,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-30T19:18:25.548979Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1868727681,"revision":35723,"compact-revision":33427} {"level":"info","ts":"2026-06-30T19:20:10.34944Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3cec112083e8d3ff","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-30T19:20:10.354325Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-30T19:20:10.35446Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}