{"level":"warn","ts":"2026-05-28T21:45:16.269514Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-28T21:45:16.269598Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.233:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.233:2380","--initial-cluster=instance=https://199.19.213.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.233: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-05-28T21:45:16.269664Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-28T21:45:16.269676Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.233:2380"]} {"level":"info","ts":"2026-05-28T21:45:16.269693Z","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-05-28T21:45:16.270325Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.233:2379"]} {"level":"info","ts":"2026-05-28T21:45:16.270402Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.233:2380"],"listen-peer-urls":["https://199.19.213.233:2380"],"advertise-client-urls":["https://199.19.213.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.233: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-05-28T21:45:16.273679Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.072689ms"} {"level":"info","ts":"2026-05-28T21:45:16.280515Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"de5c2ed1f829ccc5","cluster-id":"1c2360c7fdb72bdb"} {"level":"info","ts":"2026-05-28T21:45:16.280587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 switched to configuration voters=()"} {"level":"info","ts":"2026-05-28T21:45:16.280608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became follower at term 0"} {"level":"info","ts":"2026-05-28T21:45:16.280617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft de5c2ed1f829ccc5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-28T21:45:16.280626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became follower at term 1"} {"level":"info","ts":"2026-05-28T21:45:16.280662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 switched to configuration voters=(16022733053623913669)"} {"level":"warn","ts":"2026-05-28T21:45:16.285735Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-28T21:45:16.28714Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-28T21:45:16.288297Z","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-05-28T21:45:16.291302Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"de5c2ed1f829ccc5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-28T21:45:16.291909Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"de5c2ed1f829ccc5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-28T21:45:16.291947Z","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-05-28T21:45:16.292094Z","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-05-28T21:45:16.292117Z","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-05-28T21:45:16.292659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 switched to configuration voters=(16022733053623913669)"} {"level":"info","ts":"2026-05-28T21:45:16.293528Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1c2360c7fdb72bdb","local-member-id":"de5c2ed1f829ccc5","added-peer-id":"de5c2ed1f829ccc5","added-peer-peer-urls":["https://199.19.213.233:2380"]} {"level":"info","ts":"2026-05-28T21:45:16.297895Z","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-05-28T21:45:16.298128Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.233:2380"} {"level":"info","ts":"2026-05-28T21:45:16.298225Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.233:2380"} {"level":"info","ts":"2026-05-28T21:45:16.298379Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"de5c2ed1f829ccc5","initial-advertise-peer-urls":["https://199.19.213.233:2380"],"listen-peer-urls":["https://199.19.213.233:2380"],"advertise-client-urls":["https://199.19.213.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-28T21:45:16.298466Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-28T21:45:16.981866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 is starting a new election at term 1"} {"level":"info","ts":"2026-05-28T21:45:16.981997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-28T21:45:16.982054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 received MsgPreVoteResp from de5c2ed1f829ccc5 at term 1"} {"level":"info","ts":"2026-05-28T21:45:16.982079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became candidate at term 2"} {"level":"info","ts":"2026-05-28T21:45:16.982091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 received MsgVoteResp from de5c2ed1f829ccc5 at term 2"} {"level":"info","ts":"2026-05-28T21:45:16.982144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became leader at term 2"} {"level":"info","ts":"2026-05-28T21:45:16.98218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: de5c2ed1f829ccc5 elected leader de5c2ed1f829ccc5 at term 2"} {"level":"info","ts":"2026-05-28T21:45:16.983692Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"de5c2ed1f829ccc5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.233:2379]}","request-path":"/0/members/de5c2ed1f829ccc5/attributes","cluster-id":"1c2360c7fdb72bdb","publish-timeout":"7s"} {"level":"info","ts":"2026-05-28T21:45:16.98392Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T21:45:16.98398Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T21:45:16.984097Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-28T21:45:16.98415Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-28T21:45:16.984222Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T21:45:16.987481Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-28T21:45:16.988359Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.233:2379"} {"level":"info","ts":"2026-05-28T21:45:16.996692Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1c2360c7fdb72bdb","local-member-id":"de5c2ed1f829ccc5","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T21:45:16.996792Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T21:45:16.996815Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T21:45:27.507978Z","caller":"traceutil/trace.go:171","msg":"trace[1844083863] linearizableReadLoop","detail":"{readStateIndex:344; appliedIndex:342; }","duration":"297.317185ms","start":"2026-05-28T21:45:27.210642Z","end":"2026-05-28T21:45:27.507959Z","steps":["trace[1844083863] 'read index received' (duration: 28.422361ms)","trace[1844083863] 'applied index is now lower than readState.Index' (duration: 268.894124ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:45:27.508029Z","caller":"traceutil/trace.go:171","msg":"trace[1804150382] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"298.285593ms","start":"2026-05-28T21:45:27.209709Z","end":"2026-05-28T21:45:27.507995Z","steps":["trace[1804150382] 'process raft request' (duration: 298.140039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:27.508147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.483318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-05-28T21:45:27.508205Z","caller":"traceutil/trace.go:171","msg":"trace[762281029] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:332; }","duration":"297.58586ms","start":"2026-05-28T21:45:27.210611Z","end":"2026-05-28T21:45:27.508196Z","steps":["trace[762281029] 'agreement among raft nodes before linearized reading' (duration: 297.469228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:27.508246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.90621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-28T21:45:27.508371Z","caller":"traceutil/trace.go:171","msg":"trace[1433576568] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:332; }","duration":"283.068342ms","start":"2026-05-28T21:45:27.225278Z","end":"2026-05-28T21:45:27.508347Z","steps":["trace[1433576568] 'agreement among raft nodes before linearized reading' (duration: 282.837818ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:27.710901Z","caller":"traceutil/trace.go:171","msg":"trace[2093096523] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"190.737634ms","start":"2026-05-28T21:45:27.520141Z","end":"2026-05-28T21:45:27.710879Z","steps":["trace[2093096523] 'process raft request' (duration: 190.596112ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:27.864739Z","caller":"traceutil/trace.go:171","msg":"trace[2116697984] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"148.613191ms","start":"2026-05-28T21:45:27.716098Z","end":"2026-05-28T21:45:27.864711Z","steps":["trace[2116697984] 'process raft request' (duration: 51.014122ms)","trace[2116697984] 'compare' (duration: 97.486007ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:45:27.924715Z","caller":"traceutil/trace.go:171","msg":"trace[1307183621] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"207.90053ms","start":"2026-05-28T21:45:27.716789Z","end":"2026-05-28T21:45:27.92469Z","steps":["trace[1307183621] 'process raft request' (duration: 207.757697ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:27.924721Z","caller":"traceutil/trace.go:171","msg":"trace[562672102] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"208.038292ms","start":"2026-05-28T21:45:27.716619Z","end":"2026-05-28T21:45:27.924657Z","steps":["trace[562672102] 'process raft request' (duration: 207.769457ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:27.924803Z","caller":"traceutil/trace.go:171","msg":"trace[674330060] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"201.173992ms","start":"2026-05-28T21:45:27.723584Z","end":"2026-05-28T21:45:27.924758Z","steps":["trace[674330060] 'process raft request' (duration: 201.017639ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:53.953381Z","caller":"traceutil/trace.go:171","msg":"trace[1155888516] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"232.0684ms","start":"2026-05-28T21:45:53.721275Z","end":"2026-05-28T21:45:53.953343Z","steps":["trace[1155888516] 'process raft request' (duration: 222.709502ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:53.953755Z","caller":"traceutil/trace.go:171","msg":"trace[1474881572] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"228.857831ms","start":"2026-05-28T21:45:53.724854Z","end":"2026-05-28T21:45:53.953712Z","steps":["trace[1474881572] 'process raft request' (duration: 228.414672ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:53.953915Z","caller":"traceutil/trace.go:171","msg":"trace[2075951758] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"227.82289ms","start":"2026-05-28T21:45:53.726022Z","end":"2026-05-28T21:45:53.953845Z","steps":["trace[2075951758] 'process raft request' (duration: 227.558055ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:45:53.95429Z","caller":"traceutil/trace.go:171","msg":"trace[500897045] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"169.53143ms","start":"2026-05-28T21:45:53.784721Z","end":"2026-05-28T21:45:53.954252Z","steps":["trace[500897045] 'process raft request' (duration: 169.177393ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:00.463498Z","caller":"traceutil/trace.go:171","msg":"trace[222507352] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"138.175583ms","start":"2026-05-28T21:46:00.325289Z","end":"2026-05-28T21:46:00.463465Z","steps":["trace[222507352] 'process raft request' (duration: 123.011236ms)","trace[222507352] 'compare' (duration: 15.012845ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:46:00.747736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.890796ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:46:00.748019Z","caller":"traceutil/trace.go:171","msg":"trace[1625160958] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"237.470564ms","start":"2026-05-28T21:46:00.510497Z","end":"2026-05-28T21:46:00.747967Z","steps":["trace[1625160958] 'process raft request' (duration: 67.554008ms)","trace[1625160958] 'compare' (duration: 168.676452ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:46:00.748434Z","caller":"traceutil/trace.go:171","msg":"trace[889196679] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"236.480055ms","start":"2026-05-28T21:46:00.51188Z","end":"2026-05-28T21:46:00.74836Z","steps":["trace[889196679] 'process raft request' (duration: 236.22102ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:00.748555Z","caller":"traceutil/trace.go:171","msg":"trace[1962447487] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:620; }","duration":"202.255288ms","start":"2026-05-28T21:46:00.546275Z","end":"2026-05-28T21:46:00.74853Z","steps":["trace[1962447487] 'read index received' (duration: 31.795862ms)","trace[1962447487] 'applied index is now lower than readState.Index' (duration: 170.457736ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:46:00.74864Z","caller":"traceutil/trace.go:171","msg":"trace[664134652] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"235.875514ms","start":"2026-05-28T21:46:00.512722Z","end":"2026-05-28T21:46:00.748597Z","steps":["trace[664134652] 'process raft request' (duration: 235.553108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:00.748714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.410902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:46:00.748756Z","caller":"traceutil/trace.go:171","msg":"trace[1506043462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"202.523443ms","start":"2026-05-28T21:46:00.546222Z","end":"2026-05-28T21:46:00.748745Z","steps":["trace[1506043462] 'agreement among raft nodes before linearized reading' (duration: 202.431841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:00.748746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.530371ms","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-05-28T21:46:00.748825Z","caller":"traceutil/trace.go:171","msg":"trace[1453619234] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:598; }","duration":"177.653282ms","start":"2026-05-28T21:46:00.571153Z","end":"2026-05-28T21:46:00.748806Z","steps":["trace[1453619234] 'agreement among raft nodes before linearized reading' (duration: 177.52155ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:01.11022Z","caller":"traceutil/trace.go:171","msg":"trace[788456695] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"355.738313ms","start":"2026-05-28T21:46:00.754453Z","end":"2026-05-28T21:46:01.110191Z","steps":["trace[788456695] 'process raft request' (duration: 355.474678ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:01.11049Z","caller":"traceutil/trace.go:171","msg":"trace[353502328] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"345.368617ms","start":"2026-05-28T21:46:00.765102Z","end":"2026-05-28T21:46:01.11047Z","steps":["trace[353502328] 'process raft request' (duration: 345.01084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:46:01.110574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:00.754437Z","time spent":"355.861175ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:46:01.110671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:46:00.765086Z","time spent":"345.464188ms","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T21:46:01.444324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.852031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-28T21:46:01.444449Z","caller":"traceutil/trace.go:171","msg":"trace[2042449945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:601; }","duration":"145.025845ms","start":"2026-05-28T21:46:01.299395Z","end":"2026-05-28T21:46:01.444421Z","steps":["trace[2042449945] 'range keys from in-memory index tree' (duration: 144.632597ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:15.932823Z","caller":"traceutil/trace.go:171","msg":"trace[875019218] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"115.910433ms","start":"2026-05-28T21:46:15.816892Z","end":"2026-05-28T21:46:15.932802Z","steps":["trace[875019218] 'process raft request' (duration: 115.78374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:34.796048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.356622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T21:47:34.796078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.365586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-28T21:47:34.79616Z","caller":"traceutil/trace.go:171","msg":"trace[279733890] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1820; }","duration":"250.517415ms","start":"2026-05-28T21:47:34.54562Z","end":"2026-05-28T21:47:34.796138Z","steps":["trace[279733890] 'range keys from in-memory index tree' (duration: 250.23399ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:34.796212Z","caller":"traceutil/trace.go:171","msg":"trace[322686465] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1820; }","duration":"146.514159ms","start":"2026-05-28T21:47:34.649636Z","end":"2026-05-28T21:47:34.79615Z","steps":["trace[322686465] 'range keys from in-memory index tree' (duration: 146.164943ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:48:19.608884Z","caller":"traceutil/trace.go:171","msg":"trace[774999303] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"111.686093ms","start":"2026-05-28T21:48:19.497158Z","end":"2026-05-28T21:48:19.608844Z","steps":["trace[774999303] 'process raft request' (duration: 111.436829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:48:19.980707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.912419ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:48:19.980894Z","caller":"traceutil/trace.go:171","msg":"trace[1253928850] linearizableReadLoop","detail":"{readStateIndex:2202; appliedIndex:2201; }","duration":"200.995232ms","start":"2026-05-28T21:48:19.779874Z","end":"2026-05-28T21:48:19.980869Z","steps":["trace[1253928850] 'read index received' (duration: 62.634525ms)","trace[1253928850] 'applied index is now lower than readState.Index' (duration: 138.358687ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:48:19.980919Z","caller":"traceutil/trace.go:171","msg":"trace[1563237434] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"273.131247ms","start":"2026-05-28T21:48:19.707741Z","end":"2026-05-28T21:48:19.980873Z","steps":["trace[1563237434] 'process raft request' (duration: 134.865061ms)","trace[1563237434] 'compare' (duration: 137.480601ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:48:19.981337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.421701ms","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-05-28T21:48:19.981436Z","caller":"traceutil/trace.go:171","msg":"trace[1720010372] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2121; }","duration":"201.557363ms","start":"2026-05-28T21:48:19.779847Z","end":"2026-05-28T21:48:19.981405Z","steps":["trace[1720010372] 'agreement among raft nodes before linearized reading' (duration: 201.157696ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:48:20.097521Z","caller":"traceutil/trace.go:171","msg":"trace[1419753461] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"110.577462ms","start":"2026-05-28T21:48:19.986902Z","end":"2026-05-28T21:48:20.097479Z","steps":["trace[1419753461] 'process raft request' (duration: 106.141998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:48:25.162357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.306265ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-05-28T21:49:48.771433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.487662ms","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-05-28T21:49:48.771553Z","caller":"traceutil/trace.go:171","msg":"trace[2039586070] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2794; }","duration":"236.689016ms","start":"2026-05-28T21:49:48.534842Z","end":"2026-05-28T21:49:48.771531Z","steps":["trace[2039586070] 'range keys from in-memory index tree' (duration: 236.200235ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:49:48.949778Z","caller":"traceutil/trace.go:171","msg":"trace[1256331097] transaction","detail":"{read_only:false; response_revision:2795; number_of_response:1; }","duration":"174.162618ms","start":"2026-05-28T21:49:48.775588Z","end":"2026-05-28T21:49:48.94975Z","steps":["trace[1256331097] 'process raft request' (duration: 174.008715ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:49:49.032427Z","caller":"traceutil/trace.go:171","msg":"trace[420639610] transaction","detail":"{read_only:false; response_revision:2796; number_of_response:1; }","duration":"256.622324ms","start":"2026-05-28T21:49:48.775775Z","end":"2026-05-28T21:49:49.032398Z","steps":["trace[420639610] 'process raft request' (duration: 206.790537ms)","trace[420639610] 'compare' (duration: 49.625923ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:52:19.521869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.133712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T21:52:19.522044Z","caller":"traceutil/trace.go:171","msg":"trace[736975034] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3790; }","duration":"145.384417ms","start":"2026-05-28T21:52:19.37664Z","end":"2026-05-28T21:52:19.522024Z","steps":["trace[736975034] 'count revisions from in-memory index tree' (duration: 145.04958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:53:37.093321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.486825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-74782466-2620-4db8-94ab-6f78f4f13cbb\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-28T21:53:37.093428Z","caller":"traceutil/trace.go:171","msg":"trace[1213811512] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-74782466-2620-4db8-94ab-6f78f4f13cbb; range_end:; response_count:1; response_revision:4945; }","duration":"142.632598ms","start":"2026-05-28T21:53:36.950761Z","end":"2026-05-28T21:53:37.093394Z","steps":["trace[1213811512] 'range keys from in-memory index tree' (duration: 142.302962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:53:37.093597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.280377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:728671"} {"level":"info","ts":"2026-05-28T21:53:37.093675Z","caller":"traceutil/trace.go:171","msg":"trace[711883224] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4945; }","duration":"109.43288ms","start":"2026-05-28T21:53:36.984224Z","end":"2026-05-28T21:53:37.093657Z","steps":["trace[711883224] 'range keys from in-memory index tree' (duration: 108.823259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:53:39.150692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.930909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786856"} {"level":"info","ts":"2026-05-28T21:53:39.150789Z","caller":"traceutil/trace.go:171","msg":"trace[638216226] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4985; }","duration":"110.079202ms","start":"2026-05-28T21:53:39.04069Z","end":"2026-05-28T21:53:39.150769Z","steps":["trace[638216226] 'range keys from in-memory index tree' (duration: 109.431689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:53:51.761853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.15849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:53:51.761957Z","caller":"traceutil/trace.go:171","msg":"trace[1276895261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5220; }","duration":"215.315653ms","start":"2026-05-28T21:53:51.546621Z","end":"2026-05-28T21:53:51.761937Z","steps":["trace[1276895261] 'range keys from in-memory index tree' (duration: 215.081738ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:51.773956Z","caller":"traceutil/trace.go:171","msg":"trace[1378112829] transaction","detail":"{read_only:false; response_revision:5221; number_of_response:1; }","duration":"225.310662ms","start":"2026-05-28T21:53:51.548585Z","end":"2026-05-28T21:53:51.773895Z","steps":["trace[1378112829] 'process raft request' (duration: 225.118068ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:59.091563Z","caller":"traceutil/trace.go:171","msg":"trace[2022513535] transaction","detail":"{read_only:false; response_revision:5383; number_of_response:1; }","duration":"173.279657ms","start":"2026-05-28T21:53:58.918227Z","end":"2026-05-28T21:53:59.091507Z","steps":["trace[2022513535] 'process raft request' (duration: 115.168028ms)","trace[2022513535] 'compare' (duration: 57.845214ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:53:59.311492Z","caller":"traceutil/trace.go:171","msg":"trace[1883543002] transaction","detail":"{read_only:false; response_revision:5384; number_of_response:1; }","duration":"120.666284ms","start":"2026-05-28T21:53:59.190794Z","end":"2026-05-28T21:53:59.31146Z","steps":["trace[1883543002] 'process raft request' (duration: 120.382007ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:59.523302Z","caller":"traceutil/trace.go:171","msg":"trace[1231135310] transaction","detail":"{read_only:false; response_revision:5386; number_of_response:1; }","duration":"205.595309ms","start":"2026-05-28T21:53:59.317672Z","end":"2026-05-28T21:53:59.523268Z","steps":["trace[1231135310] 'process raft request' (duration: 195.990677ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:59.523457Z","caller":"traceutil/trace.go:171","msg":"trace[1775118055] transaction","detail":"{read_only:false; response_revision:5388; number_of_response:1; }","duration":"205.550778ms","start":"2026-05-28T21:53:59.317898Z","end":"2026-05-28T21:53:59.523449Z","steps":["trace[1775118055] 'process raft request' (duration: 205.338604ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:59.523537Z","caller":"traceutil/trace.go:171","msg":"trace[620392016] transaction","detail":"{read_only:false; response_revision:5390; number_of_response:1; }","duration":"204.386875ms","start":"2026-05-28T21:53:59.319126Z","end":"2026-05-28T21:53:59.523513Z","steps":["trace[620392016] 'process raft request' (duration: 204.188452ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:59.523696Z","caller":"traceutil/trace.go:171","msg":"trace[1529194523] linearizableReadLoop","detail":"{readStateIndex:5594; appliedIndex:5588; }","duration":"167.11607ms","start":"2026-05-28T21:53:59.356513Z","end":"2026-05-28T21:53:59.523629Z","steps":["trace[1529194523] 'read index received' (duration: 157.167673ms)","trace[1529194523] 'applied index is now lower than readState.Index' (duration: 9.946907ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:53:59.523328Z","caller":"traceutil/trace.go:171","msg":"trace[1772146344] transaction","detail":"{read_only:false; response_revision:5387; number_of_response:1; }","duration":"205.445795ms","start":"2026-05-28T21:53:59.317852Z","end":"2026-05-28T21:53:59.523298Z","steps":["trace[1772146344] 'process raft request' (duration: 205.344984ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:59.523979Z","caller":"traceutil/trace.go:171","msg":"trace[1976723708] transaction","detail":"{read_only:false; response_revision:5391; number_of_response:1; }","duration":"204.430186ms","start":"2026-05-28T21:53:59.319516Z","end":"2026-05-28T21:53:59.523947Z","steps":["trace[1976723708] 'process raft request' (duration: 203.837435ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:59.523423Z","caller":"traceutil/trace.go:171","msg":"trace[1702426070] transaction","detail":"{read_only:false; response_revision:5389; number_of_response:1; }","duration":"205.479037ms","start":"2026-05-28T21:53:59.317933Z","end":"2026-05-28T21:53:59.523412Z","steps":["trace[1702426070] 'process raft request' (duration: 205.339694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:53:59.524161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.68996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-28T21:53:59.52422Z","caller":"traceutil/trace.go:171","msg":"trace[1262956414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5391; }","duration":"178.808992ms","start":"2026-05-28T21:53:59.345396Z","end":"2026-05-28T21:53:59.524205Z","steps":["trace[1262956414] 'agreement among raft nodes before linearized reading' (duration: 178.557416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:53:59.524746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.971466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jbwhf\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-05-28T21:53:59.524902Z","caller":"traceutil/trace.go:171","msg":"trace[1778287885] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jbwhf; range_end:; response_count:1; response_revision:5391; }","duration":"177.148789ms","start":"2026-05-28T21:53:59.347735Z","end":"2026-05-28T21:53:59.524883Z","steps":["trace[1778287885] 'agreement among raft nodes before linearized reading' (duration: 176.391415ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:59.541649Z","caller":"traceutil/trace.go:171","msg":"trace[540386754] transaction","detail":"{read_only:false; response_revision:5392; number_of_response:1; }","duration":"177.14817ms","start":"2026-05-28T21:53:59.364471Z","end":"2026-05-28T21:53:59.541619Z","steps":["trace[540386754] 'process raft request' (duration: 176.979397ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:59.542046Z","caller":"traceutil/trace.go:171","msg":"trace[1865053157] transaction","detail":"{read_only:false; response_revision:5393; number_of_response:1; }","duration":"169.20764ms","start":"2026-05-28T21:53:59.372817Z","end":"2026-05-28T21:53:59.542024Z","steps":["trace[1865053157] 'process raft request' (duration: 168.733032ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:55:02.829802Z","caller":"traceutil/trace.go:171","msg":"trace[1336500715] transaction","detail":"{read_only:false; response_revision:5981; number_of_response:1; }","duration":"110.172653ms","start":"2026-05-28T21:55:02.719596Z","end":"2026-05-28T21:55:02.829769Z","steps":["trace[1336500715] 'process raft request' (duration: 109.983248ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:55:17.018613Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2950} {"level":"info","ts":"2026-05-28T21:55:17.095431Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2950,"took":"75.242092ms","hash":2339383248,"current-db-size-bytes":58793984,"current-db-size":"59 MB","current-db-size-in-use-bytes":34353152,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-28T21:55:17.0955Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2339383248,"revision":2950,"compact-revision":-1} {"level":"info","ts":"2026-05-28T21:59:53.870698Z","caller":"traceutil/trace.go:171","msg":"trace[542773271] linearizableReadLoop","detail":"{readStateIndex:8446; appliedIndex:8445; }","duration":"133.259818ms","start":"2026-05-28T21:59:53.737407Z","end":"2026-05-28T21:59:53.870667Z","steps":["trace[542773271] 'read index received' (duration: 133.088275ms)","trace[542773271] 'applied index is now lower than readState.Index' (duration: 169.933µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:59:53.870804Z","caller":"traceutil/trace.go:171","msg":"trace[1759827120] transaction","detail":"{read_only:false; response_revision:8157; number_of_response:1; }","duration":"318.584144ms","start":"2026-05-28T21:59:53.552179Z","end":"2026-05-28T21:59:53.870763Z","steps":["trace[1759827120] 'process raft request' (duration: 318.276988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:59:53.870883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.445672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:59:53.870968Z","caller":"traceutil/trace.go:171","msg":"trace[115867125] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:8157; }","duration":"133.597684ms","start":"2026-05-28T21:59:53.737355Z","end":"2026-05-28T21:59:53.870952Z","steps":["trace[115867125] 'agreement among raft nodes before linearized reading' (duration: 133.435751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:59:53.871139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:59:53.552154Z","time spent":"318.896599ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T21:59:53.989076Z","caller":"traceutil/trace.go:171","msg":"trace[608453698] linearizableReadLoop","detail":"{readStateIndex:8447; appliedIndex:8446; }","duration":"103.885088ms","start":"2026-05-28T21:59:53.885166Z","end":"2026-05-28T21:59:53.989051Z","steps":["trace[608453698] 'read index received' (duration: 102.266147ms)","trace[608453698] 'applied index is now lower than readState.Index' (duration: 1.617471ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:59:53.98915Z","caller":"traceutil/trace.go:171","msg":"trace[150736854] transaction","detail":"{read_only:false; response_revision:8158; number_of_response:1; }","duration":"110.791577ms","start":"2026-05-28T21:59:53.878333Z","end":"2026-05-28T21:59:53.989125Z","steps":["trace[150736854] 'process raft request' (duration: 109.194577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:59:53.989365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.175033ms","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-05-28T21:59:53.989409Z","caller":"traceutil/trace.go:171","msg":"trace[1224821725] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8158; }","duration":"104.247484ms","start":"2026-05-28T21:59:53.885151Z","end":"2026-05-28T21:59:53.989398Z","steps":["trace[1224821725] 'agreement among raft nodes before linearized reading' (duration: 103.995359ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:00:17.025222Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6104} {"level":"info","ts":"2026-05-28T22:00:17.124674Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6104,"took":"97.361115ms","hash":3561446983,"current-db-size-bytes":58793984,"current-db-size":"59 MB","current-db-size-in-use-bytes":23453696,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-28T22:00:17.124744Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3561446983,"revision":6104,"compact-revision":2950} {"level":"warn","ts":"2026-05-28T22:00:33.06757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.404744ms","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-05-28T22:00:33.067647Z","caller":"traceutil/trace.go:171","msg":"trace[1093796108] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8419; }","duration":"291.521967ms","start":"2026-05-28T22:00:32.776111Z","end":"2026-05-28T22:00:33.067633Z","steps":["trace[1093796108] 'range keys from in-memory index tree' (duration: 291.270232ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:00:44.690494Z","caller":"traceutil/trace.go:171","msg":"trace[1069214528] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8523; }","duration":"114.709112ms","start":"2026-05-28T22:00:44.575758Z","end":"2026-05-28T22:00:44.690468Z","steps":["trace[1069214528] 'process raft request' (duration: 45.164797ms)","trace[1069214528] 'compare' (duration: 69.482463ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:00:45.531101Z","caller":"traceutil/trace.go:171","msg":"trace[580928550] transaction","detail":"{read_only:false; response_revision:8528; number_of_response:1; }","duration":"135.96114ms","start":"2026-05-28T22:00:45.395113Z","end":"2026-05-28T22:00:45.531074Z","steps":["trace[580928550] 'process raft request' (duration: 89.286285ms)","trace[580928550] 'compare' (duration: 46.468931ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:01:34.427191Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000246a.wal"} {"level":"info","ts":"2026-05-28T22:02:00.031106Z","caller":"traceutil/trace.go:171","msg":"trace[1478307582] linearizableReadLoop","detail":"{readStateIndex:9593; appliedIndex:9592; }","duration":"101.618976ms","start":"2026-05-28T22:01:59.929458Z","end":"2026-05-28T22:02:00.031077Z","steps":["trace[1478307582] 'read index received' (duration: 101.470734ms)","trace[1478307582] 'applied index is now lower than readState.Index' (duration: 147.192µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:02:00.031254Z","caller":"traceutil/trace.go:171","msg":"trace[1748289742] transaction","detail":"{read_only:false; response_revision:9266; number_of_response:1; }","duration":"204.798224ms","start":"2026-05-28T22:01:59.826411Z","end":"2026-05-28T22:02:00.03121Z","steps":["trace[1748289742] 'process raft request' (duration: 204.519559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:02:00.03136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.317222ms","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-05-28T22:02:00.031421Z","caller":"traceutil/trace.go:171","msg":"trace[802037866] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9266; }","duration":"100.409134ms","start":"2026-05-28T22:01:59.930996Z","end":"2026-05-28T22:02:00.031405Z","steps":["trace[802037866] 'agreement among raft nodes before linearized reading' (duration: 100.238261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:02:00.031436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.952083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-28T22:02:00.03152Z","caller":"traceutil/trace.go:171","msg":"trace[1807700666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9266; }","duration":"102.081796ms","start":"2026-05-28T22:01:59.929417Z","end":"2026-05-28T22:02:00.031499Z","steps":["trace[1807700666] 'agreement among raft nodes before linearized reading' (duration: 101.80471ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:02:53.690923Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"de5c2ed1f829ccc5","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-28T22:02:53.696371Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-28T22:02:53.696447Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-28T22:05:17.032433Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8285} {"level":"info","ts":"2026-05-28T22:05:17.106534Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8285,"took":"71.883349ms","hash":526477167,"current-db-size-bytes":58793984,"current-db-size":"59 MB","current-db-size-in-use-bytes":26701824,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-28T22:05:17.106584Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":526477167,"revision":8285,"compact-revision":6104} {"level":"info","ts":"2026-05-28T22:05:39.910719Z","caller":"traceutil/trace.go:171","msg":"trace[1087812980] transaction","detail":"{read_only:false; response_revision:11096; number_of_response:1; }","duration":"163.102461ms","start":"2026-05-28T22:05:39.747591Z","end":"2026-05-28T22:05:39.910693Z","steps":["trace[1087812980] 'process raft request' (duration: 162.950889ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:05:39.922108Z","caller":"traceutil/trace.go:171","msg":"trace[1392569087] linearizableReadLoop","detail":"{readStateIndex:11478; appliedIndex:11476; }","duration":"166.69464ms","start":"2026-05-28T22:05:39.755395Z","end":"2026-05-28T22:05:39.922089Z","steps":["trace[1392569087] 'read index received' (duration: 155.075111ms)","trace[1392569087] 'applied index is now lower than readState.Index' (duration: 11.618539ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:05:39.922138Z","caller":"traceutil/trace.go:171","msg":"trace[1002475575] transaction","detail":"{read_only:false; response_revision:11097; number_of_response:1; }","duration":"173.034098ms","start":"2026-05-28T22:05:39.749089Z","end":"2026-05-28T22:05:39.922123Z","steps":["trace[1002475575] 'process raft request' (duration: 172.849885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:05:39.922317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.908424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-28T22:05:39.922364Z","caller":"traceutil/trace.go:171","msg":"trace[554934645] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11097; }","duration":"167.022996ms","start":"2026-05-28T22:05:39.755331Z","end":"2026-05-28T22:05:39.922354Z","steps":["trace[554934645] 'agreement among raft nodes before linearized reading' (duration: 166.865613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:05:39.922416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.034868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jbwhf\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-05-28T22:05:39.9225Z","caller":"traceutil/trace.go:171","msg":"trace[1587655026] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jbwhf; range_end:; response_count:1; response_revision:11097; }","duration":"114.170641ms","start":"2026-05-28T22:05:39.80831Z","end":"2026-05-28T22:05:39.922481Z","steps":["trace[1587655026] 'agreement among raft nodes before linearized reading' (duration: 113.928796ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:05:40.646924Z","caller":"traceutil/trace.go:171","msg":"trace[613742297] linearizableReadLoop","detail":"{readStateIndex:11483; appliedIndex:11482; }","duration":"102.137665ms","start":"2026-05-28T22:05:40.544746Z","end":"2026-05-28T22:05:40.646883Z","steps":["trace[613742297] 'read index received' (duration: 93.420491ms)","trace[613742297] 'applied index is now lower than readState.Index' (duration: 8.715644ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:05:40.647155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.393679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T22:05:40.647126Z","caller":"traceutil/trace.go:171","msg":"trace[1616952351] transaction","detail":"{read_only:false; response_revision:11101; number_of_response:1; }","duration":"121.028189ms","start":"2026-05-28T22:05:40.525996Z","end":"2026-05-28T22:05:40.647025Z","steps":["trace[1616952351] 'process raft request' (duration: 112.305785ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:05:40.647213Z","caller":"traceutil/trace.go:171","msg":"trace[108046140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11101; }","duration":"102.532971ms","start":"2026-05-28T22:05:40.544664Z","end":"2026-05-28T22:05:40.647197Z","steps":["trace[108046140] 'agreement among raft nodes before linearized reading' (duration: 102.406809ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:10:17.039761Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10964} {"level":"info","ts":"2026-05-28T22:10:17.124881Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10964,"took":"81.487202ms","hash":395355140,"current-db-size-bytes":58793984,"current-db-size":"59 MB","current-db-size-in-use-bytes":27942912,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-28T22:10:17.124945Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":395355140,"revision":10964,"compact-revision":8285} {"level":"info","ts":"2026-05-28T22:12:23.207304Z","caller":"traceutil/trace.go:171","msg":"trace[1961419103] transaction","detail":"{read_only:false; response_revision:13973; number_of_response:1; }","duration":"196.420591ms","start":"2026-05-28T22:12:23.010857Z","end":"2026-05-28T22:12:23.207278Z","steps":["trace[1961419103] 'process raft request' (duration: 196.193877ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:12:23.213842Z","caller":"traceutil/trace.go:171","msg":"trace[1478393046] linearizableReadLoop","detail":"{readStateIndex:14460; appliedIndex:14458; }","duration":"159.700261ms","start":"2026-05-28T22:12:23.054127Z","end":"2026-05-28T22:12:23.213827Z","steps":["trace[1478393046] 'read index received' (duration: 152.901243ms)","trace[1478393046] 'applied index is now lower than readState.Index' (duration: 6.798158ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:12:23.214013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.877056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jbwhf\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-05-28T22:12:23.213952Z","caller":"traceutil/trace.go:171","msg":"trace[1000684401] transaction","detail":"{read_only:false; response_revision:13974; number_of_response:1; }","duration":"194.794961ms","start":"2026-05-28T22:12:23.019119Z","end":"2026-05-28T22:12:23.213914Z","steps":["trace[1000684401] 'process raft request' (duration: 194.597007ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:12:23.214042Z","caller":"traceutil/trace.go:171","msg":"trace[557612771] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jbwhf; range_end:; response_count:1; response_revision:13975; }","duration":"159.941936ms","start":"2026-05-28T22:12:23.054093Z","end":"2026-05-28T22:12:23.214035Z","steps":["trace[557612771] 'agreement among raft nodes before linearized reading' (duration: 159.798173ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:12:23.214222Z","caller":"traceutil/trace.go:171","msg":"trace[845520773] transaction","detail":"{read_only:false; response_revision:13975; number_of_response:1; }","duration":"121.064486ms","start":"2026-05-28T22:12:23.093124Z","end":"2026-05-28T22:12:23.214189Z","steps":["trace[845520773] 'process raft request' (duration: 120.674198ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:15:17.045781Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12994} {"level":"info","ts":"2026-05-28T22:15:17.122056Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12994,"took":"73.598763ms","hash":1257530395,"current-db-size-bytes":58793984,"current-db-size":"59 MB","current-db-size-in-use-bytes":30674944,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-28T22:15:17.122127Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1257530395,"revision":12994,"compact-revision":10964} {"level":"info","ts":"2026-05-28T22:19:15.57265Z","caller":"traceutil/trace.go:171","msg":"trace[1693547252] transaction","detail":"{read_only:false; response_revision:17255; number_of_response:1; }","duration":"153.597104ms","start":"2026-05-28T22:19:15.419031Z","end":"2026-05-28T22:19:15.572628Z","steps":["trace[1693547252] 'process raft request' (duration: 153.213087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:19:21.621587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.474786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:19:21.62185Z","caller":"traceutil/trace.go:171","msg":"trace[592798642] transaction","detail":"{read_only:false; response_revision:17289; number_of_response:1; }","duration":"254.930454ms","start":"2026-05-28T22:19:21.366886Z","end":"2026-05-28T22:19:21.621817Z","steps":["trace[592798642] 'process raft request' (duration: 34.956738ms)","trace[592798642] 'compare' (duration: 219.300102ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:19:22.076159Z","caller":"traceutil/trace.go:171","msg":"trace[1275451342] linearizableReadLoop","detail":"{readStateIndex:17883; appliedIndex:17882; }","duration":"165.668272ms","start":"2026-05-28T22:19:21.910474Z","end":"2026-05-28T22:19:22.076142Z","steps":["trace[1275451342] 'read index received' (duration: 165.468289ms)","trace[1275451342] 'applied index is now lower than readState.Index' (duration: 199.393µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:19:22.07631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.816015ms","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-05-28T22:19:22.076329Z","caller":"traceutil/trace.go:171","msg":"trace[1649573765] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17292; }","duration":"165.869207ms","start":"2026-05-28T22:19:21.910455Z","end":"2026-05-28T22:19:22.076324Z","steps":["trace[1649573765] 'agreement among raft nodes before linearized reading' (duration: 165.749283ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:19:22.076532Z","caller":"traceutil/trace.go:171","msg":"trace[1132465848] transaction","detail":"{read_only:false; response_revision:17292; number_of_response:1; }","duration":"168.387484ms","start":"2026-05-28T22:19:21.908139Z","end":"2026-05-28T22:19:22.076527Z","steps":["trace[1132465848] 'process raft request' (duration: 167.858354ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:19:22.428948Z","caller":"traceutil/trace.go:171","msg":"trace[39733251] transaction","detail":"{read_only:false; response_revision:17294; number_of_response:1; }","duration":"271.626079ms","start":"2026-05-28T22:19:22.157299Z","end":"2026-05-28T22:19:22.428925Z","steps":["trace[39733251] 'process raft request' (duration: 271.473976ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:19:22.63519Z","caller":"traceutil/trace.go:171","msg":"trace[917617487] transaction","detail":"{read_only:false; response_revision:17295; number_of_response:1; }","duration":"467.919819ms","start":"2026-05-28T22:19:22.167238Z","end":"2026-05-28T22:19:22.635158Z","steps":["trace[917617487] 'process raft request' (duration: 467.551421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:19:22.635388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:19:22.167229Z","time spent":"468.041251ms","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20988,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:19:30.572072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.402239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1125"} {"level":"info","ts":"2026-05-28T22:19:30.572244Z","caller":"traceutil/trace.go:171","msg":"trace[1500042318] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:17447; }","duration":"111.621462ms","start":"2026-05-28T22:19:30.460594Z","end":"2026-05-28T22:19:30.572216Z","steps":["trace[1500042318] 'agreement among raft nodes before linearized reading' (duration: 111.290496ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:19:30.572433Z","caller":"traceutil/trace.go:171","msg":"trace[1171205348] transaction","detail":"{read_only:false; response_revision:17447; number_of_response:1; }","duration":"106.784153ms","start":"2026-05-28T22:19:30.465589Z","end":"2026-05-28T22:19:30.572373Z","steps":["trace[1171205348] 'process raft request' (duration: 106.200692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:19:30.572627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.78637ms","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-05-28T22:19:30.572693Z","caller":"traceutil/trace.go:171","msg":"trace[1046734813] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17447; }","duration":"109.869901ms","start":"2026-05-28T22:19:30.462804Z","end":"2026-05-28T22:19:30.572674Z","steps":["trace[1046734813] 'agreement among raft nodes before linearized reading' (duration: 109.715098ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:19:30.573063Z","caller":"traceutil/trace.go:171","msg":"trace[773409820] transaction","detail":"{read_only:false; response_revision:17446; number_of_response:1; }","duration":"115.029197ms","start":"2026-05-28T22:19:30.458011Z","end":"2026-05-28T22:19:30.57304Z","steps":["trace[773409820] 'process raft request' (duration: 113.58613ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:20:17.052177Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15369} {"level":"info","ts":"2026-05-28T22:20:17.151107Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15369,"took":"95.274946ms","hash":3040631999,"current-db-size-bytes":58793984,"current-db-size":"59 MB","current-db-size-in-use-bytes":33062912,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-28T22:20:17.151164Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3040631999,"revision":15369,"compact-revision":12994} {"level":"info","ts":"2026-05-28T22:23:21.930069Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"de5c2ed1f829ccc5","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-28T22:23:21.938179Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-28T22:23:21.938286Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-28T22:24:49.990144Z","caller":"traceutil/trace.go:171","msg":"trace[407447389] transaction","detail":"{read_only:false; response_revision:19878; number_of_response:1; }","duration":"311.649112ms","start":"2026-05-28T22:24:49.678464Z","end":"2026-05-28T22:24:49.990113Z","steps":["trace[407447389] 'process raft request' (duration: 311.403798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:24:49.990336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:24:49.678429Z","time spent":"311.816495ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:24:50.183535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.052385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:24:50.183723Z","caller":"traceutil/trace.go:171","msg":"trace[162217986] linearizableReadLoop","detail":"{readStateIndex:20554; appliedIndex:20552; }","duration":"135.197337ms","start":"2026-05-28T22:24:50.048506Z","end":"2026-05-28T22:24:50.183703Z","steps":["trace[162217986] 'read index received' (duration: 11.797714ms)","trace[162217986] 'applied index is now lower than readState.Index' (duration: 123.398483ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:24:50.183823Z","caller":"traceutil/trace.go:171","msg":"trace[1582446136] transaction","detail":"{read_only:false; response_revision:19880; number_of_response:1; }","duration":"430.138243ms","start":"2026-05-28T22:24:49.753674Z","end":"2026-05-28T22:24:50.183812Z","steps":["trace[1582446136] 'process raft request' (duration: 429.96407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:24:50.183894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:24:49.753644Z","time spent":"430.212375ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:24:50.184019Z","caller":"traceutil/trace.go:171","msg":"trace[1362260996] transaction","detail":"{read_only:false; response_revision:19879; number_of_response:1; }","duration":"503.411658ms","start":"2026-05-28T22:24:49.680575Z","end":"2026-05-28T22:24:50.183987Z","steps":["trace[1362260996] 'process raft request' (duration: 379.813082ms)","trace[1362260996] 'compare' (duration: 122.916643ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:24:50.184145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.613454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:24:50.184322Z","caller":"traceutil/trace.go:171","msg":"trace[706212623] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:19880; }","duration":"135.856049ms","start":"2026-05-28T22:24:50.04845Z","end":"2026-05-28T22:24:50.184306Z","steps":["trace[706212623] 'agreement among raft nodes before linearized reading' (duration: 135.614175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:24:50.184168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:24:49.68056Z","time spent":"503.534981ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:24:50.488615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.008757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:24:50.488797Z","caller":"traceutil/trace.go:171","msg":"trace[604061251] transaction","detail":"{read_only:false; response_revision:19883; number_of_response:1; }","duration":"285.349795ms","start":"2026-05-28T22:24:50.20343Z","end":"2026-05-28T22:24:50.48878Z","steps":["trace[604061251] 'process raft request' (duration: 285.287594ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:24:50.488879Z","caller":"traceutil/trace.go:171","msg":"trace[1689581521] transaction","detail":"{read_only:false; response_revision:19882; number_of_response:1; }","duration":"296.955325ms","start":"2026-05-28T22:24:50.191879Z","end":"2026-05-28T22:24:50.488834Z","steps":["trace[1689581521] 'process raft request' (duration: 156.638172ms)","trace[1689581521] 'compare' (duration: 139.766162ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:25:17.063024Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17962} {"level":"info","ts":"2026-05-28T22:25:17.175266Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17962,"took":"108.486511ms","hash":2464920257,"current-db-size-bytes":58793984,"current-db-size":"59 MB","current-db-size-in-use-bytes":29700096,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-28T22:25:17.175341Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2464920257,"revision":17962,"compact-revision":15369} {"level":"warn","ts":"2026-05-28T22:28:26.152044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.861215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:28:26.152191Z","caller":"traceutil/trace.go:171","msg":"trace[792747520] linearizableReadLoop","detail":"{readStateIndex:22956; appliedIndex:22955; }","duration":"198.075045ms","start":"2026-05-28T22:28:25.954097Z","end":"2026-05-28T22:28:26.152172Z","steps":["trace[792747520] 'read index received' (duration: 38.938787ms)","trace[792747520] 'applied index is now lower than readState.Index' (duration: 159.134848ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:28:26.15227Z","caller":"traceutil/trace.go:171","msg":"trace[210638926] transaction","detail":"{read_only:false; response_revision:22212; number_of_response:1; }","duration":"242.79804ms","start":"2026-05-28T22:28:25.90946Z","end":"2026-05-28T22:28:26.152258Z","steps":["trace[210638926] 'process raft request' (duration: 83.562301ms)","trace[210638926] 'compare' (duration: 158.70109ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:28:26.152623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.504752ms","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-05-28T22:28:26.152767Z","caller":"traceutil/trace.go:171","msg":"trace[1886115576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22212; }","duration":"198.782758ms","start":"2026-05-28T22:28:25.953956Z","end":"2026-05-28T22:28:26.152739Z","steps":["trace[1886115576] 'agreement among raft nodes before linearized reading' (duration: 198.339369ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:28:26.231518Z","caller":"traceutil/trace.go:171","msg":"trace[2004655804] transaction","detail":"{read_only:false; response_revision:22213; number_of_response:1; }","duration":"266.869566ms","start":"2026-05-28T22:28:25.96463Z","end":"2026-05-28T22:28:26.2315Z","steps":["trace[2004655804] 'process raft request' (duration: 266.682023ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:28:26.315036Z","caller":"traceutil/trace.go:171","msg":"trace[713952013] transaction","detail":"{read_only:false; response_revision:22214; number_of_response:1; }","duration":"312.310185ms","start":"2026-05-28T22:28:26.002702Z","end":"2026-05-28T22:28:26.315012Z","steps":["trace[713952013] 'process raft request' (duration: 312.029619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:28:26.315229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:28:26.00268Z","time spent":"312.424398ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:28:26.315346Z","caller":"traceutil/trace.go:171","msg":"trace[1121646029] transaction","detail":"{read_only:false; response_revision:22215; number_of_response:1; }","duration":"157.975907ms","start":"2026-05-28T22:28:26.157337Z","end":"2026-05-28T22:28:26.315313Z","steps":["trace[1121646029] 'process raft request' (duration: 157.608179ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:28:26.315556Z","caller":"traceutil/trace.go:171","msg":"trace[1493590665] transaction","detail":"{read_only:false; response_revision:22216; number_of_response:1; }","duration":"150.632878ms","start":"2026-05-28T22:28:26.164912Z","end":"2026-05-28T22:28:26.315545Z","steps":["trace[1493590665] 'process raft request' (duration: 150.316272ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:28:26.315605Z","caller":"traceutil/trace.go:171","msg":"trace[1082927892] linearizableReadLoop","detail":"{readStateIndex:22958; appliedIndex:22956; }","duration":"163.350369ms","start":"2026-05-28T22:28:26.152217Z","end":"2026-05-28T22:28:26.315568Z","steps":["trace[1082927892] 'read index received' (duration: 79.101776ms)","trace[1082927892] 'applied index is now lower than readState.Index' (duration: 84.246183ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:28:26.316327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.523105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" ","response":"range_response_count:1 size:623"} {"level":"info","ts":"2026-05-28T22:28:26.316397Z","caller":"traceutil/trace.go:171","msg":"trace[247614642] range","detail":"{range_begin:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; range_end:; response_count:1; response_revision:22217; }","duration":"361.76893ms","start":"2026-05-28T22:28:25.954614Z","end":"2026-05-28T22:28:26.316383Z","steps":["trace[247614642] 'agreement among raft nodes before linearized reading' (duration: 361.115268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:28:26.316447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:28:25.954603Z","time spent":"361.834462ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":646,"request content":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" "} {"level":"info","ts":"2026-05-28T22:30:17.067794Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20140} {"level":"info","ts":"2026-05-28T22:30:17.171965Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20140,"took":"101.270995ms","hash":952315589,"current-db-size-bytes":58793984,"current-db-size":"59 MB","current-db-size-in-use-bytes":42205184,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-05-28T22:30:17.172039Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":952315589,"revision":20140,"compact-revision":17962} {"level":"info","ts":"2026-05-28T22:32:00.185576Z","caller":"traceutil/trace.go:171","msg":"trace[511817523] transaction","detail":"{read_only:false; response_revision:24381; number_of_response:1; }","duration":"116.399733ms","start":"2026-05-28T22:32:00.069145Z","end":"2026-05-28T22:32:00.185544Z","steps":["trace[511817523] 'process raft request' (duration: 116.216118ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:01.15741Z","caller":"traceutil/trace.go:171","msg":"trace[95169480] transaction","detail":"{read_only:false; response_revision:24389; number_of_response:1; }","duration":"119.360339ms","start":"2026-05-28T22:32:01.038015Z","end":"2026-05-28T22:32:01.157375Z","steps":["trace[95169480] 'process raft request' (duration: 119.179085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:01.442865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.219066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:32:01.442977Z","caller":"traceutil/trace.go:171","msg":"trace[945824797] linearizableReadLoop","detail":"{readStateIndex:25194; appliedIndex:25193; }","duration":"167.460094ms","start":"2026-05-28T22:32:01.2755Z","end":"2026-05-28T22:32:01.442961Z","steps":["trace[945824797] 'read index received' (duration: 48.044664ms)","trace[945824797] 'applied index is now lower than readState.Index' (duration: 119.4141ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:01.443061Z","caller":"traceutil/trace.go:171","msg":"trace[541819036] transaction","detail":"{read_only:false; response_revision:24390; number_of_response:1; }","duration":"189.334425ms","start":"2026-05-28T22:32:01.253706Z","end":"2026-05-28T22:32:01.44304Z","steps":["trace[541819036] 'process raft request' (duration: 69.850544ms)","trace[541819036] 'compare' (duration: 118.732157ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:01.443129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.626097ms","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-05-28T22:32:01.443165Z","caller":"traceutil/trace.go:171","msg":"trace[756775496] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24390; }","duration":"167.690448ms","start":"2026-05-28T22:32:01.275461Z","end":"2026-05-28T22:32:01.443151Z","steps":["trace[756775496] 'agreement among raft nodes before linearized reading' (duration: 167.567846ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:01.75019Z","caller":"traceutil/trace.go:171","msg":"trace[330147213] linearizableReadLoop","detail":"{readStateIndex:25195; appliedIndex:25194; }","duration":"307.140603ms","start":"2026-05-28T22:32:01.443004Z","end":"2026-05-28T22:32:01.750145Z","steps":["trace[330147213] 'read index received' (duration: 268.053307ms)","trace[330147213] 'applied index is now lower than readState.Index' (duration: 39.085066ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:01.75022Z","caller":"traceutil/trace.go:171","msg":"trace[886049931] transaction","detail":"{read_only:false; response_revision:24391; number_of_response:1; }","duration":"403.715653ms","start":"2026-05-28T22:32:01.346461Z","end":"2026-05-28T22:32:01.750176Z","steps":["trace[886049931] 'process raft request' (duration: 364.500604ms)","trace[886049931] 'compare' (duration: 38.865182ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:01.750308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.443387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-28T22:32:01.750382Z","caller":"traceutil/trace.go:171","msg":"trace[1789119218] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:24391; }","duration":"360.57557ms","start":"2026-05-28T22:32:01.389786Z","end":"2026-05-28T22:32:01.750361Z","steps":["trace[1789119218] 'agreement among raft nodes before linearized reading' (duration: 360.413067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:01.750386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:01.346436Z","time spent":"403.867805ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:01.750428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:01.389767Z","time spent":"360.648871ms","remote":"127.0.0.1:35508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-28T22:32:01.750518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.184932ms","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-05-28T22:32:01.750574Z","caller":"traceutil/trace.go:171","msg":"trace[1648425648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24391; }","duration":"437.286964ms","start":"2026-05-28T22:32:01.313269Z","end":"2026-05-28T22:32:01.750556Z","steps":["trace[1648425648] 'agreement among raft nodes before linearized reading' (duration: 437.05298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:01.750684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:01.313253Z","time spent":"437.418917ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-28T22:32:01.762581Z","caller":"traceutil/trace.go:171","msg":"trace[2057978093] transaction","detail":"{read_only:false; response_revision:24392; number_of_response:1; }","duration":"314.195627ms","start":"2026-05-28T22:32:01.448366Z","end":"2026-05-28T22:32:01.762562Z","steps":["trace[2057978093] 'process raft request' (duration: 313.872201ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:01.762641Z","caller":"traceutil/trace.go:171","msg":"trace[2085763199] transaction","detail":"{read_only:false; response_revision:24393; number_of_response:1; }","duration":"283.706893ms","start":"2026-05-28T22:32:01.47887Z","end":"2026-05-28T22:32:01.762577Z","steps":["trace[2085763199] 'process raft request' (duration: 283.610341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:01.762708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:01.448341Z","time spent":"314.292758ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:01.762909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.019446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:01.76299Z","caller":"traceutil/trace.go:171","msg":"trace[556187428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24394; }","duration":"218.172188ms","start":"2026-05-28T22:32:01.544798Z","end":"2026-05-28T22:32:01.76297Z","steps":["trace[556187428] 'agreement among raft nodes before linearized reading' (duration: 218.027576ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:01.997611Z","caller":"traceutil/trace.go:171","msg":"trace[251042601] transaction","detail":"{read_only:false; response_revision:24396; number_of_response:1; }","duration":"176.769979ms","start":"2026-05-28T22:32:01.820694Z","end":"2026-05-28T22:32:01.997464Z","steps":["trace[251042601] 'process raft request' (duration: 129.162713ms)","trace[251042601] 'compare' (duration: 47.243629ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:02.326473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.193315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-28T22:32:02.326551Z","caller":"traceutil/trace.go:171","msg":"trace[369998629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24396; }","duration":"136.321188ms","start":"2026-05-28T22:32:02.190213Z","end":"2026-05-28T22:32:02.326534Z","steps":["trace[369998629] 'range keys from in-memory index tree' (duration: 135.942119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:02.326742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.68143ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:02.326863Z","caller":"traceutil/trace.go:171","msg":"trace[1055890365] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:24396; }","duration":"140.803082ms","start":"2026-05-28T22:32:02.186051Z","end":"2026-05-28T22:32:02.326854Z","steps":["trace[1055890365] 'range keys from in-memory index tree' (duration: 140.67416ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:02.580895Z","caller":"traceutil/trace.go:171","msg":"trace[856870236] transaction","detail":"{read_only:false; response_revision:24397; number_of_response:1; }","duration":"248.486829ms","start":"2026-05-28T22:32:02.332373Z","end":"2026-05-28T22:32:02.58086Z","steps":["trace[856870236] 'process raft request' (duration: 248.254585ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:02.65986Z","caller":"traceutil/trace.go:171","msg":"trace[1025347569] linearizableReadLoop","detail":"{readStateIndex:25202; appliedIndex:25200; }","duration":"309.690521ms","start":"2026-05-28T22:32:02.350138Z","end":"2026-05-28T22:32:02.659828Z","steps":["trace[1025347569] 'read index received' (duration: 230.676763ms)","trace[1025347569] 'applied index is now lower than readState.Index' (duration: 79.012678ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:02.659874Z","caller":"traceutil/trace.go:171","msg":"trace[1337748834] transaction","detail":"{read_only:false; response_revision:24399; number_of_response:1; }","duration":"304.670087ms","start":"2026-05-28T22:32:02.35517Z","end":"2026-05-28T22:32:02.65984Z","steps":["trace[1337748834] 'process raft request' (duration: 304.354102ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:02.659874Z","caller":"traceutil/trace.go:171","msg":"trace[1263652625] transaction","detail":"{read_only:false; response_revision:24400; number_of_response:1; }","duration":"302.044528ms","start":"2026-05-28T22:32:02.357801Z","end":"2026-05-28T22:32:02.659845Z","steps":["trace[1263652625] 'process raft request' (duration: 301.906916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:02.660083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:02.357787Z","time spent":"302.214401ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:02.660098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.943937ms","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-05-28T22:32:02.660144Z","caller":"traceutil/trace.go:171","msg":"trace[255985306] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24400; }","duration":"310.038888ms","start":"2026-05-28T22:32:02.35009Z","end":"2026-05-28T22:32:02.660129Z","steps":["trace[255985306] 'agreement among raft nodes before linearized reading' (duration: 309.871345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:02.660176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:02.350073Z","time spent":"310.096109ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-28T22:32:02.660193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:02.355152Z","time spent":"304.808191ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:02.660246Z","caller":"traceutil/trace.go:171","msg":"trace[1300996981] transaction","detail":"{read_only:false; response_revision:24398; number_of_response:1; }","duration":"326.324005ms","start":"2026-05-28T22:32:02.333251Z","end":"2026-05-28T22:32:02.659575Z","steps":["trace[1300996981] 'process raft request' (duration: 324.775715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:02.660351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.094478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:02.660433Z","caller":"traceutil/trace.go:171","msg":"trace[911715893] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:24400; }","duration":"260.22173ms","start":"2026-05-28T22:32:02.400189Z","end":"2026-05-28T22:32:02.660411Z","steps":["trace[911715893] 'agreement among raft nodes before linearized reading' (duration: 260.098827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:02.660439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:02.333216Z","time spent":"327.127079ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:02.660523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.016416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:02.660572Z","caller":"traceutil/trace.go:171","msg":"trace[1138504681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24400; }","duration":"115.098907ms","start":"2026-05-28T22:32:02.545458Z","end":"2026-05-28T22:32:02.660557Z","steps":["trace[1138504681] 'agreement among raft nodes before linearized reading' (duration: 115.032046ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:02.926961Z","caller":"traceutil/trace.go:171","msg":"trace[800625699] transaction","detail":"{read_only:false; response_revision:24402; number_of_response:1; }","duration":"174.106329ms","start":"2026-05-28T22:32:02.752835Z","end":"2026-05-28T22:32:02.926941Z","steps":["trace[800625699] 'process raft request' (duration: 166.256821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:03.227489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.376003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-28T22:32:03.227585Z","caller":"traceutil/trace.go:171","msg":"trace[321207399] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:24402; }","duration":"174.537346ms","start":"2026-05-28T22:32:03.053025Z","end":"2026-05-28T22:32:03.227562Z","steps":["trace[321207399] 'count revisions from in-memory index tree' (duration: 174.277671ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:03.42602Z","caller":"traceutil/trace.go:171","msg":"trace[1276191031] transaction","detail":"{read_only:false; response_revision:24403; number_of_response:1; }","duration":"262.313389ms","start":"2026-05-28T22:32:03.163681Z","end":"2026-05-28T22:32:03.425995Z","steps":["trace[1276191031] 'process raft request' (duration: 262.063015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:03.610656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.56013ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:32:03.61079Z","caller":"traceutil/trace.go:171","msg":"trace[1992126616] transaction","detail":"{read_only:false; response_revision:24404; number_of_response:1; }","duration":"162.647942ms","start":"2026-05-28T22:32:03.448095Z","end":"2026-05-28T22:32:03.610743Z","steps":["trace[1992126616] 'process raft request' (duration: 24.93504ms)","trace[1992126616] 'compare' (duration: 137.398377ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:03.904354Z","caller":"traceutil/trace.go:171","msg":"trace[885772066] transaction","detail":"{read_only:false; response_revision:24405; number_of_response:1; }","duration":"148.460276ms","start":"2026-05-28T22:32:03.755862Z","end":"2026-05-28T22:32:03.904322Z","steps":["trace[885772066] 'process raft request' (duration: 141.351333ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:04.096809Z","caller":"traceutil/trace.go:171","msg":"trace[1653622837] linearizableReadLoop","detail":"{readStateIndex:25210; appliedIndex:25208; }","duration":"329.365852ms","start":"2026-05-28T22:32:03.767417Z","end":"2026-05-28T22:32:04.096783Z","steps":["trace[1653622837] 'read index received' (duration: 129.769464ms)","trace[1653622837] 'applied index is now lower than readState.Index' (duration: 199.595028ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:04.09684Z","caller":"traceutil/trace.go:171","msg":"trace[1277990880] transaction","detail":"{read_only:false; response_revision:24407; number_of_response:1; }","duration":"328.916794ms","start":"2026-05-28T22:32:03.767889Z","end":"2026-05-28T22:32:04.096806Z","steps":["trace[1277990880] 'process raft request' (duration: 328.835063ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:04.096864Z","caller":"traceutil/trace.go:171","msg":"trace[940615926] transaction","detail":"{read_only:false; response_revision:24406; number_of_response:1; }","duration":"329.662917ms","start":"2026-05-28T22:32:03.76716Z","end":"2026-05-28T22:32:04.096823Z","steps":["trace[940615926] 'process raft request' (duration: 269.25478ms)","trace[940615926] 'compare' (duration: 60.154613ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:04.097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:03.767869Z","time spent":"329.040536ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:04.097074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.648477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-28T22:32:04.097121Z","caller":"traceutil/trace.go:171","msg":"trace[329720194] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24407; }","duration":"329.77663ms","start":"2026-05-28T22:32:03.76733Z","end":"2026-05-28T22:32:04.097107Z","steps":["trace[329720194] 'agreement among raft nodes before linearized reading' (duration: 329.609967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:04.097169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:03.767282Z","time spent":"329.873681ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-28T22:32:04.09715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.420446ms","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-05-28T22:32:04.097225Z","caller":"traceutil/trace.go:171","msg":"trace[1001123176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24407; }","duration":"275.553648ms","start":"2026-05-28T22:32:03.821654Z","end":"2026-05-28T22:32:04.097208Z","steps":["trace[1001123176] 'agreement among raft nodes before linearized reading' (duration: 275.364535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:04.097432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:03.767147Z","time spent":"330.127596ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:04.245062Z","caller":"traceutil/trace.go:171","msg":"trace[775399574] transaction","detail":"{read_only:false; response_revision:24408; number_of_response:1; }","duration":"142.124296ms","start":"2026-05-28T22:32:04.102911Z","end":"2026-05-28T22:32:04.245036Z","steps":["trace[775399574] 'process raft request' (duration: 141.936463ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:04.257839Z","caller":"traceutil/trace.go:171","msg":"trace[1376674878] transaction","detail":"{read_only:false; response_revision:24409; number_of_response:1; }","duration":"154.56866ms","start":"2026-05-28T22:32:04.103244Z","end":"2026-05-28T22:32:04.257813Z","steps":["trace[1376674878] 'process raft request' (duration: 154.405817ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:04.7778Z","caller":"traceutil/trace.go:171","msg":"trace[1828155460] transaction","detail":"{read_only:false; response_revision:24412; number_of_response:1; }","duration":"112.665611ms","start":"2026-05-28T22:32:04.665102Z","end":"2026-05-28T22:32:04.777767Z","steps":["trace[1828155460] 'process raft request' (duration: 99.211518ms)","trace[1828155460] 'compare' (duration: 13.338891ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:04.873021Z","caller":"traceutil/trace.go:171","msg":"trace[1597441003] linearizableReadLoop","detail":"{readStateIndex:25217; appliedIndex:25215; }","duration":"204.775366ms","start":"2026-05-28T22:32:04.66822Z","end":"2026-05-28T22:32:04.872996Z","steps":["trace[1597441003] 'read index received' (duration: 96.09671ms)","trace[1597441003] 'applied index is now lower than readState.Index' (duration: 108.677376ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:04.873068Z","caller":"traceutil/trace.go:171","msg":"trace[1686193100] transaction","detail":"{read_only:false; response_revision:24414; number_of_response:1; }","duration":"204.692065ms","start":"2026-05-28T22:32:04.668338Z","end":"2026-05-28T22:32:04.87303Z","steps":["trace[1686193100] 'process raft request' (duration: 204.532691ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:04.873089Z","caller":"traceutil/trace.go:171","msg":"trace[284329643] transaction","detail":"{read_only:false; response_revision:24413; number_of_response:1; }","duration":"206.811245ms","start":"2026-05-28T22:32:04.666252Z","end":"2026-05-28T22:32:04.873063Z","steps":["trace[284329643] 'process raft request' (duration: 206.471967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:04.873398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.38503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-28T22:32:04.87344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.344498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-28T22:32:04.873541Z","caller":"traceutil/trace.go:171","msg":"trace[967230707] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24415; }","duration":"152.542232ms","start":"2026-05-28T22:32:04.720957Z","end":"2026-05-28T22:32:04.8735Z","steps":["trace[967230707] 'agreement among raft nodes before linearized reading' (duration: 152.300068ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:04.873248Z","caller":"traceutil/trace.go:171","msg":"trace[1111217078] transaction","detail":"{read_only:false; response_revision:24415; number_of_response:1; }","duration":"162.476619ms","start":"2026-05-28T22:32:04.710676Z","end":"2026-05-28T22:32:04.873152Z","steps":["trace[1111217078] 'process raft request' (duration: 162.270004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:04.8733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.053302ms","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-05-28T22:32:04.873743Z","caller":"traceutil/trace.go:171","msg":"trace[312371595] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24415; }","duration":"205.528891ms","start":"2026-05-28T22:32:04.668202Z","end":"2026-05-28T22:32:04.873731Z","steps":["trace[312371595] 'agreement among raft nodes before linearized reading' (duration: 204.92421ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:04.873523Z","caller":"traceutil/trace.go:171","msg":"trace[442167458] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:24415; }","duration":"111.46874ms","start":"2026-05-28T22:32:04.762043Z","end":"2026-05-28T22:32:04.873512Z","steps":["trace[442167458] 'agreement among raft nodes before linearized reading' (duration: 111.264756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:05.829851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.720008ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:32:05.830127Z","caller":"traceutil/trace.go:171","msg":"trace[496255181] transaction","detail":"{read_only:false; response_revision:24419; number_of_response:1; }","duration":"210.470373ms","start":"2026-05-28T22:32:05.619612Z","end":"2026-05-28T22:32:05.830082Z","steps":["trace[496255181] 'process raft request' (duration: 59.422929ms)","trace[496255181] 'compare' (duration: 150.401493ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:06.309722Z","caller":"traceutil/trace.go:171","msg":"trace[150196483] transaction","detail":"{read_only:false; response_revision:24421; number_of_response:1; }","duration":"207.894835ms","start":"2026-05-28T22:32:06.101802Z","end":"2026-05-28T22:32:06.309697Z","steps":["trace[150196483] 'process raft request' (duration: 198.02777ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:06.31251Z","caller":"traceutil/trace.go:171","msg":"trace[196389158] transaction","detail":"{read_only:false; response_revision:24422; number_of_response:1; }","duration":"209.623678ms","start":"2026-05-28T22:32:06.102853Z","end":"2026-05-28T22:32:06.312477Z","steps":["trace[196389158] 'process raft request' (duration: 209.502535ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:06.495497Z","caller":"traceutil/trace.go:171","msg":"trace[1979452028] transaction","detail":"{read_only:false; response_revision:24423; number_of_response:1; }","duration":"178.383089ms","start":"2026-05-28T22:32:06.317083Z","end":"2026-05-28T22:32:06.495466Z","steps":["trace[1979452028] 'process raft request' (duration: 178.212526ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:06.508979Z","caller":"traceutil/trace.go:171","msg":"trace[201849838] linearizableReadLoop","detail":"{readStateIndex:25229; appliedIndex:25228; }","duration":"151.646675ms","start":"2026-05-28T22:32:06.357311Z","end":"2026-05-28T22:32:06.508958Z","steps":["trace[201849838] 'read index received' (duration: 138.874705ms)","trace[201849838] 'applied index is now lower than readState.Index' (duration: 12.77073ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:06.509073Z","caller":"traceutil/trace.go:171","msg":"trace[1347228834] transaction","detail":"{read_only:false; response_revision:24424; number_of_response:1; }","duration":"185.079475ms","start":"2026-05-28T22:32:06.323977Z","end":"2026-05-28T22:32:06.509057Z","steps":["trace[1347228834] 'process raft request' (duration: 184.863431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:06.509173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.83288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:06.509239Z","caller":"traceutil/trace.go:171","msg":"trace[1703027380] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:24424; }","duration":"151.943281ms","start":"2026-05-28T22:32:06.357279Z","end":"2026-05-28T22:32:06.509222Z","steps":["trace[1703027380] 'agreement among raft nodes before linearized reading' (duration: 151.795589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:06.966244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.375555ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:32:06.966518Z","caller":"traceutil/trace.go:171","msg":"trace[1179177949] transaction","detail":"{read_only:false; response_revision:24426; number_of_response:1; }","duration":"184.691848ms","start":"2026-05-28T22:32:06.781803Z","end":"2026-05-28T22:32:06.966495Z","steps":["trace[1179177949] 'process raft request' (duration: 62.947135ms)","trace[1179177949] 'compare' (duration: 120.930898ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:07.29551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.015805ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:32:07.295678Z","caller":"traceutil/trace.go:171","msg":"trace[216358163] transaction","detail":"{read_only:false; response_revision:24428; number_of_response:1; }","duration":"322.645347ms","start":"2026-05-28T22:32:06.973013Z","end":"2026-05-28T22:32:07.295659Z","steps":["trace[216358163] 'process raft request' (duration: 322.588696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:07.295738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:06.972991Z","time spent":"322.716887ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:07.295865Z","caller":"traceutil/trace.go:171","msg":"trace[1669807527] linearizableReadLoop","detail":"{readStateIndex:25232; appliedIndex:25231; }","duration":"329.488224ms","start":"2026-05-28T22:32:06.966342Z","end":"2026-05-28T22:32:07.29583Z","steps":["trace[1669807527] 'read index received' (duration: 127.648053ms)","trace[1669807527] 'applied index is now lower than readState.Index' (duration: 201.836571ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:07.296023Z","caller":"traceutil/trace.go:171","msg":"trace[1542843640] transaction","detail":"{read_only:false; response_revision:24427; number_of_response:1; }","duration":"416.892781ms","start":"2026-05-28T22:32:06.879111Z","end":"2026-05-28T22:32:07.296004Z","steps":["trace[1542843640] 'process raft request' (duration: 214.926268ms)","trace[1542843640] 'compare' (duration: 200.904573ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:07.296124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.700914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:07.296161Z","caller":"traceutil/trace.go:171","msg":"trace[2064670758] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:24428; }","duration":"325.758544ms","start":"2026-05-28T22:32:06.97039Z","end":"2026-05-28T22:32:07.296148Z","steps":["trace[2064670758] 'agreement among raft nodes before linearized reading' (duration: 325.698613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:07.296164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:06.879082Z","time spent":"416.976743ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:07.29619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:06.970378Z","time spent":"325.803605ms","remote":"127.0.0.1:34720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true "} {"level":"warn","ts":"2026-05-28T22:32:07.29639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.840702ms","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-05-28T22:32:07.29642Z","caller":"traceutil/trace.go:171","msg":"trace[569301207] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24428; }","duration":"354.888702ms","start":"2026-05-28T22:32:06.941521Z","end":"2026-05-28T22:32:07.296409Z","steps":["trace[569301207] 'agreement among raft nodes before linearized reading' (duration: 354.794311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:07.296442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:06.941509Z","time spent":"354.927393ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-28T22:32:07.296613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.522062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-28T22:32:07.296639Z","caller":"traceutil/trace.go:171","msg":"trace[1937490120] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24428; }","duration":"169.588393ms","start":"2026-05-28T22:32:07.127043Z","end":"2026-05-28T22:32:07.296632Z","steps":["trace[1937490120] 'agreement among raft nodes before linearized reading' (duration: 169.512822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:07.296863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.17277ms","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-05-28T22:32:07.296915Z","caller":"traceutil/trace.go:171","msg":"trace[1452283362] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24428; }","duration":"269.261441ms","start":"2026-05-28T22:32:07.027638Z","end":"2026-05-28T22:32:07.296899Z","steps":["trace[1452283362] 'agreement among raft nodes before linearized reading' (duration: 269.067408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:07.297046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.11959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:07.297083Z","caller":"traceutil/trace.go:171","msg":"trace[317139158] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:24428; }","duration":"207.186021ms","start":"2026-05-28T22:32:07.089885Z","end":"2026-05-28T22:32:07.297071Z","steps":["trace[317139158] 'agreement among raft nodes before linearized reading' (duration: 207.12407ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:07.754057Z","caller":"traceutil/trace.go:171","msg":"trace[978915947] transaction","detail":"{read_only:false; response_revision:24429; number_of_response:1; }","duration":"453.147583ms","start":"2026-05-28T22:32:07.300882Z","end":"2026-05-28T22:32:07.75403Z","steps":["trace[978915947] 'process raft request' (duration: 452.935869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:07.754212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:07.300861Z","time spent":"453.270454ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:08.036592Z","caller":"traceutil/trace.go:171","msg":"trace[1143678012] linearizableReadLoop","detail":"{readStateIndex:25236; appliedIndex:25233; }","duration":"544.834789ms","start":"2026-05-28T22:32:07.49174Z","end":"2026-05-28T22:32:08.036575Z","steps":["trace[1143678012] 'read index received' (duration: 261.739789ms)","trace[1143678012] 'applied index is now lower than readState.Index' (duration: 283.09441ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:08.036668Z","caller":"traceutil/trace.go:171","msg":"trace[1941682101] transaction","detail":"{read_only:false; response_revision:24431; number_of_response:1; }","duration":"734.539652ms","start":"2026-05-28T22:32:07.302122Z","end":"2026-05-28T22:32:08.036662Z","steps":["trace[1941682101] 'process raft request' (duration: 734.403959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:08.036736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:07.302103Z","time spent":"734.578703ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:08.036758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.989362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:08.036599Z","caller":"traceutil/trace.go:171","msg":"trace[1891007082] transaction","detail":"{read_only:false; response_revision:24430; number_of_response:1; }","duration":"734.559222ms","start":"2026-05-28T22:32:07.302026Z","end":"2026-05-28T22:32:08.036585Z","steps":["trace[1891007082] 'process raft request' (duration: 729.396385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:08.036881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:07.30201Z","time spent":"734.855138ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:08.036882Z","caller":"traceutil/trace.go:171","msg":"trace[994371569] transaction","detail":"{read_only:false; response_revision:24432; number_of_response:1; }","duration":"510.464582ms","start":"2026-05-28T22:32:07.526406Z","end":"2026-05-28T22:32:08.036871Z","steps":["trace[994371569] 'process raft request' (duration: 510.144476ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:08.036837Z","caller":"traceutil/trace.go:171","msg":"trace[1389294148] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:24432; }","duration":"545.087374ms","start":"2026-05-28T22:32:07.491732Z","end":"2026-05-28T22:32:08.03682Z","steps":["trace[1389294148] 'agreement among raft nodes before linearized reading' (duration: 544.936351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:08.03698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:07.491664Z","time spent":"545.307797ms","remote":"127.0.0.1:58500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"warn","ts":"2026-05-28T22:32:08.036973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:07.526384Z","time spent":"510.534493ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:08.194225Z","caller":"traceutil/trace.go:171","msg":"trace[526088542] linearizableReadLoop","detail":"{readStateIndex:25239; appliedIndex:25237; }","duration":"157.560338ms","start":"2026-05-28T22:32:08.036639Z","end":"2026-05-28T22:32:08.194199Z","steps":["trace[526088542] 'read index received' (duration: 132.611778ms)","trace[526088542] 'applied index is now lower than readState.Index' (duration: 24.9471ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:08.194386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.302228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:08.194407Z","caller":"traceutil/trace.go:171","msg":"trace[117168408] transaction","detail":"{read_only:false; response_revision:24433; number_of_response:1; }","duration":"360.191314ms","start":"2026-05-28T22:32:07.83418Z","end":"2026-05-28T22:32:08.194371Z","steps":["trace[117168408] 'process raft request' (duration: 335.155502ms)","trace[117168408] 'compare' (duration: 24.658234ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:08.194558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.421844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-28T22:32:08.194604Z","caller":"traceutil/trace.go:171","msg":"trace[925656575] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:24434; }","duration":"226.504805ms","start":"2026-05-28T22:32:07.968088Z","end":"2026-05-28T22:32:08.194593Z","steps":["trace[925656575] 'agreement among raft nodes before linearized reading' (duration: 226.375233ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:08.194526Z","caller":"traceutil/trace.go:171","msg":"trace[689694663] transaction","detail":"{read_only:false; response_revision:24434; number_of_response:1; }","duration":"189.463458ms","start":"2026-05-28T22:32:08.004987Z","end":"2026-05-28T22:32:08.19445Z","steps":["trace[689694663] 'process raft request' (duration: 189.145972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:08.194605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:07.834154Z","time spent":"360.331586ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:08.194441Z","caller":"traceutil/trace.go:171","msg":"trace[1044451498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24434; }","duration":"648.39755ms","start":"2026-05-28T22:32:07.546029Z","end":"2026-05-28T22:32:08.194426Z","steps":["trace[1044451498] 'agreement among raft nodes before linearized reading' (duration: 648.270178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:08.195012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:07.546018Z","time spent":"648.96507ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-28T22:32:08.503728Z","caller":"traceutil/trace.go:171","msg":"trace[80767940] transaction","detail":"{read_only:false; response_revision:24435; number_of_response:1; }","duration":"188.474889ms","start":"2026-05-28T22:32:08.31523Z","end":"2026-05-28T22:32:08.503705Z","steps":["trace[80767940] 'process raft request' (duration: 188.208753ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:08.504569Z","caller":"traceutil/trace.go:171","msg":"trace[1583628359] transaction","detail":"{read_only:false; response_revision:24436; number_of_response:1; }","duration":"186.547162ms","start":"2026-05-28T22:32:08.317999Z","end":"2026-05-28T22:32:08.504546Z","steps":["trace[1583628359] 'process raft request' (duration: 186.333998ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:08.702813Z","caller":"traceutil/trace.go:171","msg":"trace[1107629540] transaction","detail":"{read_only:false; response_revision:24438; number_of_response:1; }","duration":"137.977959ms","start":"2026-05-28T22:32:08.564805Z","end":"2026-05-28T22:32:08.702783Z","steps":["trace[1107629540] 'process raft request' (duration: 90.166948ms)","trace[1107629540] 'compare' (duration: 47.563986ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:13.225665Z","caller":"traceutil/trace.go:171","msg":"trace[827232206] transaction","detail":"{read_only:false; response_revision:24471; number_of_response:1; }","duration":"239.53049ms","start":"2026-05-28T22:32:12.986105Z","end":"2026-05-28T22:32:13.225636Z","steps":["trace[827232206] 'process raft request' (duration: 239.352067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:13.787157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.009277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-28T22:32:13.787241Z","caller":"traceutil/trace.go:171","msg":"trace[185265393] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:24471; }","duration":"499.147669ms","start":"2026-05-28T22:32:13.288075Z","end":"2026-05-28T22:32:13.787223Z","steps":["trace[185265393] 'count revisions from in-memory index tree' (duration: 498.927985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:13.7873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:13.28806Z","time spent":"499.21306ms","remote":"127.0.0.1:35380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":7,"response size":31,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-05-28T22:32:13.787737Z","caller":"traceutil/trace.go:171","msg":"trace[1278609212] linearizableReadLoop","detail":"{readStateIndex:25278; appliedIndex:25277; }","duration":"463.012609ms","start":"2026-05-28T22:32:13.324705Z","end":"2026-05-28T22:32:13.787718Z","steps":["trace[1278609212] 'read index received' (duration: 461.469419ms)","trace[1278609212] 'applied index is now lower than readState.Index' (duration: 1.54272ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:13.787914Z","caller":"traceutil/trace.go:171","msg":"trace[890836454] transaction","detail":"{read_only:false; response_revision:24472; number_of_response:1; }","duration":"463.953717ms","start":"2026-05-28T22:32:13.323954Z","end":"2026-05-28T22:32:13.787908Z","steps":["trace[890836454] 'process raft request' (duration: 462.225273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:13.787981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:13.323933Z","time spent":"463.999688ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:13.788109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.411606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-28T22:32:13.788126Z","caller":"traceutil/trace.go:171","msg":"trace[2088342468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24472; }","duration":"463.486808ms","start":"2026-05-28T22:32:13.324635Z","end":"2026-05-28T22:32:13.788122Z","steps":["trace[2088342468] 'agreement among raft nodes before linearized reading' (duration: 463.419906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:13.788138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:13.324622Z","time spent":"463.513438ms","remote":"127.0.0.1:35402","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-05-28T22:32:13.788205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.538777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:13.788221Z","caller":"traceutil/trace.go:171","msg":"trace[1881591770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24472; }","duration":"242.594487ms","start":"2026-05-28T22:32:13.545623Z","end":"2026-05-28T22:32:13.788217Z","steps":["trace[1881591770] 'agreement among raft nodes before linearized reading' (duration: 242.571327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:13.788475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.902211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1987"} {"level":"info","ts":"2026-05-28T22:32:13.788686Z","caller":"traceutil/trace.go:171","msg":"trace[181366482] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:24472; }","duration":"169.158626ms","start":"2026-05-28T22:32:13.61952Z","end":"2026-05-28T22:32:13.788679Z","steps":["trace[181366482] 'agreement among raft nodes before linearized reading' (duration: 168.873311ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:14.11325Z","caller":"traceutil/trace.go:171","msg":"trace[2063287770] linearizableReadLoop","detail":"{readStateIndex:25282; appliedIndex:25279; }","duration":"131.813492ms","start":"2026-05-28T22:32:13.981397Z","end":"2026-05-28T22:32:14.11321Z","steps":["trace[2063287770] 'read index received' (duration: 41.42161ms)","trace[2063287770] 'applied index is now lower than readState.Index' (duration: 90.390502ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:14.113361Z","caller":"traceutil/trace.go:171","msg":"trace[204745263] transaction","detail":"{read_only:false; response_revision:24476; number_of_response:1; }","duration":"308.500429ms","start":"2026-05-28T22:32:13.804849Z","end":"2026-05-28T22:32:14.11335Z","steps":["trace[204745263] 'process raft request' (duration: 308.321225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:14.113463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:13.804834Z","time spent":"308.55456ms","remote":"127.0.0.1:35436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":869,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:14.113464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.066596ms","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-05-28T22:32:14.113506Z","caller":"traceutil/trace.go:171","msg":"trace[2052669513] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:24476; }","duration":"132.160838ms","start":"2026-05-28T22:32:13.981335Z","end":"2026-05-28T22:32:14.113496Z","steps":["trace[2052669513] 'agreement among raft nodes before linearized reading' (duration: 132.064617ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:14.113208Z","caller":"traceutil/trace.go:171","msg":"trace[713043076] transaction","detail":"{read_only:false; response_revision:24474; number_of_response:1; }","duration":"320.352252ms","start":"2026-05-28T22:32:13.79282Z","end":"2026-05-28T22:32:14.113172Z","steps":["trace[713043076] 'process raft request' (duration: 229.97924ms)","trace[713043076] 'compare' (duration: 90.166449ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:14.114014Z","caller":"traceutil/trace.go:171","msg":"trace[384612982] transaction","detail":"{read_only:false; response_revision:24475; number_of_response:1; }","duration":"318.556829ms","start":"2026-05-28T22:32:13.795437Z","end":"2026-05-28T22:32:14.113994Z","steps":["trace[384612982] 'process raft request' (duration: 317.662752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:14.114125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:13.795421Z","time spent":"318.647211ms","remote":"127.0.0.1:35436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":924,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:14.114159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:13.792795Z","time spent":"321.101347ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:14.385957Z","caller":"traceutil/trace.go:171","msg":"trace[96563191] linearizableReadLoop","detail":"{readStateIndex:25283; appliedIndex:25282; }","duration":"272.610334ms","start":"2026-05-28T22:32:14.113318Z","end":"2026-05-28T22:32:14.385929Z","steps":["trace[96563191] 'read index received' (duration: 271.255778ms)","trace[96563191] 'applied index is now lower than readState.Index' (duration: 1.352716ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:14.386089Z","caller":"traceutil/trace.go:171","msg":"trace[1235810211] transaction","detail":"{read_only:false; response_revision:24477; number_of_response:1; }","duration":"326.951547ms","start":"2026-05-28T22:32:14.059095Z","end":"2026-05-28T22:32:14.386047Z","steps":["trace[1235810211] 'process raft request' (duration: 325.50108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:14.386207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.60719ms","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-05-28T22:32:14.386252Z","caller":"traceutil/trace.go:171","msg":"trace[1715702943] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24477; }","duration":"326.690011ms","start":"2026-05-28T22:32:14.059549Z","end":"2026-05-28T22:32:14.386239Z","steps":["trace[1715702943] 'agreement among raft nodes before linearized reading' (duration: 326.510708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:14.386279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:14.059067Z","time spent":"327.10575ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:14.386292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:14.059539Z","time spent":"326.744602ms","remote":"127.0.0.1:35402","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":"info","ts":"2026-05-28T22:32:14.407394Z","caller":"traceutil/trace.go:171","msg":"trace[795091583] transaction","detail":"{read_only:false; response_revision:24478; number_of_response:1; }","duration":"188.291235ms","start":"2026-05-28T22:32:14.219078Z","end":"2026-05-28T22:32:14.407369Z","steps":["trace[795091583] 'process raft request' (duration: 188.044121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:14.407468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.541575ms","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-05-28T22:32:14.407495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.058202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra-registry.18b3db65b370f58d\" ","response":"range_response_count:1 size:722"} {"level":"info","ts":"2026-05-28T22:32:14.407531Z","caller":"traceutil/trace.go:171","msg":"trace[380671130] 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:24479; }","duration":"124.653237ms","start":"2026-05-28T22:32:14.282862Z","end":"2026-05-28T22:32:14.407515Z","steps":["trace[380671130] 'agreement among raft nodes before linearized reading' (duration: 124.530455ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:14.40757Z","caller":"traceutil/trace.go:171","msg":"trace[1497990939] range","detail":"{range_begin:/registry/events/openstack/container-infra-registry.18b3db65b370f58d; range_end:; response_count:1; response_revision:24479; }","duration":"290.165793ms","start":"2026-05-28T22:32:14.117384Z","end":"2026-05-28T22:32:14.407549Z","steps":["trace[1497990939] 'agreement among raft nodes before linearized reading' (duration: 289.96563ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:14.407886Z","caller":"traceutil/trace.go:171","msg":"trace[578939056] transaction","detail":"{read_only:false; response_revision:24479; number_of_response:1; }","duration":"183.27283ms","start":"2026-05-28T22:32:14.224594Z","end":"2026-05-28T22:32:14.407867Z","steps":["trace[578939056] 'process raft request' (duration: 182.69133ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:14.590612Z","caller":"traceutil/trace.go:171","msg":"trace[1762776001] transaction","detail":"{read_only:false; response_revision:24480; number_of_response:1; }","duration":"199.869184ms","start":"2026-05-28T22:32:14.390723Z","end":"2026-05-28T22:32:14.590592Z","steps":["trace[1762776001] 'process raft request' (duration: 198.575969ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:14.600004Z","caller":"traceutil/trace.go:171","msg":"trace[851779240] linearizableReadLoop","detail":"{readStateIndex:25287; appliedIndex:25285; }","duration":"186.537264ms","start":"2026-05-28T22:32:14.413456Z","end":"2026-05-28T22:32:14.599993Z","steps":["trace[851779240] 'read index received' (duration: 175.752491ms)","trace[851779240] 'applied index is now lower than readState.Index' (duration: 10.784063ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:14.600225Z","caller":"traceutil/trace.go:171","msg":"trace[1772279023] transaction","detail":"{read_only:false; response_revision:24481; number_of_response:1; }","duration":"190.344984ms","start":"2026-05-28T22:32:14.409872Z","end":"2026-05-28T22:32:14.600217Z","steps":["trace[1772279023] 'process raft request' (duration: 189.938927ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:14.600386Z","caller":"traceutil/trace.go:171","msg":"trace[1237978194] transaction","detail":"{read_only:false; response_revision:24482; number_of_response:1; }","duration":"135.96142ms","start":"2026-05-28T22:32:14.464418Z","end":"2026-05-28T22:32:14.600379Z","steps":["trace[1237978194] 'process raft request' (duration: 135.482101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:14.600732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.271077ms","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-05-28T22:32:14.60077Z","caller":"traceutil/trace.go:171","msg":"trace[1219052881] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24484; }","duration":"187.375429ms","start":"2026-05-28T22:32:14.413384Z","end":"2026-05-28T22:32:14.60076Z","steps":["trace[1219052881] 'agreement among raft nodes before linearized reading' (duration: 187.315938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:22.946572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.840657ms","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-05-28T22:32:22.946388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.617882ms","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-05-28T22:32:22.946692Z","caller":"traceutil/trace.go:171","msg":"trace[666574912] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24602; }","duration":"154.01864ms","start":"2026-05-28T22:32:22.792646Z","end":"2026-05-28T22:32:22.946665Z","steps":["trace[666574912] 'range keys from in-memory index tree' (duration: 153.628293ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:22.946723Z","caller":"traceutil/trace.go:171","msg":"trace[1935923401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24602; }","duration":"163.006319ms","start":"2026-05-28T22:32:22.783695Z","end":"2026-05-28T22:32:22.946701Z","steps":["trace[1935923401] 'range keys from in-memory index tree' (duration: 162.45306ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:23.069521Z","caller":"traceutil/trace.go:171","msg":"trace[1360649377] transaction","detail":"{read_only:false; response_revision:24603; number_of_response:1; }","duration":"118.838248ms","start":"2026-05-28T22:32:22.95059Z","end":"2026-05-28T22:32:23.069429Z","steps":["trace[1360649377] 'process raft request' (duration: 118.574673ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:23.107816Z","caller":"traceutil/trace.go:171","msg":"trace[106305540] linearizableReadLoop","detail":"{readStateIndex:25413; appliedIndex:25411; }","duration":"116.113497ms","start":"2026-05-28T22:32:22.991672Z","end":"2026-05-28T22:32:23.107786Z","steps":["trace[106305540] 'read index received' (duration: 77.549761ms)","trace[106305540] 'applied index is now lower than readState.Index' (duration: 38.562396ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:23.107959Z","caller":"traceutil/trace.go:171","msg":"trace[1346042394] transaction","detail":"{read_only:false; response_revision:24604; number_of_response:1; }","duration":"156.858204ms","start":"2026-05-28T22:32:22.950981Z","end":"2026-05-28T22:32:23.107839Z","steps":["trace[1346042394] 'process raft request' (duration: 156.63438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:23.108031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.341151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2906ed14-5a54-4128-8ed6-5415ae97c599\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-28T22:32:23.108108Z","caller":"traceutil/trace.go:171","msg":"trace[1442853324] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2906ed14-5a54-4128-8ed6-5415ae97c599; range_end:; response_count:1; response_revision:24604; }","duration":"116.448102ms","start":"2026-05-28T22:32:22.991641Z","end":"2026-05-28T22:32:23.108089Z","steps":["trace[1442853324] 'agreement among raft nodes before linearized reading' (duration: 116.268759ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:23.222098Z","caller":"traceutil/trace.go:171","msg":"trace[624956594] transaction","detail":"{read_only:false; response_revision:24605; number_of_response:1; }","duration":"107.168078ms","start":"2026-05-28T22:32:23.114901Z","end":"2026-05-28T22:32:23.222069Z","steps":["trace[624956594] 'process raft request' (duration: 104.543369ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:23.355913Z","caller":"traceutil/trace.go:171","msg":"trace[792295806] transaction","detail":"{read_only:false; response_revision:24606; number_of_response:1; }","duration":"239.339647ms","start":"2026-05-28T22:32:23.116548Z","end":"2026-05-28T22:32:23.355888Z","steps":["trace[792295806] 'process raft request' (duration: 239.177584ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:23.527558Z","caller":"traceutil/trace.go:171","msg":"trace[1902860919] transaction","detail":"{read_only:false; response_revision:24607; number_of_response:1; }","duration":"249.537799ms","start":"2026-05-28T22:32:23.277994Z","end":"2026-05-28T22:32:23.527532Z","steps":["trace[1902860919] 'process raft request' (duration: 249.369256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:23.743497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.721109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2906ed14-5a54-4128-8ed6-5415ae97c599\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-05-28T22:32:23.743584Z","caller":"traceutil/trace.go:171","msg":"trace[167068028] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2906ed14-5a54-4128-8ed6-5415ae97c599; range_end:; response_count:1; response_revision:24607; }","duration":"132.863052ms","start":"2026-05-28T22:32:23.6107Z","end":"2026-05-28T22:32:23.743563Z","steps":["trace[167068028] 'range keys from in-memory index tree' (duration: 132.527946ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:24.068211Z","caller":"traceutil/trace.go:171","msg":"trace[986782355] transaction","detail":"{read_only:false; response_revision:24609; number_of_response:1; }","duration":"164.109ms","start":"2026-05-28T22:32:23.904045Z","end":"2026-05-28T22:32:24.068154Z","steps":["trace[986782355] 'process raft request' (duration: 130.884574ms)","trace[986782355] 'compare' (duration: 32.100875ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:24.385673Z","caller":"traceutil/trace.go:171","msg":"trace[109247912] linearizableReadLoop","detail":"{readStateIndex:25419; appliedIndex:25418; }","duration":"221.010821ms","start":"2026-05-28T22:32:24.164633Z","end":"2026-05-28T22:32:24.385644Z","steps":["trace[109247912] 'read index received' (duration: 160.638625ms)","trace[109247912] 'applied index is now lower than readState.Index' (duration: 60.371336ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:24.385776Z","caller":"traceutil/trace.go:171","msg":"trace[1860141585] transaction","detail":"{read_only:false; response_revision:24610; number_of_response:1; }","duration":"313.510474ms","start":"2026-05-28T22:32:24.072215Z","end":"2026-05-28T22:32:24.385726Z","steps":["trace[1860141585] 'process raft request' (duration: 253.159308ms)","trace[1860141585] 'compare' (duration: 60.141882ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:24.385866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.224686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-28T22:32:24.38591Z","caller":"traceutil/trace.go:171","msg":"trace[661282969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24610; }","duration":"221.324797ms","start":"2026-05-28T22:32:24.164573Z","end":"2026-05-28T22:32:24.385898Z","steps":["trace[661282969] 'agreement among raft nodes before linearized reading' (duration: 221.172974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:24.385949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:24.072193Z","time spent":"313.664726ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:26.745088Z","caller":"traceutil/trace.go:171","msg":"trace[934168080] linearizableReadLoop","detail":"{readStateIndex:25438; appliedIndex:25437; }","duration":"388.19274ms","start":"2026-05-28T22:32:26.356874Z","end":"2026-05-28T22:32:26.745067Z","steps":["trace[934168080] 'read index received' (duration: 388.062838ms)","trace[934168080] 'applied index is now lower than readState.Index' (duration: 128.832µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:26.745148Z","caller":"traceutil/trace.go:171","msg":"trace[1657942067] transaction","detail":"{read_only:false; response_revision:24628; number_of_response:1; }","duration":"416.032144ms","start":"2026-05-28T22:32:26.329082Z","end":"2026-05-28T22:32:26.745114Z","steps":["trace[1657942067] 'process raft request' (duration: 415.82597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:26.745299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.406584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"warn","ts":"2026-05-28T22:32:26.745304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:26.329065Z","time spent":"416.155357ms","remote":"127.0.0.1:35202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:26.745342Z","caller":"traceutil/trace.go:171","msg":"trace[1905215219] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24628; }","duration":"388.481195ms","start":"2026-05-28T22:32:26.356848Z","end":"2026-05-28T22:32:26.745329Z","steps":["trace[1905215219] 'agreement among raft nodes before linearized reading' (duration: 388.330612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:26.745381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:26.356818Z","time spent":"388.554047ms","remote":"127.0.0.1:58466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3259,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-05-28T22:32:26.831676Z","caller":"traceutil/trace.go:171","msg":"trace[447707290] transaction","detail":"{read_only:false; response_revision:24630; number_of_response:1; }","duration":"371.429674ms","start":"2026-05-28T22:32:26.46022Z","end":"2026-05-28T22:32:26.83165Z","steps":["trace[447707290] 'process raft request' (duration: 371.328883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:26.831846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:26.460207Z","time spent":"371.567707ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:26.831683Z","caller":"traceutil/trace.go:171","msg":"trace[1298927550] transaction","detail":"{read_only:false; response_revision:24629; number_of_response:1; }","duration":"372.78064ms","start":"2026-05-28T22:32:26.458868Z","end":"2026-05-28T22:32:26.831649Z","steps":["trace[1298927550] 'process raft request' (duration: 372.547576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:26.832034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:26.458849Z","time spent":"373.102156ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:26.8321Z","caller":"traceutil/trace.go:171","msg":"trace[613055877] transaction","detail":"{read_only:false; response_revision:24631; number_of_response:1; }","duration":"352.09242ms","start":"2026-05-28T22:32:26.479967Z","end":"2026-05-28T22:32:26.832059Z","steps":["trace[613055877] 'process raft request' (duration: 351.624711ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:26.832179Z","caller":"traceutil/trace.go:171","msg":"trace[1707488977] transaction","detail":"{read_only:false; response_revision:24632; number_of_response:1; }","duration":"188.339516ms","start":"2026-05-28T22:32:26.643822Z","end":"2026-05-28T22:32:26.832162Z","steps":["trace[1707488977] 'process raft request' (duration: 187.990809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:26.83224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.955342ms","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-05-28T22:32:26.832261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:26.479954Z","time spent":"352.224862ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:26.832282Z","caller":"traceutil/trace.go:171","msg":"trace[1832592943] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24633; }","duration":"177.010303ms","start":"2026-05-28T22:32:26.655259Z","end":"2026-05-28T22:32:26.832269Z","steps":["trace[1832592943] 'agreement among raft nodes before linearized reading' (duration: 176.85683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:26.832288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.337481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-28T22:32:26.832265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.886168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-28T22:32:26.832323Z","caller":"traceutil/trace.go:171","msg":"trace[22760021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24633; }","duration":"286.368851ms","start":"2026-05-28T22:32:26.545943Z","end":"2026-05-28T22:32:26.832312Z","steps":["trace[22760021] 'agreement among raft nodes before linearized reading' (duration: 286.323551ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:26.832345Z","caller":"traceutil/trace.go:171","msg":"trace[37017926] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24633; }","duration":"170.99763ms","start":"2026-05-28T22:32:26.661327Z","end":"2026-05-28T22:32:26.832325Z","steps":["trace[37017926] 'agreement among raft nodes before linearized reading' (duration: 170.776326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:26.832238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.46853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-28T22:32:26.83248Z","caller":"traceutil/trace.go:171","msg":"trace[108782808] transaction","detail":"{read_only:false; response_revision:24633; number_of_response:1; }","duration":"180.329965ms","start":"2026-05-28T22:32:26.65214Z","end":"2026-05-28T22:32:26.83247Z","steps":["trace[108782808] 'process raft request' (duration: 179.889067ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:26.832482Z","caller":"traceutil/trace.go:171","msg":"trace[1917286855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24633; }","duration":"392.739955ms","start":"2026-05-28T22:32:26.439729Z","end":"2026-05-28T22:32:26.832469Z","steps":["trace[1917286855] 'agreement among raft nodes before linearized reading' (duration: 392.398499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:26.832525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:26.439713Z","time spent":"392.800496ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T22:32:31.595305Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755373960380024480,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-28T22:32:31.735005Z","caller":"traceutil/trace.go:171","msg":"trace[1485257478] transaction","detail":"{read_only:false; response_revision:24683; number_of_response:1; }","duration":"648.457341ms","start":"2026-05-28T22:32:31.08652Z","end":"2026-05-28T22:32:31.734977Z","steps":["trace[1485257478] 'process raft request' (duration: 648.284838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:31.735162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:31.086498Z","time spent":"648.583253ms","remote":"127.0.0.1:40186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7681,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:32.245866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.535137ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:32:32.246046Z","caller":"traceutil/trace.go:171","msg":"trace[1409034348] transaction","detail":"{read_only:false; response_revision:24684; number_of_response:1; }","duration":"683.713535ms","start":"2026-05-28T22:32:31.56231Z","end":"2026-05-28T22:32:32.246024Z","steps":["trace[1409034348] 'process raft request' (duration: 235.908863ms)","trace[1409034348] 'compare' (duration: 447.132219ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:32.246143Z","caller":"traceutil/trace.go:171","msg":"trace[57811972] transaction","detail":"{read_only:false; response_revision:24685; number_of_response:1; }","duration":"312.241109ms","start":"2026-05-28T22:32:31.933885Z","end":"2026-05-28T22:32:32.246126Z","steps":["trace[57811972] 'process raft request' (duration: 312.130627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:32.246172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:31.5623Z","time spent":"683.811357ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:32.246234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:31.933867Z","time spent":"312.314391ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:32.246326Z","caller":"traceutil/trace.go:171","msg":"trace[2138495041] linearizableReadLoop","detail":"{readStateIndex:25495; appliedIndex:25493; }","duration":"1.151032875s","start":"2026-05-28T22:32:31.09525Z","end":"2026-05-28T22:32:32.246283Z","steps":["trace[2138495041] 'read index received' (duration: 639.537003ms)","trace[2138495041] 'applied index is now lower than readState.Index' (duration: 511.491762ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:32.246514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.151254058s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:32.246604Z","caller":"traceutil/trace.go:171","msg":"trace[190201711] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:24685; }","duration":"1.151366291s","start":"2026-05-28T22:32:31.095216Z","end":"2026-05-28T22:32:32.246582Z","steps":["trace[190201711] 'agreement among raft nodes before linearized reading' (duration: 1.151180847s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:32.246653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:31.095159Z","time spent":"1.151483063s","remote":"127.0.0.1:40170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true "} {"level":"warn","ts":"2026-05-28T22:32:32.702627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.853271ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:32:32.702766Z","caller":"traceutil/trace.go:171","msg":"trace[1942476462] linearizableReadLoop","detail":"{readStateIndex:25497; appliedIndex:25496; }","duration":"456.304653ms","start":"2026-05-28T22:32:32.246414Z","end":"2026-05-28T22:32:32.702718Z","steps":["trace[1942476462] 'read index received' (duration: 117.204698ms)","trace[1942476462] 'applied index is now lower than readState.Index' (duration: 339.098465ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:32.70283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158070457s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-28T22:32:32.702897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.589109894s","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-05-28T22:32:32.702913Z","caller":"traceutil/trace.go:171","msg":"trace[1429823917] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24686; }","duration":"1.158186979s","start":"2026-05-28T22:32:31.544702Z","end":"2026-05-28T22:32:32.702889Z","steps":["trace[1429823917] 'agreement among raft nodes before linearized reading' (duration: 1.158071417s)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:32.702931Z","caller":"traceutil/trace.go:171","msg":"trace[1670979939] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24686; }","duration":"1.589144865s","start":"2026-05-28T22:32:31.113775Z","end":"2026-05-28T22:32:32.70292Z","steps":["trace[1670979939] 'agreement among raft nodes before linearized reading' (duration: 1.589018682s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:32.702961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:31.113713Z","time spent":"1.589241056s","remote":"127.0.0.1:35402","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-05-28T22:32:32.703015Z","caller":"traceutil/trace.go:171","msg":"trace[2043960906] transaction","detail":"{read_only:false; response_revision:24686; number_of_response:1; }","duration":"679.690278ms","start":"2026-05-28T22:32:32.023307Z","end":"2026-05-28T22:32:32.702997Z","steps":["trace[2043960906] 'process raft request' (duration: 340.383499ms)","trace[2043960906] 'compare' (duration: 338.745039ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:32.703108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:32.023295Z","time spent":"679.756789ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:32.703206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.530060751s","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-05-28T22:32:32.703246Z","caller":"traceutil/trace.go:171","msg":"trace[1668604045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24686; }","duration":"1.530117903s","start":"2026-05-28T22:32:31.173119Z","end":"2026-05-28T22:32:32.703237Z","steps":["trace[1668604045] 'agreement among raft nodes before linearized reading' (duration: 1.530019682s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:32.703272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:31.173113Z","time spent":"1.530152414s","remote":"127.0.0.1:35402","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-05-28T22:32:32.703283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.515481237s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-28T22:32:32.703378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.700033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-28T22:32:32.702959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:31.544686Z","time spent":"1.15826362s","remote":"127.0.0.1:35136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-28T22:32:32.70341Z","caller":"traceutil/trace.go:171","msg":"trace[78064412] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:24686; }","duration":"1.515678341s","start":"2026-05-28T22:32:31.1877Z","end":"2026-05-28T22:32:32.703379Z","steps":["trace[78064412] 'agreement among raft nodes before linearized reading' (duration: 1.515437257s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:32.703449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.873121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18367"} {"level":"warn","ts":"2026-05-28T22:32:32.70346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.9466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"warn","ts":"2026-05-28T22:32:32.703468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.533833733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10359"} {"level":"info","ts":"2026-05-28T22:32:32.703488Z","caller":"traceutil/trace.go:171","msg":"trace[1675334283] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:24686; }","duration":"962.911353ms","start":"2026-05-28T22:32:31.740566Z","end":"2026-05-28T22:32:32.703477Z","steps":["trace[1675334283] 'agreement among raft nodes before linearized reading' (duration: 962.81364ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:32.703489Z","caller":"traceutil/trace.go:171","msg":"trace[1949374152] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24686; }","duration":"948.029983ms","start":"2026-05-28T22:32:31.755451Z","end":"2026-05-28T22:32:32.703481Z","steps":["trace[1949374152] 'agreement among raft nodes before linearized reading' (duration: 947.95001ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:32.703493Z","caller":"traceutil/trace.go:171","msg":"trace[1603629337] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24686; }","duration":"1.533886165s","start":"2026-05-28T22:32:31.1696Z","end":"2026-05-28T22:32:32.703486Z","steps":["trace[1603629337] 'agreement among raft nodes before linearized reading' (duration: 1.533792542s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:32.703482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:31.187681Z","time spent":"1.515788503s","remote":"127.0.0.1:40166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true "} {"level":"warn","ts":"2026-05-28T22:32:32.70351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:31.740506Z","time spent":"963.000414ms","remote":"127.0.0.1:35300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":18390,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" "} {"level":"warn","ts":"2026-05-28T22:32:32.703512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:31.755432Z","time spent":"948.076553ms","remote":"127.0.0.1:58466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3259,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-05-28T22:32:32.703519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:31.169583Z","time spent":"1.533928505s","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10382,"request content":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" "} {"level":"warn","ts":"2026-05-28T22:32:32.703507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.638164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-28T22:32:32.703507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.106087628s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:32.703589Z","caller":"traceutil/trace.go:171","msg":"trace[161008163] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:24686; }","duration":"699.742476ms","start":"2026-05-28T22:32:32.003827Z","end":"2026-05-28T22:32:32.703569Z","steps":["trace[161008163] 'agreement among raft nodes before linearized reading' (duration: 699.560802ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:32.703602Z","caller":"traceutil/trace.go:171","msg":"trace[119427307] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:24686; }","duration":"1.106206431s","start":"2026-05-28T22:32:31.597374Z","end":"2026-05-28T22:32:32.70358Z","steps":["trace[119427307] 'agreement among raft nodes before linearized reading' (duration: 1.106022906s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:32.703624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.431430545s","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":"warn","ts":"2026-05-28T22:32:32.703633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:32.00381Z","time spent":"699.815657ms","remote":"127.0.0.1:34198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-05-28T22:32:32.703647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:31.597356Z","time spent":"1.106281313s","remote":"127.0.0.1:35138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true "} {"level":"info","ts":"2026-05-28T22:32:32.703661Z","caller":"traceutil/trace.go:171","msg":"trace[44449977] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:24686; }","duration":"1.431495787s","start":"2026-05-28T22:32:31.272155Z","end":"2026-05-28T22:32:32.703651Z","steps":["trace[44449977] 'agreement among raft nodes before linearized reading' (duration: 1.431404335s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:32.703707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.48532594s","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":"warn","ts":"2026-05-28T22:32:32.703538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.485130886s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-28T22:32:32.703736Z","caller":"traceutil/trace.go:171","msg":"trace[986190707] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:24686; }","duration":"1.4853863s","start":"2026-05-28T22:32:31.218341Z","end":"2026-05-28T22:32:32.703727Z","steps":["trace[986190707] 'agreement among raft nodes before linearized reading' (duration: 1.48534728s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:32.703754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:31.218317Z","time spent":"1.485433522s","remote":"127.0.0.1:56336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-28T22:32:32.703704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:31.272141Z","time spent":"1.431555968s","remote":"127.0.0.1:40242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"info","ts":"2026-05-28T22:32:32.70375Z","caller":"traceutil/trace.go:171","msg":"trace[824477154] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24686; }","duration":"1.485370371s","start":"2026-05-28T22:32:31.218373Z","end":"2026-05-28T22:32:32.703743Z","steps":["trace[824477154] 'agreement among raft nodes before linearized reading' (duration: 1.485102206s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:32.7039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:31.218357Z","time spent":"1.485479222s","remote":"127.0.0.1:35292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1883,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" "} {"level":"info","ts":"2026-05-28T22:32:32.703419Z","caller":"traceutil/trace.go:171","msg":"trace[347097406] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24686; }","duration":"581.774025ms","start":"2026-05-28T22:32:32.121635Z","end":"2026-05-28T22:32:32.703409Z","steps":["trace[347097406] 'agreement among raft nodes before linearized reading' (duration: 581.649922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:32.704062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:32.121614Z","time spent":"582.429677ms","remote":"127.0.0.1:35402","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-05-28T22:32:33.211229Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755373960380024489,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-28T22:32:33.539227Z","caller":"traceutil/trace.go:171","msg":"trace[528768365] transaction","detail":"{read_only:false; response_revision:24687; number_of_response:1; }","duration":"831.544729ms","start":"2026-05-28T22:32:32.707636Z","end":"2026-05-28T22:32:33.539181Z","steps":["trace[528768365] 'process raft request' (duration: 800.839561ms)","trace[528768365] 'compare' (duration: 30.043736ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:33.539435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:32.707606Z","time spent":"831.716411ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:33.585865Z","caller":"traceutil/trace.go:171","msg":"trace[248212755] transaction","detail":"{read_only:false; response_revision:24688; number_of_response:1; }","duration":"877.330951ms","start":"2026-05-28T22:32:32.708512Z","end":"2026-05-28T22:32:33.585843Z","steps":["trace[248212755] 'process raft request' (duration: 877.132738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:33.586042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:32.708491Z","time spent":"877.436463ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:33.586273Z","caller":"traceutil/trace.go:171","msg":"trace[1680657713] transaction","detail":"{read_only:false; response_revision:24689; number_of_response:1; }","duration":"877.694988ms","start":"2026-05-28T22:32:32.708525Z","end":"2026-05-28T22:32:33.586219Z","steps":["trace[1680657713] 'process raft request' (duration: 877.2461ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:33.586353Z","caller":"traceutil/trace.go:171","msg":"trace[2023721063] transaction","detail":"{read_only:false; response_revision:24690; number_of_response:1; }","duration":"870.099144ms","start":"2026-05-28T22:32:32.716231Z","end":"2026-05-28T22:32:33.58633Z","steps":["trace[2023721063] 'process raft request' (duration: 869.86713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:33.586528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:32.70851Z","time spent":"877.887301ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:33.586589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:32.716211Z","time spent":"870.330448ms","remote":"127.0.0.1:35304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10634,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:33.586712Z","caller":"traceutil/trace.go:171","msg":"trace[1217275517] transaction","detail":"{read_only:false; response_revision:24691; number_of_response:1; }","duration":"728.896534ms","start":"2026-05-28T22:32:32.857777Z","end":"2026-05-28T22:32:33.586674Z","steps":["trace[1217275517] 'process raft request' (duration: 728.472017ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:33.586826Z","caller":"traceutil/trace.go:171","msg":"trace[504511211] transaction","detail":"{read_only:false; response_revision:24693; number_of_response:1; }","duration":"694.180561ms","start":"2026-05-28T22:32:32.892626Z","end":"2026-05-28T22:32:33.586806Z","steps":["trace[504511211] 'process raft request' (duration: 694.049768ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:33.586849Z","caller":"traceutil/trace.go:171","msg":"trace[534126092] transaction","detail":"{read_only:false; response_revision:24692; number_of_response:1; }","duration":"717.43699ms","start":"2026-05-28T22:32:32.86936Z","end":"2026-05-28T22:32:33.586797Z","steps":["trace[534126092] 'process raft request' (duration: 717.174465ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:33.586895Z","caller":"traceutil/trace.go:171","msg":"trace[32516841] transaction","detail":"{read_only:false; response_revision:24694; number_of_response:1; }","duration":"692.920647ms","start":"2026-05-28T22:32:32.893949Z","end":"2026-05-28T22:32:33.586869Z","steps":["trace[32516841] 'process raft request' (duration: 692.785135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:33.586922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:32.857744Z","time spent":"729.058818ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:33.586933Z","caller":"traceutil/trace.go:171","msg":"trace[444703489] transaction","detail":"{read_only:false; response_revision:24695; number_of_response:1; }","duration":"690.91681ms","start":"2026-05-28T22:32:32.896001Z","end":"2026-05-28T22:32:33.586918Z","steps":["trace[444703489] 'process raft request' (duration: 690.778707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:33.586948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:32.89261Z","time spent":"694.282643ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:33.586985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:32.869336Z","time spent":"717.579672ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:33.587015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:32.895972Z","time spent":"691.004683ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:33.587029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:32.89393Z","time spent":"693.030121ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:33.586871Z","caller":"traceutil/trace.go:171","msg":"trace[1813978529] linearizableReadLoop","detail":"{readStateIndex:25506; appliedIndex:25497; }","duration":"875.870553ms","start":"2026-05-28T22:32:32.71096Z","end":"2026-05-28T22:32:33.58683Z","steps":["trace[1813978529] 'read index received' (duration: 797.528128ms)","trace[1813978529] 'applied index is now lower than readState.Index' (duration: 78.341005ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:33.587134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.142019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-2906ed14-5a54-4128-8ed6-5415ae97c599\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-05-28T22:32:33.587193Z","caller":"traceutil/trace.go:171","msg":"trace[319555653] range","detail":"{range_begin:/registry/persistentvolumes/pvc-2906ed14-5a54-4128-8ed6-5415ae97c599; range_end:; response_count:1; response_revision:24695; }","duration":"876.261972ms","start":"2026-05-28T22:32:32.710911Z","end":"2026-05-28T22:32:33.587173Z","steps":["trace[319555653] 'agreement among raft nodes before linearized reading' (duration: 876.033436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:33.587241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:32.710894Z","time spent":"876.338213ms","remote":"127.0.0.1:35278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-2906ed14-5a54-4128-8ed6-5415ae97c599\" "} {"level":"warn","ts":"2026-05-28T22:32:33.587261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.53385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:33.587329Z","caller":"traceutil/trace.go:171","msg":"trace[229930341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24695; }","duration":"873.600131ms","start":"2026-05-28T22:32:32.713713Z","end":"2026-05-28T22:32:33.587313Z","steps":["trace[229930341] 'agreement among raft nodes before linearized reading' (duration: 873.477109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:33.587387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:32.713694Z","time spent":"873.682972ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T22:32:33.587429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.721361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:33.587504Z","caller":"traceutil/trace.go:171","msg":"trace[1335626473] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:24695; }","duration":"567.805593ms","start":"2026-05-28T22:32:33.019653Z","end":"2026-05-28T22:32:33.587458Z","steps":["trace[1335626473] 'agreement among raft nodes before linearized reading' (duration: 567.701431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:33.587521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.539331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-28T22:32:33.587523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.85736ms","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-05-28T22:32:33.587559Z","caller":"traceutil/trace.go:171","msg":"trace[1102952464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24695; }","duration":"684.618022ms","start":"2026-05-28T22:32:32.902932Z","end":"2026-05-28T22:32:33.58755Z","steps":["trace[1102952464] 'agreement among raft nodes before linearized reading' (duration: 684.4644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:33.587559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:33.019631Z","time spent":"567.916864ms","remote":"127.0.0.1:35154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true "} {"level":"info","ts":"2026-05-28T22:32:33.587576Z","caller":"traceutil/trace.go:171","msg":"trace[882597272] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24695; }","duration":"664.936541ms","start":"2026-05-28T22:32:32.922621Z","end":"2026-05-28T22:32:33.587558Z","steps":["trace[882597272] 'agreement among raft nodes before linearized reading' (duration: 664.750538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:33.587581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:32.902919Z","time spent":"684.658323ms","remote":"127.0.0.1:35402","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-05-28T22:32:33.58761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:32.922608Z","time spent":"664.996852ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-28T22:32:33.58733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.280012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-28T22:32:33.587667Z","caller":"traceutil/trace.go:171","msg":"trace[2040619314] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24695; }","duration":"653.622048ms","start":"2026-05-28T22:32:32.934023Z","end":"2026-05-28T22:32:33.587645Z","steps":["trace[2040619314] 'agreement among raft nodes before linearized reading' (duration: 653.1764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:33.587727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:32.934014Z","time spent":"653.695749ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-28T22:32:38.060625Z","caller":"traceutil/trace.go:171","msg":"trace[101012094] linearizableReadLoop","detail":"{readStateIndex:25540; appliedIndex:25539; }","duration":"461.070353ms","start":"2026-05-28T22:32:37.599529Z","end":"2026-05-28T22:32:38.0606Z","steps":["trace[101012094] 'read index received' (duration: 461.018392ms)","trace[101012094] 'applied index is now lower than readState.Index' (duration: 50.721µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:38.060761Z","caller":"traceutil/trace.go:171","msg":"trace[762576871] transaction","detail":"{read_only:false; response_revision:24728; number_of_response:1; }","duration":"501.79859ms","start":"2026-05-28T22:32:37.558928Z","end":"2026-05-28T22:32:38.060727Z","steps":["trace[762576871] 'process raft request' (duration: 501.513335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:38.060841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.292947ms","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-05-28T22:32:38.060879Z","caller":"traceutil/trace.go:171","msg":"trace[537469385] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24728; }","duration":"461.373879ms","start":"2026-05-28T22:32:37.599495Z","end":"2026-05-28T22:32:38.060869Z","steps":["trace[537469385] 'agreement among raft nodes before linearized reading' (duration: 461.225056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:38.060916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:37.599479Z","time spent":"461.430339ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-28T22:32:38.060939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:37.558909Z","time spent":"501.936452ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:38.56122Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755373960380024588,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-28T22:32:38.60389Z","caller":"traceutil/trace.go:171","msg":"trace[1486663229] transaction","detail":"{read_only:false; response_revision:24729; number_of_response:1; }","duration":"1.002782363s","start":"2026-05-28T22:32:37.601076Z","end":"2026-05-28T22:32:38.603859Z","steps":["trace[1486663229] 'process raft request' (duration: 1.002471037s)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:38.604025Z","caller":"traceutil/trace.go:171","msg":"trace[812285568] transaction","detail":"{read_only:false; response_revision:24733; number_of_response:1; }","duration":"996.423014ms","start":"2026-05-28T22:32:37.607574Z","end":"2026-05-28T22:32:38.603997Z","steps":["trace[812285568] 'process raft request' (duration: 996.361053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:38.604059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:37.60106Z","time spent":"1.002904975s","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:38.604075Z","caller":"traceutil/trace.go:171","msg":"trace[661886686] transaction","detail":"{read_only:false; response_revision:24731; number_of_response:1; }","duration":"1.002201802s","start":"2026-05-28T22:32:37.601853Z","end":"2026-05-28T22:32:38.604055Z","steps":["trace[661886686] 'process raft request' (duration: 1.001973128s)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:38.604124Z","caller":"traceutil/trace.go:171","msg":"trace[501576615] transaction","detail":"{read_only:false; response_revision:24730; number_of_response:1; }","duration":"1.002968977s","start":"2026-05-28T22:32:37.601131Z","end":"2026-05-28T22:32:38.6041Z","steps":["trace[501576615] 'process raft request' (duration: 1.002629521s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:38.604197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:37.607567Z","time spent":"996.530956ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:38.604228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:37.601127Z","time spent":"1.003051698s","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:38.604221Z","caller":"traceutil/trace.go:171","msg":"trace[1606815955] transaction","detail":"{read_only:false; response_revision:24732; number_of_response:1; }","duration":"1.001376446s","start":"2026-05-28T22:32:37.602808Z","end":"2026-05-28T22:32:38.604184Z","steps":["trace[1606815955] 'process raft request' (duration: 1.001067421s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:38.604314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:37.601848Z","time spent":"1.002316254s","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:38.604508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:37.602803Z","time spent":"1.00158853s","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:38.770982Z","caller":"traceutil/trace.go:171","msg":"trace[1006218918] linearizableReadLoop","detail":"{readStateIndex:25548; appliedIndex:25540; }","duration":"710.249634ms","start":"2026-05-28T22:32:38.060694Z","end":"2026-05-28T22:32:38.770944Z","steps":["trace[1006218918] 'read index received' (duration: 542.924842ms)","trace[1006218918] 'applied index is now lower than readState.Index' (duration: 167.323161ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:38.77122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.059287856s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-05-28T22:32:38.771256Z","caller":"traceutil/trace.go:171","msg":"trace[544668017] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24736; }","duration":"1.059356288s","start":"2026-05-28T22:32:37.711886Z","end":"2026-05-28T22:32:38.771243Z","steps":["trace[544668017] 'agreement among raft nodes before linearized reading' (duration: 1.059205755s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:38.771288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:37.711879Z","time spent":"1.059401959s","remote":"127.0.0.1:58466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3259,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-05-28T22:32:38.771257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.826121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-05-28T22:32:38.77129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.38365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-28T22:32:38.77133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165369314s","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-05-28T22:32:38.77136Z","caller":"traceutil/trace.go:171","msg":"trace[2067799514] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:24736; }","duration":"630.502843ms","start":"2026-05-28T22:32:38.140836Z","end":"2026-05-28T22:32:38.771339Z","steps":["trace[2067799514] 'agreement among raft nodes before linearized reading' (duration: 630.384481ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:38.771358Z","caller":"traceutil/trace.go:171","msg":"trace[251260979] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:24736; }","duration":"841.993795ms","start":"2026-05-28T22:32:37.929341Z","end":"2026-05-28T22:32:38.771335Z","steps":["trace[251260979] 'agreement among raft nodes before linearized reading' (duration: 841.639758ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:38.771382Z","caller":"traceutil/trace.go:171","msg":"trace[1003555295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24736; }","duration":"1.165440966s","start":"2026-05-28T22:32:37.605929Z","end":"2026-05-28T22:32:38.77137Z","steps":["trace[1003555295] 'agreement among raft nodes before linearized reading' (duration: 1.165272203s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:38.771402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:38.140815Z","time spent":"630.576904ms","remote":"127.0.0.1:35058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true "} {"level":"warn","ts":"2026-05-28T22:32:38.771424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:37.605921Z","time spent":"1.165491317s","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-28T22:32:38.771422Z","caller":"traceutil/trace.go:171","msg":"trace[2022281088] transaction","detail":"{read_only:false; response_revision:24734; number_of_response:1; }","duration":"706.293289ms","start":"2026-05-28T22:32:38.065119Z","end":"2026-05-28T22:32:38.771412Z","steps":["trace[2022281088] 'process raft request' (duration: 705.581676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:38.771459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123925054s","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-05-28T22:32:38.771488Z","caller":"traceutil/trace.go:171","msg":"trace[1916521509] transaction","detail":"{read_only:false; response_revision:24736; number_of_response:1; }","duration":"480.021808ms","start":"2026-05-28T22:32:38.291445Z","end":"2026-05-28T22:32:38.771466Z","steps":["trace[1916521509] 'process raft request' (duration: 479.458128ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:38.771502Z","caller":"traceutil/trace.go:171","msg":"trace[1499271599] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24736; }","duration":"1.123985564s","start":"2026-05-28T22:32:37.647499Z","end":"2026-05-28T22:32:38.771484Z","steps":["trace[1499271599] 'agreement among raft nodes before linearized reading' (duration: 1.123906773s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:38.771385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.122052618s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-28T22:32:38.771544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:37.647483Z","time spent":"1.124053806s","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-28T22:32:38.771558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:38.065067Z","time spent":"706.405511ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:38.771617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.826817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-28T22:32:38.771614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:38.291427Z","time spent":"480.10038ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:38.771652Z","caller":"traceutil/trace.go:171","msg":"trace[351622249] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:24736; }","duration":"178.863127ms","start":"2026-05-28T22:32:38.592779Z","end":"2026-05-28T22:32:38.771642Z","steps":["trace[351622249] 'agreement among raft nodes before linearized reading' (duration: 178.806226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:38.771419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:37.929321Z","time spent":"842.084156ms","remote":"127.0.0.1:35210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":143,"response size":32,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-05-28T22:32:38.771573Z","caller":"traceutil/trace.go:171","msg":"trace[146551664] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24736; }","duration":"1.122259903s","start":"2026-05-28T22:32:37.649303Z","end":"2026-05-28T22:32:38.771563Z","steps":["trace[146551664] 'agreement among raft nodes before linearized reading' (duration: 1.122027359s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:38.771744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:37.649298Z","time spent":"1.122431657s","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-28T22:32:38.771283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123806001s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-28T22:32:38.771785Z","caller":"traceutil/trace.go:171","msg":"trace[805203816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24736; }","duration":"1.124339103s","start":"2026-05-28T22:32:37.647436Z","end":"2026-05-28T22:32:38.771775Z","steps":["trace[805203816] 'agreement among raft nodes before linearized reading' (duration: 1.12372033s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:38.771811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:37.647429Z","time spent":"1.124374703s","remote":"127.0.0.1:35402","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-05-28T22:32:38.771821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.953679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:38.771852Z","caller":"traceutil/trace.go:171","msg":"trace[1029496771] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:24736; }","duration":"196.051102ms","start":"2026-05-28T22:32:38.575791Z","end":"2026-05-28T22:32:38.771842Z","steps":["trace[1029496771] 'agreement among raft nodes before linearized reading' (duration: 195.997281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:38.771906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.825629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-28T22:32:38.771923Z","caller":"traceutil/trace.go:171","msg":"trace[637481272] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24736; }","duration":"544.88422ms","start":"2026-05-28T22:32:38.227034Z","end":"2026-05-28T22:32:38.771918Z","steps":["trace[637481272] 'agreement among raft nodes before linearized reading' (duration: 544.827639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:38.771939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:38.227011Z","time spent":"544.92442ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":555,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-05-28T22:32:38.771356Z","caller":"traceutil/trace.go:171","msg":"trace[1945748950] transaction","detail":"{read_only:false; response_revision:24735; number_of_response:1; }","duration":"482.080827ms","start":"2026-05-28T22:32:38.289243Z","end":"2026-05-28T22:32:38.771324Z","steps":["trace[1945748950] 'process raft request' (duration: 481.616809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:38.771981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.968547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-28T22:32:38.772023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:38.289205Z","time spent":"482.758181ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:38.772029Z","caller":"traceutil/trace.go:171","msg":"trace[336838661] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:24736; }","duration":"387.060399ms","start":"2026-05-28T22:32:38.384953Z","end":"2026-05-28T22:32:38.772013Z","steps":["trace[336838661] 'agreement among raft nodes before linearized reading' (duration: 386.976367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:38.772076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:38.384929Z","time spent":"387.13609ms","remote":"127.0.0.1:34634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true "} {"level":"info","ts":"2026-05-28T22:32:38.922417Z","caller":"traceutil/trace.go:171","msg":"trace[1489204907] transaction","detail":"{read_only:false; response_revision:24739; number_of_response:1; }","duration":"145.318966ms","start":"2026-05-28T22:32:38.77708Z","end":"2026-05-28T22:32:38.922399Z","steps":["trace[1489204907] 'process raft request' (duration: 145.261735ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:38.922439Z","caller":"traceutil/trace.go:171","msg":"trace[617950790] transaction","detail":"{read_only:false; response_revision:24738; number_of_response:1; }","duration":"146.221543ms","start":"2026-05-28T22:32:38.776177Z","end":"2026-05-28T22:32:38.922399Z","steps":["trace[617950790] 'process raft request' (duration: 146.02223ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:38.922757Z","caller":"traceutil/trace.go:171","msg":"trace[393989105] transaction","detail":"{read_only:false; response_revision:24741; number_of_response:1; }","duration":"144.557461ms","start":"2026-05-28T22:32:38.778176Z","end":"2026-05-28T22:32:38.922733Z","steps":["trace[393989105] 'process raft request' (duration: 144.442579ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:38.92283Z","caller":"traceutil/trace.go:171","msg":"trace[1528721355] transaction","detail":"{read_only:false; response_revision:24740; number_of_response:1; }","duration":"145.665563ms","start":"2026-05-28T22:32:38.777136Z","end":"2026-05-28T22:32:38.922802Z","steps":["trace[1528721355] 'process raft request' (duration: 145.231375ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:40.393157Z","caller":"traceutil/trace.go:171","msg":"trace[1539977637] transaction","detail":"{read_only:false; response_revision:24745; number_of_response:1; }","duration":"223.0164ms","start":"2026-05-28T22:32:40.170123Z","end":"2026-05-28T22:32:40.393139Z","steps":["trace[1539977637] 'process raft request' (duration: 222.880618ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:40.473789Z","caller":"traceutil/trace.go:171","msg":"trace[1342203831] transaction","detail":"{read_only:false; response_revision:24746; number_of_response:1; }","duration":"262.395601ms","start":"2026-05-28T22:32:40.211368Z","end":"2026-05-28T22:32:40.473764Z","steps":["trace[1342203831] 'process raft request' (duration: 238.805697ms)","trace[1342203831] 'compare' (duration: 23.176526ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:40.760319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.481069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:32:40.760441Z","caller":"traceutil/trace.go:171","msg":"trace[1171049752] transaction","detail":"{read_only:false; response_revision:24747; number_of_response:1; }","duration":"151.466572ms","start":"2026-05-28T22:32:40.608955Z","end":"2026-05-28T22:32:40.760422Z","steps":["trace[1171049752] 'process raft request' (duration: 13.79694ms)","trace[1171049752] 'compare' (duration: 137.375007ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:40.785171Z","caller":"traceutil/trace.go:171","msg":"trace[1730751032] transaction","detail":"{read_only:false; response_revision:24748; number_of_response:1; }","duration":"175.024466ms","start":"2026-05-28T22:32:40.610124Z","end":"2026-05-28T22:32:40.785149Z","steps":["trace[1730751032] 'process raft request' (duration: 174.76623ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:40.785269Z","caller":"traceutil/trace.go:171","msg":"trace[1537773171] transaction","detail":"{read_only:false; response_revision:24749; number_of_response:1; }","duration":"175.043436ms","start":"2026-05-28T22:32:40.610199Z","end":"2026-05-28T22:32:40.785242Z","steps":["trace[1537773171] 'process raft request' (duration: 174.878803ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:40.785633Z","caller":"traceutil/trace.go:171","msg":"trace[1432874808] linearizableReadLoop","detail":"{readStateIndex:25564; appliedIndex:25560; }","duration":"111.885967ms","start":"2026-05-28T22:32:40.673732Z","end":"2026-05-28T22:32:40.785618Z","steps":["trace[1432874808] 'read index received' (duration: 111.110242ms)","trace[1432874808] 'applied index is now lower than readState.Index' (duration: 774.115µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:40.785674Z","caller":"traceutil/trace.go:171","msg":"trace[1045104779] transaction","detail":"{read_only:false; response_revision:24751; number_of_response:1; }","duration":"174.865973ms","start":"2026-05-28T22:32:40.610798Z","end":"2026-05-28T22:32:40.785664Z","steps":["trace[1045104779] 'process raft request' (duration: 174.713441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:40.785809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.07095ms","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-05-28T22:32:40.785834Z","caller":"traceutil/trace.go:171","msg":"trace[426194003] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24751; }","duration":"112.144421ms","start":"2026-05-28T22:32:40.673682Z","end":"2026-05-28T22:32:40.785826Z","steps":["trace[426194003] 'agreement among raft nodes before linearized reading' (duration: 112.003679ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:40.785606Z","caller":"traceutil/trace.go:171","msg":"trace[101058261] transaction","detail":"{read_only:false; response_revision:24750; number_of_response:1; }","duration":"175.2367ms","start":"2026-05-28T22:32:40.610352Z","end":"2026-05-28T22:32:40.785589Z","steps":["trace[101058261] 'process raft request' (duration: 174.770672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:45.37933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.028428ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-28T22:32:45.379451Z","caller":"traceutil/trace.go:171","msg":"trace[1328052743] linearizableReadLoop","detail":"{readStateIndex:25601; appliedIndex:25600; }","duration":"124.125508ms","start":"2026-05-28T22:32:45.255301Z","end":"2026-05-28T22:32:45.379427Z","steps":["trace[1328052743] 'read index received' (duration: 46.491µs)","trace[1328052743] 'applied index is now lower than readState.Index' (duration: 124.077397ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:45.379552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.23927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:45.379594Z","caller":"traceutil/trace.go:171","msg":"trace[780067887] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:24787; }","duration":"124.322971ms","start":"2026-05-28T22:32:45.255255Z","end":"2026-05-28T22:32:45.379578Z","steps":["trace[780067887] 'agreement among raft nodes before linearized reading' (duration: 124.227ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:52.263916Z","caller":"traceutil/trace.go:171","msg":"trace[2137526445] transaction","detail":"{read_only:false; response_revision:24847; number_of_response:1; }","duration":"132.642978ms","start":"2026-05-28T22:32:52.131254Z","end":"2026-05-28T22:32:52.263897Z","steps":["trace[2137526445] 'process raft request' (duration: 132.520126ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:54.482443Z","caller":"traceutil/trace.go:171","msg":"trace[1689463504] transaction","detail":"{read_only:false; response_revision:24861; number_of_response:1; }","duration":"210.649336ms","start":"2026-05-28T22:32:54.271778Z","end":"2026-05-28T22:32:54.482428Z","steps":["trace[1689463504] 'process raft request' (duration: 210.30604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:54.790861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.419274ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:32:54.791011Z","caller":"traceutil/trace.go:171","msg":"trace[897098314] linearizableReadLoop","detail":"{readStateIndex:25677; appliedIndex:25676; }","duration":"300.214854ms","start":"2026-05-28T22:32:54.490773Z","end":"2026-05-28T22:32:54.790987Z","steps":["trace[897098314] 'read index received' (duration: 107.437814ms)","trace[897098314] 'applied index is now lower than readState.Index' (duration: 192.77501ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:54.791266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.454978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:54.791337Z","caller":"traceutil/trace.go:171","msg":"trace[266030346] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:24862; }","duration":"300.578531ms","start":"2026-05-28T22:32:54.490744Z","end":"2026-05-28T22:32:54.791323Z","steps":["trace[266030346] 'agreement among raft nodes before linearized reading' (duration: 300.432708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:54.791369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:54.490736Z","time spent":"300.624091ms","remote":"127.0.0.1:34974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":29,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true "} {"level":"info","ts":"2026-05-28T22:32:54.791944Z","caller":"traceutil/trace.go:171","msg":"trace[643195068] transaction","detail":"{read_only:false; response_revision:24862; number_of_response:1; }","duration":"301.571349ms","start":"2026-05-28T22:32:54.490348Z","end":"2026-05-28T22:32:54.79192Z","steps":["trace[643195068] 'process raft request' (duration: 107.947433ms)","trace[643195068] 'compare' (duration: 192.222219ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:54.792098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:54.490306Z","time spent":"301.699712ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:55.199628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.230258ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:32:55.19981Z","caller":"traceutil/trace.go:171","msg":"trace[661296728] linearizableReadLoop","detail":"{readStateIndex:25678; appliedIndex:25677; }","duration":"408.758087ms","start":"2026-05-28T22:32:54.791041Z","end":"2026-05-28T22:32:55.199799Z","steps":["trace[661296728] 'read index received' (duration: 197.408967ms)","trace[661296728] 'applied index is now lower than readState.Index' (duration: 211.34834ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:55.199842Z","caller":"traceutil/trace.go:171","msg":"trace[1809193238] transaction","detail":"{read_only:false; response_revision:24863; number_of_response:1; }","duration":"435.521081ms","start":"2026-05-28T22:32:54.76429Z","end":"2026-05-28T22:32:55.199811Z","steps":["trace[1809193238] 'process raft request' (duration: 224.049759ms)","trace[1809193238] 'compare' (duration: 210.943582ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:55.199863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.406505ms","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-05-28T22:32:55.199899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.139034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:55.199915Z","caller":"traceutil/trace.go:171","msg":"trace[396984964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24863; }","duration":"657.187606ms","start":"2026-05-28T22:32:54.542723Z","end":"2026-05-28T22:32:55.199911Z","steps":["trace[396984964] 'agreement among raft nodes before linearized reading' (duration: 657.137125ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:55.199919Z","caller":"traceutil/trace.go:171","msg":"trace[435733406] 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:24863; }","duration":"498.495987ms","start":"2026-05-28T22:32:54.701411Z","end":"2026-05-28T22:32:55.199907Z","steps":["trace[435733406] 'agreement among raft nodes before linearized reading' (duration: 498.406086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:55.199933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:54.542705Z","time spent":"657.223527ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T22:32:55.199945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:54.701397Z","time spent":"498.543028ms","remote":"127.0.0.1:35260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-05-28T22:32:55.200017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.834537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1155"} {"level":"warn","ts":"2026-05-28T22:32:55.200045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:54.764269Z","time spent":"435.634623ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:55.200103Z","caller":"traceutil/trace.go:171","msg":"trace[2059401006] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:24863; }","duration":"589.966819ms","start":"2026-05-28T22:32:54.610117Z","end":"2026-05-28T22:32:55.200084Z","steps":["trace[2059401006] 'agreement among raft nodes before linearized reading' (duration: 589.704025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:55.20016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:54.610099Z","time spent":"590.051461ms","remote":"127.0.0.1:35208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1178,"request content":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" "} {"level":"info","ts":"2026-05-28T22:32:55.261482Z","caller":"traceutil/trace.go:171","msg":"trace[106168401] transaction","detail":"{read_only:false; response_revision:24866; number_of_response:1; }","duration":"411.183343ms","start":"2026-05-28T22:32:54.850273Z","end":"2026-05-28T22:32:55.261457Z","steps":["trace[106168401] 'process raft request' (duration: 411.118952ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:55.261526Z","caller":"traceutil/trace.go:171","msg":"trace[1761216337] transaction","detail":"{read_only:false; response_revision:24864; number_of_response:1; }","duration":"456.428375ms","start":"2026-05-28T22:32:54.805061Z","end":"2026-05-28T22:32:55.261489Z","steps":["trace[1761216337] 'process raft request' (duration: 456.15093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:55.261649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:54.850258Z","time spent":"411.301465ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:55.261716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:54.805039Z","time spent":"456.568158ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:55.261774Z","caller":"traceutil/trace.go:171","msg":"trace[1229275273] transaction","detail":"{read_only:false; response_revision:24867; number_of_response:1; }","duration":"411.414388ms","start":"2026-05-28T22:32:54.850334Z","end":"2026-05-28T22:32:55.261749Z","steps":["trace[1229275273] 'process raft request' (duration: 411.089392ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:55.261886Z","caller":"traceutil/trace.go:171","msg":"trace[1902415825] transaction","detail":"{read_only:false; response_revision:24869; number_of_response:1; }","duration":"409.92268ms","start":"2026-05-28T22:32:54.851948Z","end":"2026-05-28T22:32:55.26187Z","steps":["trace[1902415825] 'process raft request' (duration: 409.825718ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:55.261893Z","caller":"traceutil/trace.go:171","msg":"trace[282602630] transaction","detail":"{read_only:false; response_revision:24868; number_of_response:1; }","duration":"411.452779ms","start":"2026-05-28T22:32:54.850404Z","end":"2026-05-28T22:32:55.261857Z","steps":["trace[282602630] 'process raft request' (duration: 411.253955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:55.261906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:54.850318Z","time spent":"411.518419ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:55.261949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:54.851925Z","time spent":"409.990601ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:55.26203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:54.850366Z","time spent":"411.583ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:55.26192Z","caller":"traceutil/trace.go:171","msg":"trace[15219135] transaction","detail":"{read_only:false; response_revision:24870; number_of_response:1; }","duration":"407.996962ms","start":"2026-05-28T22:32:54.853901Z","end":"2026-05-28T22:32:55.261897Z","steps":["trace[15219135] 'process raft request' (duration: 407.916791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:55.262239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:54.853887Z","time spent":"408.319639ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:55.262251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.64738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-05-28T22:32:55.262171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.031966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-28T22:32:55.262292Z","caller":"traceutil/trace.go:171","msg":"trace[1101191489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24870; }","duration":"352.724602ms","start":"2026-05-28T22:32:54.909558Z","end":"2026-05-28T22:32:55.262283Z","steps":["trace[1101191489] 'agreement among raft nodes before linearized reading' (duration: 352.637801ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:55.262324Z","caller":"traceutil/trace.go:171","msg":"trace[587882156] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24870; }","duration":"260.22177ms","start":"2026-05-28T22:32:55.00209Z","end":"2026-05-28T22:32:55.262312Z","steps":["trace[587882156] 'agreement among raft nodes before linearized reading' (duration: 259.899394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:55.262318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.519649ms","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-05-28T22:32:55.262328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:54.909538Z","time spent":"352.782283ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-28T22:32:55.26234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.217259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-28T22:32:55.262366Z","caller":"traceutil/trace.go:171","msg":"trace[231557989] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24870; }","duration":"266.59783ms","start":"2026-05-28T22:32:54.995754Z","end":"2026-05-28T22:32:55.262352Z","steps":["trace[231557989] 'agreement among raft nodes before linearized reading' (duration: 266.438827ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:55.262379Z","caller":"traceutil/trace.go:171","msg":"trace[1236467427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24870; }","duration":"260.312251ms","start":"2026-05-28T22:32:55.002055Z","end":"2026-05-28T22:32:55.262368Z","steps":["trace[1236467427] 'agreement among raft nodes before linearized reading' (duration: 260.170978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:55.262406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.599426ms","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-05-28T22:32:55.262477Z","caller":"traceutil/trace.go:171","msg":"trace[1517270334] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24870; }","duration":"406.687379ms","start":"2026-05-28T22:32:54.855771Z","end":"2026-05-28T22:32:55.262459Z","steps":["trace[1517270334] 'agreement among raft nodes before linearized reading' (duration: 406.438283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:55.262522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:54.855727Z","time spent":"406.786951ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-28T22:32:55.262506Z","caller":"traceutil/trace.go:171","msg":"trace[1543666031] transaction","detail":"{read_only:false; response_revision:24865; number_of_response:1; }","duration":"414.672149ms","start":"2026-05-28T22:32:54.846881Z","end":"2026-05-28T22:32:55.261553Z","steps":["trace[1543666031] 'process raft request' (duration: 414.472206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:55.262767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:54.846841Z","time spent":"415.862871ms","remote":"127.0.0.1:35402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:55.37988Z","caller":"traceutil/trace.go:171","msg":"trace[497766466] linearizableReadLoop","detail":"{readStateIndex:25686; appliedIndex:25685; }","duration":"117.984561ms","start":"2026-05-28T22:32:55.261865Z","end":"2026-05-28T22:32:55.37985Z","steps":["trace[497766466] 'read index received' (duration: 41.353699ms)","trace[497766466] 'applied index is now lower than readState.Index' (duration: 76.626562ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:55.379927Z","caller":"traceutil/trace.go:171","msg":"trace[777970883] transaction","detail":"{read_only:false; response_revision:24871; number_of_response:1; }","duration":"113.308873ms","start":"2026-05-28T22:32:55.266594Z","end":"2026-05-28T22:32:55.379902Z","steps":["trace[777970883] 'process raft request' (duration: 113.11145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:55.380119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.243766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:55.380146Z","caller":"traceutil/trace.go:171","msg":"trace[1906421263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24872; }","duration":"179.323266ms","start":"2026-05-28T22:32:55.200815Z","end":"2026-05-28T22:32:55.380139Z","steps":["trace[1906421263] 'agreement among raft nodes before linearized reading' (duration: 179.123492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:55.380215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.224971ms","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-05-28T22:32:55.380257Z","caller":"traceutil/trace.go:171","msg":"trace[193602654] transaction","detail":"{read_only:false; response_revision:24872; number_of_response:1; }","duration":"113.562328ms","start":"2026-05-28T22:32:55.266635Z","end":"2026-05-28T22:32:55.380197Z","steps":["trace[193602654] 'process raft request' (duration: 113.213661ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:55.380324Z","caller":"traceutil/trace.go:171","msg":"trace[1431670545] transaction","detail":"{read_only:false; response_revision:24873; number_of_response:1; }","duration":"113.66142ms","start":"2026-05-28T22:32:55.266651Z","end":"2026-05-28T22:32:55.380312Z","steps":["trace[1431670545] 'process raft request' (duration: 113.409475ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:55.380321Z","caller":"traceutil/trace.go:171","msg":"trace[800752277] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24872; }","duration":"174.385484ms","start":"2026-05-28T22:32:55.205912Z","end":"2026-05-28T22:32:55.380297Z","steps":["trace[800752277] 'agreement among raft nodes before linearized reading' (duration: 174.044827ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:55.380279Z","caller":"traceutil/trace.go:171","msg":"trace[110540708] transaction","detail":"{read_only:false; response_revision:24875; number_of_response:1; }","duration":"111.173494ms","start":"2026-05-28T22:32:55.269088Z","end":"2026-05-28T22:32:55.380262Z","steps":["trace[110540708] 'process raft request' (duration: 111.136503ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:55.380406Z","caller":"traceutil/trace.go:171","msg":"trace[1652318622] transaction","detail":"{read_only:false; response_revision:24874; number_of_response:1; }","duration":"113.477597ms","start":"2026-05-28T22:32:55.26692Z","end":"2026-05-28T22:32:55.380398Z","steps":["trace[1652318622] 'process raft request' (duration: 113.251743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:55.381106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.003532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:55.3812Z","caller":"traceutil/trace.go:171","msg":"trace[1235367423] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:24875; }","duration":"135.371818ms","start":"2026-05-28T22:32:55.245811Z","end":"2026-05-28T22:32:55.381182Z","steps":["trace[1235367423] 'agreement among raft nodes before linearized reading' (duration: 134.90605ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:58.608297Z","caller":"traceutil/trace.go:171","msg":"trace[1815159366] transaction","detail":"{read_only:false; response_revision:24891; number_of_response:1; }","duration":"108.484512ms","start":"2026-05-28T22:32:58.499781Z","end":"2026-05-28T22:32:58.608266Z","steps":["trace[1815159366] 'process raft request' (duration: 108.300939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:59.669066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.559526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:59.669196Z","caller":"traceutil/trace.go:171","msg":"trace[560116962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24903; }","duration":"123.73524ms","start":"2026-05-28T22:32:59.545437Z","end":"2026-05-28T22:32:59.669172Z","steps":["trace[560116962] 'range keys from in-memory index tree' (duration: 123.478895ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:06.778091Z","caller":"traceutil/trace.go:171","msg":"trace[1939470910] transaction","detail":"{read_only:false; response_revision:24960; number_of_response:1; }","duration":"133.27431ms","start":"2026-05-28T22:33:06.644793Z","end":"2026-05-28T22:33:06.778067Z","steps":["trace[1939470910] 'process raft request' (duration: 133.095156ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:35:17.075802Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23382} {"level":"info","ts":"2026-05-28T22:35:17.210624Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23382,"took":"130.216652ms","hash":4037569371,"current-db-size-bytes":58793984,"current-db-size":"59 MB","current-db-size-in-use-bytes":36810752,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-05-28T22:35:17.210719Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4037569371,"revision":23382,"compact-revision":20140} {"level":"info","ts":"2026-05-28T22:35:46.534225Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006aff.wal"} {"level":"info","ts":"2026-05-28T22:37:00.673353Z","caller":"traceutil/trace.go:171","msg":"trace[1076449076] transaction","detail":"{read_only:false; response_revision:27332; number_of_response:1; }","duration":"109.078165ms","start":"2026-05-28T22:37:00.564254Z","end":"2026-05-28T22:37:00.673332Z","steps":["trace[1076449076] 'process raft request' (duration: 96.675561ms)","trace[1076449076] 'compare' (duration: 11.719801ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:40:17.083827Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26248} {"level":"info","ts":"2026-05-28T22:40:17.227571Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26248,"took":"139.115648ms","hash":3765993364,"current-db-size-bytes":58793984,"current-db-size":"59 MB","current-db-size-in-use-bytes":33128448,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-28T22:40:17.227653Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3765993364,"revision":26248,"compact-revision":23382} {"level":"info","ts":"2026-05-28T22:40:51.220133Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"de5c2ed1f829ccc5","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-28T22:40:51.231277Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-28T22:40:51.231467Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-28T22:45:17.092473Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28824} {"level":"info","ts":"2026-05-28T22:45:17.276057Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28824,"took":"179.06611ms","hash":2097520474,"current-db-size-bytes":58793984,"current-db-size":"59 MB","current-db-size-in-use-bytes":30191616,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-28T22:45:17.276327Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2097520474,"revision":28824,"compact-revision":26248} {"level":"info","ts":"2026-05-28T22:50:17.099997Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31043} {"level":"info","ts":"2026-05-28T22:50:17.209299Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31043,"took":"105.513748ms","hash":2172276318,"current-db-size-bytes":58793984,"current-db-size":"59 MB","current-db-size-in-use-bytes":31145984,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-28T22:50:17.209361Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2172276318,"revision":31043,"compact-revision":28824} {"level":"info","ts":"2026-05-28T22:55:17.106551Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33309} {"level":"info","ts":"2026-05-28T22:55:17.250069Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33309,"took":"139.187451ms","hash":1736182310,"current-db-size-bytes":59838464,"current-db-size":"60 MB","current-db-size-in-use-bytes":32198656,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-28T22:55:17.250146Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1736182310,"revision":33309,"compact-revision":31043} {"level":"info","ts":"2026-05-28T23:00:17.115366Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35602} {"level":"info","ts":"2026-05-28T23:00:17.273552Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35602,"took":"150.045216ms","hash":659537103,"current-db-size-bytes":60272640,"current-db-size":"60 MB","current-db-size-in-use-bytes":31707136,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-28T23:00:17.273651Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":659537103,"revision":35602,"compact-revision":33309} {"level":"info","ts":"2026-05-28T23:02:11.721111Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"de5c2ed1f829ccc5","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-28T23:02:11.726608Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-28T23:02:11.726707Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}