{"level":"warn","ts":"2026-05-26T21:10:00.140755Z","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-26T21:10:00.140841Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.4:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.4:2380","--initial-cluster=instance=https://199.204.45.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.4: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-26T21:10:00.140907Z","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-26T21:10:00.140919Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-05-26T21:10:00.140936Z","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-26T21:10:00.14153Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"]} {"level":"info","ts":"2026-05-26T21:10:00.141602Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.4: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-26T21:10:00.146125Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.311002ms"} {"level":"info","ts":"2026-05-26T21:10:00.151631Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"66f7b57d56ad567d","cluster-id":"ec267dcaac715770"} {"level":"info","ts":"2026-05-26T21:10:00.151738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=()"} {"level":"info","ts":"2026-05-26T21:10:00.151771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 0"} {"level":"info","ts":"2026-05-26T21:10:00.151792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 66f7b57d56ad567d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-26T21:10:00.15181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 1"} {"level":"info","ts":"2026-05-26T21:10:00.151866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"warn","ts":"2026-05-26T21:10:00.157383Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-26T21:10:00.158443Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-26T21:10:00.159447Z","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-26T21:10:00.161543Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"66f7b57d56ad567d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-26T21:10:00.161847Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"66f7b57d56ad567d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-26T21:10:00.161884Z","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-26T21:10:00.16207Z","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-26T21:10:00.162098Z","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-26T21:10:00.164166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"info","ts":"2026-05-26T21:10:00.165962Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","added-peer-id":"66f7b57d56ad567d","added-peer-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-05-26T21:10:00.167884Z","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-26T21:10:00.167987Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-05-26T21:10:00.168212Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-05-26T21:10:00.168452Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"66f7b57d56ad567d","initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-26T21:10:00.168512Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-26T21:10:00.552497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d is starting a new election at term 1"} {"level":"info","ts":"2026-05-26T21:10:00.552714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-26T21:10:00.552777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgPreVoteResp from 66f7b57d56ad567d at term 1"} {"level":"info","ts":"2026-05-26T21:10:00.552837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became candidate at term 2"} {"level":"info","ts":"2026-05-26T21:10:00.552872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgVoteResp from 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-05-26T21:10:00.552898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became leader at term 2"} {"level":"info","ts":"2026-05-26T21:10:00.552923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 66f7b57d56ad567d elected leader 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-05-26T21:10:00.553592Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T21:10:00.554338Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T21:10:00.554341Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"66f7b57d56ad567d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.4:2379]}","request-path":"/0/members/66f7b57d56ad567d/attributes","cluster-id":"ec267dcaac715770","publish-timeout":"7s"} {"level":"info","ts":"2026-05-26T21:10:00.554434Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T21:10:00.55474Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T21:10:00.55481Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-26T21:10:00.554913Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-26T21:10:00.555023Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T21:10:00.555176Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T21:10:00.558423Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.4:2379"} {"level":"info","ts":"2026-05-26T21:10:00.558572Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-26T21:10:06.887018Z","caller":"traceutil/trace.go:171","msg":"trace[1970654720] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"208.345528ms","start":"2026-05-26T21:10:06.678642Z","end":"2026-05-26T21:10:06.886988Z","steps":["trace[1970654720] 'process raft request' (duration: 208.224715ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:10:06.887041Z","caller":"traceutil/trace.go:171","msg":"trace[1459246564] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"210.086131ms","start":"2026-05-26T21:10:06.676919Z","end":"2026-05-26T21:10:06.887005Z","steps":["trace[1459246564] 'process raft request' (duration: 205.672317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:10:14.635974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.864992ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:10:14.636136Z","caller":"traceutil/trace.go:171","msg":"trace[469870305] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:400; }","duration":"244.041085ms","start":"2026-05-26T21:10:14.392074Z","end":"2026-05-26T21:10:14.636115Z","steps":["trace[469870305] 'range keys from in-memory index tree' (duration: 243.75811ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:10:14.787388Z","caller":"traceutil/trace.go:171","msg":"trace[2066376165] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"148.090273ms","start":"2026-05-26T21:10:14.639268Z","end":"2026-05-26T21:10:14.787358Z","steps":["trace[2066376165] 'process raft request' (duration: 147.909799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:10:15.017381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.445257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T21:10:15.017562Z","caller":"traceutil/trace.go:171","msg":"trace[1256817243] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"371.137751ms","start":"2026-05-26T21:10:14.646393Z","end":"2026-05-26T21:10:15.017531Z","steps":["trace[1256817243] 'process raft request' (duration: 247.626813ms)","trace[1256817243] 'compare' (duration: 122.257374ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:10:15.017666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:10:14.646377Z","time spent":"371.248073ms","remote":"127.0.0.1:34578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:10:43.129272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.369618ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T21:10:43.129435Z","caller":"traceutil/trace.go:171","msg":"trace[561560759] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"201.690062ms","start":"2026-05-26T21:10:42.927722Z","end":"2026-05-26T21:10:43.129412Z","steps":["trace[561560759] 'process raft request' (duration: 92.100489ms)","trace[561560759] 'compare' (duration: 109.175344ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:10:43.63744Z","caller":"traceutil/trace.go:171","msg":"trace[337744354] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"292.720331ms","start":"2026-05-26T21:10:43.344683Z","end":"2026-05-26T21:10:43.637403Z","steps":["trace[337744354] 'process raft request' (duration: 292.463736ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:10:43.637356Z","caller":"traceutil/trace.go:171","msg":"trace[1579465774] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"149.025871ms","start":"2026-05-26T21:10:43.488297Z","end":"2026-05-26T21:10:43.637323Z","steps":["trace[1579465774] 'read index received' (duration: 148.598873ms)","trace[1579465774] 'applied index is now lower than readState.Index' (duration: 425.508µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:10:43.637873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.560081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-26T21:10:43.637952Z","caller":"traceutil/trace.go:171","msg":"trace[1110022077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"149.688674ms","start":"2026-05-26T21:10:43.488245Z","end":"2026-05-26T21:10:43.637934Z","steps":["trace[1110022077] 'agreement among raft nodes before linearized reading' (duration: 149.279606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:10:43.638074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.964653ms","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-26T21:10:43.638189Z","caller":"traceutil/trace.go:171","msg":"trace[1067223868] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"135.151336ms","start":"2026-05-26T21:10:43.503015Z","end":"2026-05-26T21:10:43.638166Z","steps":["trace[1067223868] 'agreement among raft nodes before linearized reading' (duration: 134.78393ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:10:44.925715Z","caller":"traceutil/trace.go:171","msg":"trace[739080477] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"118.347559ms","start":"2026-05-26T21:10:44.807338Z","end":"2026-05-26T21:10:44.925686Z","steps":["trace[739080477] 'process raft request' (duration: 111.406067ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:10:44.928271Z","caller":"traceutil/trace.go:171","msg":"trace[1503212036] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"115.688697ms","start":"2026-05-26T21:10:44.812548Z","end":"2026-05-26T21:10:44.928237Z","steps":["trace[1503212036] 'process raft request' (duration: 115.604906ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:10:44.928283Z","caller":"traceutil/trace.go:171","msg":"trace[1239123959] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"116.646806ms","start":"2026-05-26T21:10:44.811619Z","end":"2026-05-26T21:10:44.928266Z","steps":["trace[1239123959] 'process raft request' (duration: 116.442062ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:11:06.869743Z","caller":"traceutil/trace.go:171","msg":"trace[1913201875] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"218.08064ms","start":"2026-05-26T21:11:06.651629Z","end":"2026-05-26T21:11:06.86971Z","steps":["trace[1913201875] 'process raft request' (duration: 173.011871ms)","trace[1913201875] 'compare' (duration: 44.811705ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:11:06.87591Z","caller":"traceutil/trace.go:171","msg":"trace[823274062] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"187.682121ms","start":"2026-05-26T21:11:06.688167Z","end":"2026-05-26T21:11:06.875849Z","steps":["trace[823274062] 'process raft request' (duration: 187.496548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:12:11.526304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.765734ms","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:5"} {"level":"info","ts":"2026-05-26T21:12:11.526423Z","caller":"traceutil/trace.go:171","msg":"trace[2128783174] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1758; }","duration":"112.949147ms","start":"2026-05-26T21:12:11.413446Z","end":"2026-05-26T21:12:11.526395Z","steps":["trace[2128783174] 'count revisions from in-memory index tree' (duration: 112.632601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:12:11.526493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.5714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:12:11.526558Z","caller":"traceutil/trace.go:171","msg":"trace[838627753] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1758; }","duration":"112.685052ms","start":"2026-05-26T21:12:11.41385Z","end":"2026-05-26T21:12:11.526535Z","steps":["trace[838627753] 'range keys from in-memory index tree' (duration: 112.52928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:12:11.526656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.124201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:12:11.5267Z","caller":"traceutil/trace.go:171","msg":"trace[1046034747] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1758; }","duration":"113.274143ms","start":"2026-05-26T21:12:11.413415Z","end":"2026-05-26T21:12:11.526689Z","steps":["trace[1046034747] 'range keys from in-memory index tree' (duration: 113.041559ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:12:18.231185Z","caller":"traceutil/trace.go:171","msg":"trace[66546996] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"105.521182ms","start":"2026-05-26T21:12:18.125631Z","end":"2026-05-26T21:12:18.231152Z","steps":["trace[66546996] 'process raft request' (duration: 60.023968ms)","trace[66546996] 'compare' (duration: 45.24317ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:12:18.279098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.843672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10666"} {"level":"info","ts":"2026-05-26T21:12:18.279162Z","caller":"traceutil/trace.go:171","msg":"trace[1959904829] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1872; }","duration":"115.008365ms","start":"2026-05-26T21:12:18.164138Z","end":"2026-05-26T21:12:18.279146Z","steps":["trace[1959904829] 'agreement among raft nodes before linearized reading' (duration: 114.780921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:12:18.545145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.976735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T21:12:18.545258Z","caller":"traceutil/trace.go:171","msg":"trace[2042523588] linearizableReadLoop","detail":"{readStateIndex:1947; appliedIndex:1946; }","duration":"120.550516ms","start":"2026-05-26T21:12:18.42469Z","end":"2026-05-26T21:12:18.54524Z","steps":["trace[2042523588] 'read index received' (duration: 105.912µs)","trace[2042523588] 'applied index is now lower than readState.Index' (duration: 120.443594ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:12:18.545378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.699578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:12:18.545415Z","caller":"traceutil/trace.go:171","msg":"trace[585275313] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1874; }","duration":"120.737119ms","start":"2026-05-26T21:12:18.424666Z","end":"2026-05-26T21:12:18.545403Z","steps":["trace[585275313] 'agreement among raft nodes before linearized reading' (duration: 120.674578ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:12:18.545412Z","caller":"traceutil/trace.go:171","msg":"trace[1734141986] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"254.235728ms","start":"2026-05-26T21:12:18.291113Z","end":"2026-05-26T21:12:18.545348Z","steps":["trace[1734141986] 'process raft request' (duration: 85.972068ms)","trace[1734141986] 'compare' (duration: 167.795942ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:12:18.797804Z","caller":"traceutil/trace.go:171","msg":"trace[1287618367] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"243.86548ms","start":"2026-05-26T21:12:18.553912Z","end":"2026-05-26T21:12:18.797778Z","steps":["trace[1287618367] 'process raft request' (duration: 243.743698ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:12:18.797833Z","caller":"traceutil/trace.go:171","msg":"trace[257816285] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"243.900602ms","start":"2026-05-26T21:12:18.553894Z","end":"2026-05-26T21:12:18.797794Z","steps":["trace[257816285] 'process raft request' (duration: 243.600666ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:12:18.798082Z","caller":"traceutil/trace.go:171","msg":"trace[230815566] linearizableReadLoop","detail":"{readStateIndex:1952; appliedIndex:1948; }","duration":"178.347673ms","start":"2026-05-26T21:12:18.61971Z","end":"2026-05-26T21:12:18.798058Z","steps":["trace[230815566] 'read index received' (duration: 177.66894ms)","trace[230815566] 'applied index is now lower than readState.Index' (duration: 677.413µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:12:18.798153Z","caller":"traceutil/trace.go:171","msg":"trace[1520116778] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"239.524862ms","start":"2026-05-26T21:12:18.558614Z","end":"2026-05-26T21:12:18.798139Z","steps":["trace[1520116778] 'process raft request' (duration: 239.306148ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:12:18.798199Z","caller":"traceutil/trace.go:171","msg":"trace[949165674] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"244.183766ms","start":"2026-05-26T21:12:18.553978Z","end":"2026-05-26T21:12:18.798162Z","steps":["trace[949165674] 'process raft request' (duration: 243.718858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:12:18.798311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.580927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-26T21:12:18.798358Z","caller":"traceutil/trace.go:171","msg":"trace[1564033618] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1879; }","duration":"178.691299ms","start":"2026-05-26T21:12:18.619654Z","end":"2026-05-26T21:12:18.798345Z","steps":["trace[1564033618] 'agreement among raft nodes before linearized reading' (duration: 178.503886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:12:19.312879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.013825ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-26T21:12:19.313303Z","caller":"traceutil/trace.go:171","msg":"trace[1566249703] linearizableReadLoop","detail":"{readStateIndex:1954; appliedIndex:1952; }","duration":"425.366591ms","start":"2026-05-26T21:12:18.887912Z","end":"2026-05-26T21:12:19.313278Z","steps":["trace[1566249703] 'read index received' (duration: 272.940028ms)","trace[1566249703] 'applied index is now lower than readState.Index' (duration: 152.425413ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:12:19.313338Z","caller":"traceutil/trace.go:171","msg":"trace[199362461] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"485.4516ms","start":"2026-05-26T21:12:18.827852Z","end":"2026-05-26T21:12:19.313304Z","steps":["trace[199362461] 'process raft request' (duration: 485.180495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:12:19.313517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:12:18.827818Z","time spent":"485.604143ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:12:19.313621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.690097ms","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-26T21:12:19.313539Z","caller":"traceutil/trace.go:171","msg":"trace[1530440508] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"157.448204ms","start":"2026-05-26T21:12:19.155921Z","end":"2026-05-26T21:12:19.313369Z","steps":["trace[1530440508] 'process raft request' (duration: 157.300202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:12:19.31353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.782191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bd694be8-4a9c-4f6e-a749-eec51d7e8e93\" ","response":"range_response_count:1 size:3745"} {"level":"info","ts":"2026-05-26T21:12:19.313718Z","caller":"traceutil/trace.go:171","msg":"trace[823117149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1881; }","duration":"425.838229ms","start":"2026-05-26T21:12:18.887858Z","end":"2026-05-26T21:12:19.313697Z","steps":["trace[823117149] 'agreement among raft nodes before linearized reading' (duration: 425.553504ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:12:19.313954Z","caller":"traceutil/trace.go:171","msg":"trace[1701002378] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bd694be8-4a9c-4f6e-a749-eec51d7e8e93; range_end:; response_count:1; response_revision:1881; }","duration":"275.211638ms","start":"2026-05-26T21:12:19.038726Z","end":"2026-05-26T21:12:19.313937Z","steps":["trace[1701002378] 'agreement among raft nodes before linearized reading' (duration: 274.677719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:12:19.313987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:12:18.887836Z","time spent":"426.140465ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-26T21:12:33.480121Z","caller":"traceutil/trace.go:171","msg":"trace[1988464057] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"124.305934ms","start":"2026-05-26T21:12:33.355791Z","end":"2026-05-26T21:12:33.480097Z","steps":["trace[1988464057] 'process raft request' (duration: 124.141611ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:12:33.495089Z","caller":"traceutil/trace.go:171","msg":"trace[2076330733] linearizableReadLoop","detail":"{readStateIndex:2068; appliedIndex:2066; }","duration":"109.101297ms","start":"2026-05-26T21:12:33.385971Z","end":"2026-05-26T21:12:33.495072Z","steps":["trace[2076330733] 'read index received' (duration: 94.056555ms)","trace[2076330733] 'applied index is now lower than readState.Index' (duration: 15.043692ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:12:33.495306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.314582ms","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-26T21:12:33.495389Z","caller":"traceutil/trace.go:171","msg":"trace[704014005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1991; }","duration":"109.457264ms","start":"2026-05-26T21:12:33.385915Z","end":"2026-05-26T21:12:33.495372Z","steps":["trace[704014005] 'agreement among raft nodes before linearized reading' (duration: 109.25009ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:12:33.495178Z","caller":"traceutil/trace.go:171","msg":"trace[1021658629] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"129.1089ms","start":"2026-05-26T21:12:33.366037Z","end":"2026-05-26T21:12:33.495146Z","steps":["trace[1021658629] 'process raft request' (duration: 128.952487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:12:36.596886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.291884ms","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:5"} {"level":"info","ts":"2026-05-26T21:12:36.596976Z","caller":"traceutil/trace.go:171","msg":"trace[2068512125] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:2021; }","duration":"113.431747ms","start":"2026-05-26T21:12:36.483514Z","end":"2026-05-26T21:12:36.596945Z","steps":["trace[2068512125] 'count revisions from in-memory index tree' (duration: 113.188023ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:12:37.002374Z","caller":"traceutil/trace.go:171","msg":"trace[1971046017] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"157.551847ms","start":"2026-05-26T21:12:36.844795Z","end":"2026-05-26T21:12:37.002347Z","steps":["trace[1971046017] 'process raft request' (duration: 157.326133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:13:09.974203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.602236ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T21:13:09.974323Z","caller":"traceutil/trace.go:171","msg":"trace[632491138] linearizableReadLoop","detail":"{readStateIndex:2289; appliedIndex:2288; }","duration":"247.865665ms","start":"2026-05-26T21:13:09.726439Z","end":"2026-05-26T21:13:09.974305Z","steps":["trace[632491138] 'read index received' (duration: 113.115826ms)","trace[632491138] 'applied index is now lower than readState.Index' (duration: 134.748909ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:13:09.974542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.038899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-26T21:13:09.974564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.10822ms","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-26T21:13:09.974597Z","caller":"traceutil/trace.go:171","msg":"trace[1757075024] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2203; }","duration":"215.133102ms","start":"2026-05-26T21:13:09.759449Z","end":"2026-05-26T21:13:09.974582Z","steps":["trace[1757075024] 'agreement among raft nodes before linearized reading' (duration: 214.962419ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:13:09.974619Z","caller":"traceutil/trace.go:171","msg":"trace[1706612132] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"258.152781ms","start":"2026-05-26T21:13:09.716206Z","end":"2026-05-26T21:13:09.974359Z","steps":["trace[1706612132] 'process raft request' (duration: 123.31028ms)","trace[1706612132] 'compare' (duration: 134.481044ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:13:09.974648Z","caller":"traceutil/trace.go:171","msg":"trace[979368865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2203; }","duration":"248.242042ms","start":"2026-05-26T21:13:09.726387Z","end":"2026-05-26T21:13:09.97463Z","steps":["trace[979368865] 'agreement among raft nodes before linearized reading' (duration: 248.011938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:13:10.294562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.739882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T21:13:10.29482Z","caller":"traceutil/trace.go:171","msg":"trace[88432983] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"316.28299ms","start":"2026-05-26T21:13:09.978514Z","end":"2026-05-26T21:13:10.294797Z","steps":["trace[88432983] 'process raft request' (duration: 97.191293ms)","trace[88432983] 'compare' (duration: 218.588027ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:13:10.294902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:13:09.978493Z","time spent":"316.371561ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:13:10.294924Z","caller":"traceutil/trace.go:171","msg":"trace[1371492352] linearizableReadLoop","detail":"{readStateIndex:2291; appliedIndex:2289; }","duration":"302.263133ms","start":"2026-05-26T21:13:09.992632Z","end":"2026-05-26T21:13:10.294895Z","steps":["trace[1371492352] 'read index received' (duration: 82.985542ms)","trace[1371492352] 'applied index is now lower than readState.Index' (duration: 219.275311ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:13:10.29494Z","caller":"traceutil/trace.go:171","msg":"trace[593011588] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"316.354981ms","start":"2026-05-26T21:13:09.978553Z","end":"2026-05-26T21:13:10.294908Z","steps":["trace[593011588] 'process raft request' (duration: 316.114117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:13:10.295103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:13:09.978538Z","time spent":"316.476363ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:13:10.295137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.493697ms","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-26T21:13:10.295214Z","caller":"traceutil/trace.go:171","msg":"trace[215608175] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2205; }","duration":"302.746941ms","start":"2026-05-26T21:13:09.99245Z","end":"2026-05-26T21:13:10.295197Z","steps":["trace[215608175] 'agreement among raft nodes before linearized reading' (duration: 302.524867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:13:10.295262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:13:09.992431Z","time spent":"302.822454ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-26T21:13:10.628087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.737983ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T21:13:10.628201Z","caller":"traceutil/trace.go:171","msg":"trace[582073277] linearizableReadLoop","detail":"{readStateIndex:2293; appliedIndex:2292; }","duration":"204.158182ms","start":"2026-05-26T21:13:10.424026Z","end":"2026-05-26T21:13:10.628184Z","steps":["trace[582073277] 'read index received' (duration: 84.143923ms)","trace[582073277] 'applied index is now lower than readState.Index' (duration: 120.013078ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:13:10.628291Z","caller":"traceutil/trace.go:171","msg":"trace[258416034] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"209.600986ms","start":"2026-05-26T21:13:10.418679Z","end":"2026-05-26T21:13:10.62828Z","steps":["trace[258416034] 'process raft request' (duration: 89.568068ms)","trace[258416034] 'compare' (duration: 119.610091ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:13:10.628432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.222293ms","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-26T21:13:10.628555Z","caller":"traceutil/trace.go:171","msg":"trace[1032667870] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2207; }","duration":"109.389555ms","start":"2026-05-26T21:13:10.519141Z","end":"2026-05-26T21:13:10.628531Z","steps":["trace[1032667870] 'agreement among raft nodes before linearized reading' (duration: 109.154871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:13:10.62866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.611271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:13:10.628717Z","caller":"traceutil/trace.go:171","msg":"trace[1758312075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2207; }","duration":"204.712172ms","start":"2026-05-26T21:13:10.423989Z","end":"2026-05-26T21:13:10.628701Z","steps":["trace[1758312075] 'agreement among raft nodes before linearized reading' (duration: 204.58141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:13:16.240184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.089577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:13:16.240292Z","caller":"traceutil/trace.go:171","msg":"trace[1728239711] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2236; }","duration":"112.241619ms","start":"2026-05-26T21:13:16.128029Z","end":"2026-05-26T21:13:16.240271Z","steps":["trace[1728239711] 'range keys from in-memory index tree' (duration: 112.016775ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:13:16.454644Z","caller":"traceutil/trace.go:171","msg":"trace[40557444] linearizableReadLoop","detail":"{readStateIndex:2326; appliedIndex:2323; }","duration":"124.293379ms","start":"2026-05-26T21:13:16.330327Z","end":"2026-05-26T21:13:16.45462Z","steps":["trace[40557444] 'read index received' (duration: 23.218372ms)","trace[40557444] 'applied index is now lower than readState.Index' (duration: 101.073836ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:13:16.454675Z","caller":"traceutil/trace.go:171","msg":"trace[342452976] transaction","detail":"{read_only:false; response_revision:2238; number_of_response:1; }","duration":"133.971463ms","start":"2026-05-26T21:13:16.320676Z","end":"2026-05-26T21:13:16.454648Z","steps":["trace[342452976] 'process raft request' (duration: 131.718431ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:13:16.454787Z","caller":"traceutil/trace.go:171","msg":"trace[856974118] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"133.873341ms","start":"2026-05-26T21:13:16.320904Z","end":"2026-05-26T21:13:16.454777Z","steps":["trace[856974118] 'process raft request' (duration: 133.656947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:13:16.455105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.759388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-26T21:13:16.45511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.142341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-26T21:13:16.45516Z","caller":"traceutil/trace.go:171","msg":"trace[627972252] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2239; }","duration":"124.84634ms","start":"2026-05-26T21:13:16.3303Z","end":"2026-05-26T21:13:16.455147Z","steps":["trace[627972252] 'agreement among raft nodes before linearized reading' (duration: 124.640926ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:13:16.455212Z","caller":"traceutil/trace.go:171","msg":"trace[391845918] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2239; }","duration":"120.271743ms","start":"2026-05-26T21:13:16.334912Z","end":"2026-05-26T21:13:16.455183Z","steps":["trace[391845918] 'agreement among raft nodes before linearized reading' (duration: 119.967296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:18:29.065981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.450239ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T21:18:29.066224Z","caller":"traceutil/trace.go:171","msg":"trace[1715636157] linearizableReadLoop","detail":"{readStateIndex:5230; appliedIndex:5227; }","duration":"144.058333ms","start":"2026-05-26T21:18:28.922141Z","end":"2026-05-26T21:18:29.066199Z","steps":["trace[1715636157] 'read index received' (duration: 26.9803ms)","trace[1715636157] 'applied index is now lower than readState.Index' (duration: 117.075423ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:18:29.066409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.268457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c0b2d782-5e19-4037-b402-fc9f7e2fc1fa\" ","response":"range_response_count:1 size:3599"} {"level":"info","ts":"2026-05-26T21:18:29.066446Z","caller":"traceutil/trace.go:171","msg":"trace[2001337235] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c0b2d782-5e19-4037-b402-fc9f7e2fc1fa; range_end:; response_count:1; response_revision:5038; }","duration":"144.353139ms","start":"2026-05-26T21:18:28.922077Z","end":"2026-05-26T21:18:29.06643Z","steps":["trace[2001337235] 'agreement among raft nodes before linearized reading' (duration: 144.196676ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:18:29.066422Z","caller":"traceutil/trace.go:171","msg":"trace[1145418321] transaction","detail":"{read_only:false; response_revision:5036; number_of_response:1; }","duration":"268.503757ms","start":"2026-05-26T21:18:28.797868Z","end":"2026-05-26T21:18:29.066372Z","steps":["trace[1145418321] 'process raft request' (duration: 66.564919ms)","trace[1145418321] 'compare' (duration: 201.239035ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:18:29.066452Z","caller":"traceutil/trace.go:171","msg":"trace[654363133] transaction","detail":"{read_only:false; response_revision:5038; number_of_response:1; }","duration":"253.767067ms","start":"2026-05-26T21:18:28.812633Z","end":"2026-05-26T21:18:29.0664Z","steps":["trace[654363133] 'process raft request' (duration: 253.513802ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:18:29.067039Z","caller":"traceutil/trace.go:171","msg":"trace[127799948] transaction","detail":"{read_only:false; response_revision:5037; number_of_response:1; }","duration":"267.371685ms","start":"2026-05-26T21:18:28.799638Z","end":"2026-05-26T21:18:29.067009Z","steps":["trace[127799948] 'process raft request' (duration: 266.458538ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:18:35.319428Z","caller":"traceutil/trace.go:171","msg":"trace[79427896] transaction","detail":"{read_only:false; response_revision:5157; number_of_response:1; }","duration":"108.193545ms","start":"2026-05-26T21:18:35.211209Z","end":"2026-05-26T21:18:35.319402Z","steps":["trace[79427896] 'process raft request' (duration: 85.310853ms)","trace[79427896] 'compare' (duration: 22.561966ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:18:41.790608Z","caller":"traceutil/trace.go:171","msg":"trace[1240249213] transaction","detail":"{read_only:false; response_revision:5295; number_of_response:1; }","duration":"114.213ms","start":"2026-05-26T21:18:41.676335Z","end":"2026-05-26T21:18:41.790548Z","steps":["trace[1240249213] 'process raft request' (duration: 113.935135ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:18:41.911796Z","caller":"traceutil/trace.go:171","msg":"trace[1595898852] transaction","detail":"{read_only:false; response_revision:5297; number_of_response:1; }","duration":"118.607873ms","start":"2026-05-26T21:18:41.79313Z","end":"2026-05-26T21:18:41.911738Z","steps":["trace[1595898852] 'process raft request' (duration: 116.489832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:18:49.882335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.90216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:18:49.882444Z","caller":"traceutil/trace.go:171","msg":"trace[1758312730] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:5492; }","duration":"391.036562ms","start":"2026-05-26T21:18:49.491387Z","end":"2026-05-26T21:18:49.882423Z","steps":["trace[1758312730] 'count revisions from in-memory index tree' (duration: 390.832548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:18:49.882494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:18:49.491341Z","time spent":"391.140664ms","remote":"127.0.0.1:34502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-05-26T21:18:49.882467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.44506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f471980a-1e94-419a-a8bf-24bdff33d09c\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-26T21:18:49.882555Z","caller":"traceutil/trace.go:171","msg":"trace[867756913] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f471980a-1e94-419a-a8bf-24bdff33d09c; range_end:; response_count:1; response_revision:5492; }","duration":"254.601283ms","start":"2026-05-26T21:18:49.627932Z","end":"2026-05-26T21:18:49.882533Z","steps":["trace[867756913] 'range keys from in-memory index tree' (duration: 254.277226ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:18:50.069992Z","caller":"traceutil/trace.go:171","msg":"trace[521122429] transaction","detail":"{read_only:false; response_revision:5494; number_of_response:1; }","duration":"106.230299ms","start":"2026-05-26T21:18:49.963722Z","end":"2026-05-26T21:18:50.069953Z","steps":["trace[521122429] 'process raft request' (duration: 106.027705ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:20:00.801626Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2999} {"level":"info","ts":"2026-05-26T21:20:00.876886Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2999,"took":"73.512342ms","hash":2969301924,"current-db-size-bytes":58499072,"current-db-size":"58 MB","current-db-size-in-use-bytes":34160640,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-26T21:20:00.876973Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2969301924,"revision":2999,"compact-revision":-1} {"level":"info","ts":"2026-05-26T21:24:56.640181Z","caller":"traceutil/trace.go:171","msg":"trace[1795313042] linearizableReadLoop","detail":"{readStateIndex:8563; appliedIndex:8562; }","duration":"219.074542ms","start":"2026-05-26T21:24:56.421088Z","end":"2026-05-26T21:24:56.640163Z","steps":["trace[1795313042] 'read index received' (duration: 218.987371ms)","trace[1795313042] 'applied index is now lower than readState.Index' (duration: 86.121µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:24:56.640268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.166154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:24:56.640234Z","caller":"traceutil/trace.go:171","msg":"trace[658790462] transaction","detail":"{read_only:false; response_revision:8272; number_of_response:1; }","duration":"233.117588ms","start":"2026-05-26T21:24:56.407085Z","end":"2026-05-26T21:24:56.640202Z","steps":["trace[658790462] 'process raft request' (duration: 232.979226ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:24:56.640287Z","caller":"traceutil/trace.go:171","msg":"trace[1542288121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8272; }","duration":"219.213875ms","start":"2026-05-26T21:24:56.421068Z","end":"2026-05-26T21:24:56.640282Z","steps":["trace[1542288121] 'agreement among raft nodes before linearized reading' (duration: 219.166494ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:24:56.677219Z","caller":"traceutil/trace.go:171","msg":"trace[774658626] transaction","detail":"{read_only:false; response_revision:8274; number_of_response:1; }","duration":"200.797028ms","start":"2026-05-26T21:24:56.476405Z","end":"2026-05-26T21:24:56.677202Z","steps":["trace[774658626] 'process raft request' (duration: 200.754737ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:24:56.677279Z","caller":"traceutil/trace.go:171","msg":"trace[1661966413] transaction","detail":"{read_only:false; response_revision:8273; number_of_response:1; }","duration":"206.18962ms","start":"2026-05-26T21:24:56.471058Z","end":"2026-05-26T21:24:56.677248Z","steps":["trace[1661966413] 'process raft request' (duration: 205.973286ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:24:56.864449Z","caller":"traceutil/trace.go:171","msg":"trace[1601531161] transaction","detail":"{read_only:false; response_revision:8275; number_of_response:1; }","duration":"209.93254ms","start":"2026-05-26T21:24:56.654489Z","end":"2026-05-26T21:24:56.864422Z","steps":["trace[1601531161] 'process raft request' (duration: 207.76188ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:25:00.81392Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6105} {"level":"info","ts":"2026-05-26T21:25:00.90539Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6105,"took":"89.551983ms","hash":2377220678,"current-db-size-bytes":58499072,"current-db-size":"58 MB","current-db-size-in-use-bytes":23396352,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-26T21:25:00.905463Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2377220678,"revision":6105,"compact-revision":2999} {"level":"info","ts":"2026-05-26T21:25:08.071267Z","caller":"traceutil/trace.go:171","msg":"trace[743978785] linearizableReadLoop","detail":"{readStateIndex:8627; appliedIndex:8626; }","duration":"108.400141ms","start":"2026-05-26T21:25:07.962846Z","end":"2026-05-26T21:25:08.071246Z","steps":["trace[743978785] 'read index received' (duration: 108.242167ms)","trace[743978785] 'applied index is now lower than readState.Index' (duration: 157.174µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:25:08.071417Z","caller":"traceutil/trace.go:171","msg":"trace[1002873527] transaction","detail":"{read_only:false; response_revision:8332; number_of_response:1; }","duration":"109.218365ms","start":"2026-05-26T21:25:07.962156Z","end":"2026-05-26T21:25:08.071374Z","steps":["trace[1002873527] 'process raft request' (duration: 108.97293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:25:08.071615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.756347ms","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-26T21:25:08.071755Z","caller":"traceutil/trace.go:171","msg":"trace[1332612054] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8332; }","duration":"108.941121ms","start":"2026-05-26T21:25:07.962793Z","end":"2026-05-26T21:25:08.071735Z","steps":["trace[1332612054] 'agreement among raft nodes before linearized reading' (duration: 108.604585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:25:08.39191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.905206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T21:25:08.392149Z","caller":"traceutil/trace.go:171","msg":"trace[1184371527] linearizableReadLoop","detail":"{readStateIndex:8629; appliedIndex:8627; }","duration":"214.393065ms","start":"2026-05-26T21:25:08.177739Z","end":"2026-05-26T21:25:08.392132Z","steps":["trace[1184371527] 'read index received' (duration: 39.223432ms)","trace[1184371527] 'applied index is now lower than readState.Index' (duration: 175.168493ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:25:08.392143Z","caller":"traceutil/trace.go:171","msg":"trace[1138041537] transaction","detail":"{read_only:false; response_revision:8333; number_of_response:1; }","duration":"315.778632ms","start":"2026-05-26T21:25:08.07634Z","end":"2026-05-26T21:25:08.392118Z","steps":["trace[1138041537] 'process raft request' (duration: 140.550169ms)","trace[1138041537] 'compare' (duration: 174.676082ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:25:08.39226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:25:08.076311Z","time spent":"315.879674ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:25:08.392265Z","caller":"traceutil/trace.go:171","msg":"trace[2054056354] transaction","detail":"{read_only:false; response_revision:8334; number_of_response:1; }","duration":"315.837943ms","start":"2026-05-26T21:25:08.076417Z","end":"2026-05-26T21:25:08.392255Z","steps":["trace[2054056354] 'process raft request' (duration: 315.619269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:25:08.392327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:25:08.076402Z","time spent":"315.890964ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:25:08.392575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.822712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-26T21:25:08.392588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.852514ms","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-26T21:25:08.392656Z","caller":"traceutil/trace.go:171","msg":"trace[239753242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8334; }","duration":"214.939924ms","start":"2026-05-26T21:25:08.177697Z","end":"2026-05-26T21:25:08.392637Z","steps":["trace[239753242] 'agreement among raft nodes before linearized reading' (duration: 214.620818ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:25:08.392682Z","caller":"traceutil/trace.go:171","msg":"trace[240361647] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8334; }","duration":"194.991087ms","start":"2026-05-26T21:25:08.197666Z","end":"2026-05-26T21:25:08.392657Z","steps":["trace[240361647] 'agreement among raft nodes before linearized reading' (duration: 194.688832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:25:09.069633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.144068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T21:25:09.069855Z","caller":"traceutil/trace.go:171","msg":"trace[727128378] transaction","detail":"{read_only:false; response_revision:8338; number_of_response:1; }","duration":"345.226949ms","start":"2026-05-26T21:25:08.724594Z","end":"2026-05-26T21:25:09.069821Z","steps":["trace[727128378] 'process raft request' (duration: 102.760854ms)","trace[727128378] 'compare' (duration: 242.031926ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:25:09.070117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:25:08.724571Z","time spent":"345.476013ms","remote":"127.0.0.1:34660","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-26T21:25:09.161295Z","caller":"traceutil/trace.go:171","msg":"trace[1766889348] linearizableReadLoop","detail":"{readStateIndex:8635; appliedIndex:8632; }","duration":"223.448816ms","start":"2026-05-26T21:25:08.937811Z","end":"2026-05-26T21:25:09.16126Z","steps":["trace[1766889348] 'read index received' (duration: 93.891547ms)","trace[1766889348] 'applied index is now lower than readState.Index' (duration: 129.556349ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:25:09.161339Z","caller":"traceutil/trace.go:171","msg":"trace[1272663024] transaction","detail":"{read_only:false; response_revision:8339; number_of_response:1; }","duration":"258.041229ms","start":"2026-05-26T21:25:08.90326Z","end":"2026-05-26T21:25:09.161301Z","steps":["trace[1272663024] 'process raft request' (duration: 257.893566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:25:09.161562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.732811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6pj6v\" ","response":"range_response_count:1 size:6516"} {"level":"info","ts":"2026-05-26T21:25:09.16166Z","caller":"traceutil/trace.go:171","msg":"trace[1532709174] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6pj6v; range_end:; response_count:1; response_revision:8339; }","duration":"223.874594ms","start":"2026-05-26T21:25:08.937761Z","end":"2026-05-26T21:25:09.161635Z","steps":["trace[1532709174] 'agreement among raft nodes before linearized reading' (duration: 223.613229ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:25:37.475871Z","caller":"traceutil/trace.go:171","msg":"trace[1083233503] transaction","detail":"{read_only:false; response_revision:8544; number_of_response:1; }","duration":"208.437371ms","start":"2026-05-26T21:25:37.267382Z","end":"2026-05-26T21:25:37.47582Z","steps":["trace[1083233503] 'process raft request' (duration: 170.354792ms)","trace[1083233503] 'compare' (duration: 37.782264ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:25:37.475973Z","caller":"traceutil/trace.go:171","msg":"trace[2071140998] linearizableReadLoop","detail":"{readStateIndex:8847; appliedIndex:8846; }","duration":"138.534039ms","start":"2026-05-26T21:25:37.337285Z","end":"2026-05-26T21:25:37.475819Z","steps":["trace[2071140998] 'read index received' (duration: 100.47476ms)","trace[2071140998] 'applied index is now lower than readState.Index' (duration: 38.056769ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:25:37.476381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.061979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6pj6v\" ","response":"range_response_count:1 size:6516"} {"level":"info","ts":"2026-05-26T21:25:37.476435Z","caller":"traceutil/trace.go:171","msg":"trace[1238889626] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6pj6v; range_end:; response_count:1; response_revision:8544; }","duration":"139.176941ms","start":"2026-05-26T21:25:37.33724Z","end":"2026-05-26T21:25:37.476416Z","steps":["trace[1238889626] 'agreement among raft nodes before linearized reading' (duration: 138.914796ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:25:37.685735Z","caller":"traceutil/trace.go:171","msg":"trace[635082593] transaction","detail":"{read_only:false; response_revision:8545; number_of_response:1; }","duration":"195.387274ms","start":"2026-05-26T21:25:37.49003Z","end":"2026-05-26T21:25:37.685418Z","steps":["trace[635082593] 'process raft request' (duration: 195.16835ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:25:41.043641Z","caller":"traceutil/trace.go:171","msg":"trace[222389398] transaction","detail":"{read_only:false; response_revision:8562; number_of_response:1; }","duration":"103.416605ms","start":"2026-05-26T21:25:40.94019Z","end":"2026-05-26T21:25:41.043607Z","steps":["trace[222389398] 'process raft request' (duration: 103.187401ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:26:11.270495Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023fc.wal"} {"level":"info","ts":"2026-05-26T21:27:42.589911Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"66f7b57d56ad567d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-26T21:27:42.59565Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-26T21:27:42.595825Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-26T21:30:00.820606Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8298} {"level":"info","ts":"2026-05-26T21:30:00.906873Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8298,"took":"83.060801ms","hash":1379880843,"current-db-size-bytes":58499072,"current-db-size":"58 MB","current-db-size-in-use-bytes":26501120,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-26T21:30:00.906947Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1379880843,"revision":8298,"compact-revision":6105} {"level":"info","ts":"2026-05-26T21:30:34.455221Z","caller":"traceutil/trace.go:171","msg":"trace[1178413018] transaction","detail":"{read_only:false; response_revision:11165; number_of_response:1; }","duration":"150.445443ms","start":"2026-05-26T21:30:34.304733Z","end":"2026-05-26T21:30:34.455178Z","steps":["trace[1178413018] 'process raft request' (duration: 150.237719ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:35:00.826723Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10782} {"level":"info","ts":"2026-05-26T21:35:00.909233Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10782,"took":"79.477927ms","hash":2082836005,"current-db-size-bytes":58499072,"current-db-size":"58 MB","current-db-size-in-use-bytes":27172864,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-26T21:35:00.909308Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2082836005,"revision":10782,"compact-revision":8298} {"level":"info","ts":"2026-05-26T21:37:59.155434Z","caller":"traceutil/trace.go:171","msg":"trace[1981303378] linearizableReadLoop","detail":"{readStateIndex:14673; appliedIndex:14672; }","duration":"169.517353ms","start":"2026-05-26T21:37:58.985887Z","end":"2026-05-26T21:37:59.155404Z","steps":["trace[1981303378] 'read index received' (duration: 169.330719ms)","trace[1981303378] 'applied index is now lower than readState.Index' (duration: 185.354µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:37:59.155557Z","caller":"traceutil/trace.go:171","msg":"trace[1376599351] transaction","detail":"{read_only:false; response_revision:14187; number_of_response:1; }","duration":"276.201495ms","start":"2026-05-26T21:37:58.879313Z","end":"2026-05-26T21:37:59.155514Z","steps":["trace[1376599351] 'process raft request' (duration: 275.908359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:37:59.15578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.878729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-26T21:37:59.155835Z","caller":"traceutil/trace.go:171","msg":"trace[722539947] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:14187; }","duration":"169.985881ms","start":"2026-05-26T21:37:58.985835Z","end":"2026-05-26T21:37:59.15582Z","steps":["trace[722539947] 'agreement among raft nodes before linearized reading' (duration: 169.680196ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:39:14.877537Z","caller":"traceutil/trace.go:171","msg":"trace[1171827045] transaction","detail":"{read_only:false; response_revision:15151; number_of_response:1; }","duration":"103.105172ms","start":"2026-05-26T21:39:14.774397Z","end":"2026-05-26T21:39:14.877502Z","steps":["trace[1171827045] 'process raft request' (duration: 102.839336ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:39:14.997416Z","caller":"traceutil/trace.go:171","msg":"trace[1920081236] transaction","detail":"{read_only:false; response_revision:15155; number_of_response:1; }","duration":"110.775187ms","start":"2026-05-26T21:39:14.886588Z","end":"2026-05-26T21:39:14.997364Z","steps":["trace[1920081236] 'process raft request' (duration: 110.651945ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:39:14.997482Z","caller":"traceutil/trace.go:171","msg":"trace[1304706083] transaction","detail":"{read_only:false; response_revision:15153; number_of_response:1; }","duration":"113.166473ms","start":"2026-05-26T21:39:14.884302Z","end":"2026-05-26T21:39:14.997468Z","steps":["trace[1304706083] 'process raft request' (duration: 112.732565ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:39:14.997399Z","caller":"traceutil/trace.go:171","msg":"trace[1692642956] transaction","detail":"{read_only:false; response_revision:15154; number_of_response:1; }","duration":"112.973918ms","start":"2026-05-26T21:39:14.884385Z","end":"2026-05-26T21:39:14.997359Z","steps":["trace[1692642956] 'process raft request' (duration: 112.812475ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:39:15.286032Z","caller":"traceutil/trace.go:171","msg":"trace[63535422] transaction","detail":"{read_only:false; response_revision:15156; number_of_response:1; }","duration":"180.395184ms","start":"2026-05-26T21:39:15.105605Z","end":"2026-05-26T21:39:15.286Z","steps":["trace[63535422] 'process raft request' (duration: 178.628901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:39:15.695496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.743514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:39:15.695615Z","caller":"traceutil/trace.go:171","msg":"trace[2045389247] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15156; }","duration":"271.908288ms","start":"2026-05-26T21:39:15.423676Z","end":"2026-05-26T21:39:15.695584Z","steps":["trace[2045389247] 'range keys from in-memory index tree' (duration: 271.643102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:39:15.695914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.767166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:39:15.696008Z","caller":"traceutil/trace.go:171","msg":"trace[2052418706] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15156; }","duration":"214.912668ms","start":"2026-05-26T21:39:15.481076Z","end":"2026-05-26T21:39:15.695989Z","steps":["trace[2052418706] 'range keys from in-memory index tree' (duration: 214.691284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:39:15.696009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.817977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-26T21:39:15.696053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.793425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-05-26T21:39:15.696096Z","caller":"traceutil/trace.go:171","msg":"trace[966859724] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15156; }","duration":"214.942818ms","start":"2026-05-26T21:39:15.48113Z","end":"2026-05-26T21:39:15.696073Z","steps":["trace[966859724] 'range keys from in-memory index tree' (duration: 214.726354ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:39:15.69615Z","caller":"traceutil/trace.go:171","msg":"trace[1613025219] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15156; }","duration":"214.939238ms","start":"2026-05-26T21:39:15.481189Z","end":"2026-05-26T21:39:15.696128Z","steps":["trace[1613025219] 'range keys from in-memory index tree' (duration: 214.535461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:39:15.696091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.828442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:39:15.696308Z","caller":"traceutil/trace.go:171","msg":"trace[1134534570] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:15156; }","duration":"154.097797ms","start":"2026-05-26T21:39:15.542183Z","end":"2026-05-26T21:39:15.69628Z","steps":["trace[1134534570] 'count revisions from in-memory index tree' (duration: 153.730951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:39:15.697191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.931918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:39:15.697365Z","caller":"traceutil/trace.go:171","msg":"trace[626268162] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15156; }","duration":"216.147561ms","start":"2026-05-26T21:39:15.481193Z","end":"2026-05-26T21:39:15.697341Z","steps":["trace[626268162] 'range keys from in-memory index tree' (duration: 214.932349ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:40:00.832796Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12855} {"level":"info","ts":"2026-05-26T21:40:00.911641Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12855,"took":"76.040569ms","hash":3909916557,"current-db-size-bytes":58499072,"current-db-size":"58 MB","current-db-size-in-use-bytes":30474240,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-26T21:40:00.911698Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3909916557,"revision":12855,"compact-revision":10782} {"level":"info","ts":"2026-05-26T21:45:00.839119Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15385} {"level":"info","ts":"2026-05-26T21:45:00.938338Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15385,"took":"92.806814ms","hash":4111229147,"current-db-size-bytes":58499072,"current-db-size":"58 MB","current-db-size-in-use-bytes":32567296,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-26T21:45:00.938421Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4111229147,"revision":15385,"compact-revision":12855} {"level":"info","ts":"2026-05-26T21:45:22.133429Z","caller":"traceutil/trace.go:171","msg":"trace[2001199976] transaction","detail":"{read_only:false; response_revision:17919; number_of_response:1; }","duration":"193.555419ms","start":"2026-05-26T21:45:21.939849Z","end":"2026-05-26T21:45:22.133404Z","steps":["trace[2001199976] 'process raft request' (duration: 193.409626ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:45:22.147584Z","caller":"traceutil/trace.go:171","msg":"trace[639480412] transaction","detail":"{read_only:false; response_revision:17920; number_of_response:1; }","duration":"201.959037ms","start":"2026-05-26T21:45:21.945587Z","end":"2026-05-26T21:45:22.147547Z","steps":["trace[639480412] 'process raft request' (duration: 201.754453ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:45:22.542823Z","caller":"traceutil/trace.go:171","msg":"trace[2074447940] transaction","detail":"{read_only:false; response_revision:17921; number_of_response:1; }","duration":"250.693289ms","start":"2026-05-26T21:45:22.292109Z","end":"2026-05-26T21:45:22.542802Z","steps":["trace[2074447940] 'process raft request' (duration: 250.497085ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:45:22.584238Z","caller":"traceutil/trace.go:171","msg":"trace[150792163] linearizableReadLoop","detail":"{readStateIndex:18526; appliedIndex:18525; }","duration":"159.26879ms","start":"2026-05-26T21:45:22.424951Z","end":"2026-05-26T21:45:22.58422Z","steps":["trace[150792163] 'read index received' (duration: 118.157844ms)","trace[150792163] 'applied index is now lower than readState.Index' (duration: 41.109106ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:45:22.584418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.422604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:45:22.584397Z","caller":"traceutil/trace.go:171","msg":"trace[713605541] transaction","detail":"{read_only:false; response_revision:17922; number_of_response:1; }","duration":"182.47413ms","start":"2026-05-26T21:45:22.401869Z","end":"2026-05-26T21:45:22.584343Z","steps":["trace[713605541] 'process raft request' (duration: 182.232655ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:45:22.584495Z","caller":"traceutil/trace.go:171","msg":"trace[1788823456] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17922; }","duration":"159.547877ms","start":"2026-05-26T21:45:22.424909Z","end":"2026-05-26T21:45:22.584457Z","steps":["trace[1788823456] 'agreement among raft nodes before linearized reading' (duration: 159.386274ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:45:23.149682Z","caller":"traceutil/trace.go:171","msg":"trace[2109694842] linearizableReadLoop","detail":"{readStateIndex:18529; appliedIndex:18528; }","duration":"458.939306ms","start":"2026-05-26T21:45:22.690713Z","end":"2026-05-26T21:45:23.149652Z","steps":["trace[2109694842] 'read index received' (duration: 458.673521ms)","trace[2109694842] 'applied index is now lower than readState.Index' (duration: 264.735µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:45:23.150394Z","caller":"traceutil/trace.go:171","msg":"trace[2020109558] transaction","detail":"{read_only:false; response_revision:17925; number_of_response:1; }","duration":"479.78433ms","start":"2026-05-26T21:45:22.670571Z","end":"2026-05-26T21:45:23.150355Z","steps":["trace[2020109558] 'process raft request' (duration: 478.894133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:45:23.150415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.404407ms","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-26T21:45:23.150478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.767702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-heat-server\" ","response":"range_response_count:1 size:9509"} {"level":"warn","ts":"2026-05-26T21:45:23.150508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.510638ms","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-26T21:45:23.15053Z","caller":"traceutil/trace.go:171","msg":"trace[1418943136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17925; }","duration":"239.549298ms","start":"2026-05-26T21:45:22.910973Z","end":"2026-05-26T21:45:23.150522Z","steps":["trace[1418943136] 'agreement among raft nodes before linearized reading' (duration: 239.495727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:45:23.150535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.007024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:45:23.150533Z","caller":"traceutil/trace.go:171","msg":"trace[1346427840] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-heat-server; range_end:; response_count:1; response_revision:17925; }","duration":"459.867584ms","start":"2026-05-26T21:45:22.690651Z","end":"2026-05-26T21:45:23.150519Z","steps":["trace[1346427840] 'agreement among raft nodes before linearized reading' (duration: 459.65247ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:45:23.150558Z","caller":"traceutil/trace.go:171","msg":"trace[1496135802] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:17925; }","duration":"280.081675ms","start":"2026-05-26T21:45:22.870468Z","end":"2026-05-26T21:45:23.15055Z","steps":["trace[1496135802] 'agreement among raft nodes before linearized reading' (duration: 280.039094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:45:23.150566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:45:22.670521Z","time spent":"479.947053ms","remote":"127.0.0.1:34660","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-26T21:45:23.150563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.477897ms","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-26T21:45:23.150487Z","caller":"traceutil/trace.go:171","msg":"trace[242971153] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17925; }","duration":"421.510118ms","start":"2026-05-26T21:45:22.728958Z","end":"2026-05-26T21:45:23.150469Z","steps":["trace[242971153] 'agreement among raft nodes before linearized reading' (duration: 421.272074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:45:23.150677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.086196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-gs9tp\" ","response":"range_response_count:1 size:22118"} {"level":"info","ts":"2026-05-26T21:45:23.150691Z","caller":"traceutil/trace.go:171","msg":"trace[1857088473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17925; }","duration":"239.64684ms","start":"2026-05-26T21:45:22.911014Z","end":"2026-05-26T21:45:23.150661Z","steps":["trace[1857088473] 'agreement among raft nodes before linearized reading' (duration: 239.236052ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:45:23.150714Z","caller":"traceutil/trace.go:171","msg":"trace[311180541] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-gs9tp; range_end:; response_count:1; response_revision:17925; }","duration":"188.154068ms","start":"2026-05-26T21:45:22.962551Z","end":"2026-05-26T21:45:23.150705Z","steps":["trace[311180541] 'agreement among raft nodes before linearized reading' (duration: 188.047586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:45:23.15072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:45:22.728946Z","time spent":"421.758843ms","remote":"127.0.0.1:34660","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-26T21:45:23.150571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:45:22.690629Z","time spent":"459.929985ms","remote":"127.0.0.1:34868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":9532,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-heat-server\" "} {"level":"warn","ts":"2026-05-26T21:45:23.150444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.758574ms","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-26T21:45:23.150945Z","caller":"traceutil/trace.go:171","msg":"trace[163735927] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17925; }","duration":"248.332954ms","start":"2026-05-26T21:45:22.902601Z","end":"2026-05-26T21:45:23.150934Z","steps":["trace[163735927] 'agreement among raft nodes before linearized reading' (duration: 247.692642ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:48:19.009492Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"66f7b57d56ad567d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-26T21:48:19.015432Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-26T21:48:19.015547Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-26T21:50:00.845756Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17746} {"level":"info","ts":"2026-05-26T21:50:00.948958Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17746,"took":"96.412232ms","hash":3350299728,"current-db-size-bytes":58499072,"current-db-size":"58 MB","current-db-size-in-use-bytes":34312192,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-26T21:50:00.949028Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3350299728,"revision":17746,"compact-revision":15385} {"level":"info","ts":"2026-05-26T21:50:36.870313Z","caller":"traceutil/trace.go:171","msg":"trace[150251627] transaction","detail":"{read_only:false; response_revision:20335; number_of_response:1; }","duration":"145.341227ms","start":"2026-05-26T21:50:36.724926Z","end":"2026-05-26T21:50:36.870267Z","steps":["trace[150251627] 'process raft request' (duration: 59.26011ms)","trace[150251627] 'compare' (duration: 85.768501ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:50:37.152109Z","caller":"traceutil/trace.go:171","msg":"trace[162637412] transaction","detail":"{read_only:false; response_revision:20336; number_of_response:1; }","duration":"276.835602ms","start":"2026-05-26T21:50:36.87523Z","end":"2026-05-26T21:50:37.152065Z","steps":["trace[162637412] 'process raft request' (duration: 193.232092ms)","trace[162637412] 'compare' (duration: 83.376546ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:50:37.152957Z","caller":"traceutil/trace.go:171","msg":"trace[884205819] transaction","detail":"{read_only:false; response_revision:20338; number_of_response:1; }","duration":"277.036746ms","start":"2026-05-26T21:50:36.875887Z","end":"2026-05-26T21:50:37.152924Z","steps":["trace[884205819] 'process raft request' (duration: 276.948674ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:50:37.152955Z","caller":"traceutil/trace.go:171","msg":"trace[1546985255] transaction","detail":"{read_only:false; response_revision:20337; number_of_response:1; }","duration":"277.460544ms","start":"2026-05-26T21:50:36.875456Z","end":"2026-05-26T21:50:37.152917Z","steps":["trace[1546985255] 'process raft request' (duration: 277.308241ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:50:37.153185Z","caller":"traceutil/trace.go:171","msg":"trace[1144262285] transaction","detail":"{read_only:false; response_revision:20339; number_of_response:1; }","duration":"277.177189ms","start":"2026-05-26T21:50:36.875952Z","end":"2026-05-26T21:50:37.153129Z","steps":["trace[1144262285] 'process raft request' (duration: 276.924404ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:54:30.633735Z","caller":"traceutil/trace.go:171","msg":"trace[1990818379] linearizableReadLoop","detail":"{readStateIndex:23482; appliedIndex:23481; }","duration":"244.300038ms","start":"2026-05-26T21:54:30.389385Z","end":"2026-05-26T21:54:30.633685Z","steps":["trace[1990818379] 'read index received' (duration: 147.511459ms)","trace[1990818379] 'applied index is now lower than readState.Index' (duration: 96.786889ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:54:30.633881Z","caller":"traceutil/trace.go:171","msg":"trace[519116658] transaction","detail":"{read_only:false; response_revision:22725; number_of_response:1; }","duration":"370.675266ms","start":"2026-05-26T21:54:30.263133Z","end":"2026-05-26T21:54:30.633808Z","steps":["trace[519116658] 'process raft request' (duration: 273.722724ms)","trace[519116658] 'compare' (duration: 96.634966ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:54:30.6341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:54:30.263102Z","time spent":"370.856739ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:54:30.634182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.752096ms","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-26T21:54:30.634238Z","caller":"traceutil/trace.go:171","msg":"trace[1001197585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22725; }","duration":"244.913818ms","start":"2026-05-26T21:54:30.389308Z","end":"2026-05-26T21:54:30.634221Z","steps":["trace[1001197585] 'agreement among raft nodes before linearized reading' (duration: 244.620813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:54:30.634298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.642923ms","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-26T21:54:30.634425Z","caller":"traceutil/trace.go:171","msg":"trace[1889097578] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22725; }","duration":"244.836037ms","start":"2026-05-26T21:54:30.38956Z","end":"2026-05-26T21:54:30.634396Z","steps":["trace[1889097578] 'agreement among raft nodes before linearized reading' (duration: 244.46285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:54:30.634436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.390618ms","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-26T21:54:30.634317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.684608ms","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-26T21:54:30.634487Z","caller":"traceutil/trace.go:171","msg":"trace[1036227303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22725; }","duration":"244.47913ms","start":"2026-05-26T21:54:30.389991Z","end":"2026-05-26T21:54:30.63447Z","steps":["trace[1036227303] 'agreement among raft nodes before linearized reading' (duration: 244.352508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:54:30.634456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.008087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:54:30.634524Z","caller":"traceutil/trace.go:171","msg":"trace[1999236515] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22725; }","duration":"240.942944ms","start":"2026-05-26T21:54:30.393546Z","end":"2026-05-26T21:54:30.634489Z","steps":["trace[1999236515] 'agreement among raft nodes before linearized reading' (duration: 240.511575ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:54:30.634553Z","caller":"traceutil/trace.go:171","msg":"trace[1773956476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22725; }","duration":"211.155971ms","start":"2026-05-26T21:54:30.423376Z","end":"2026-05-26T21:54:30.634532Z","steps":["trace[1773956476] 'agreement among raft nodes before linearized reading' (duration: 210.952906ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:55:00.853168Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20133} {"level":"info","ts":"2026-05-26T21:55:00.965741Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20133,"took":"109.398648ms","hash":3295062707,"current-db-size-bytes":58499072,"current-db-size":"58 MB","current-db-size-in-use-bytes":40849408,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-05-26T21:55:00.96581Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3295062707,"revision":20133,"compact-revision":17746} {"level":"warn","ts":"2026-05-26T21:58:05.656353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.256493ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T21:58:05.65679Z","caller":"traceutil/trace.go:171","msg":"trace[1458999529] transaction","detail":"{read_only:false; response_revision:24903; number_of_response:1; }","duration":"186.114384ms","start":"2026-05-26T21:58:05.470622Z","end":"2026-05-26T21:58:05.656737Z","steps":["trace[1458999529] 'process raft request' (duration: 24.365982ms)","trace[1458999529] 'compare' (duration: 161.057599ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:06.29643Z","caller":"traceutil/trace.go:171","msg":"trace[1574092695] transaction","detail":"{read_only:false; response_revision:24910; number_of_response:1; }","duration":"255.236202ms","start":"2026-05-26T21:58:06.041159Z","end":"2026-05-26T21:58:06.296395Z","steps":["trace[1574092695] 'process raft request' (duration: 254.977777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:06.525941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.886399ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T21:58:06.526045Z","caller":"traceutil/trace.go:171","msg":"trace[966917500] linearizableReadLoop","detail":"{readStateIndex:25723; appliedIndex:25721; }","duration":"399.996404ms","start":"2026-05-26T21:58:06.126032Z","end":"2026-05-26T21:58:06.526029Z","steps":["trace[966917500] 'read index received' (duration: 170.222853ms)","trace[966917500] 'applied index is now lower than readState.Index' (duration: 229.772621ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:06.526207Z","caller":"traceutil/trace.go:171","msg":"trace[748387032] transaction","detail":"{read_only:false; response_revision:24911; number_of_response:1; }","duration":"484.967192ms","start":"2026-05-26T21:58:06.041229Z","end":"2026-05-26T21:58:06.526196Z","steps":["trace[748387032] 'process raft request' (duration: 354.728056ms)","trace[748387032] 'compare' (duration: 129.658405ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:06.526259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:06.041184Z","time spent":"485.052554ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:58:06.526328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.261359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:06.526462Z","caller":"traceutil/trace.go:171","msg":"trace[1347971973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24911; }","duration":"400.457212ms","start":"2026-05-26T21:58:06.125972Z","end":"2026-05-26T21:58:06.526429Z","steps":["trace[1347971973] 'agreement among raft nodes before linearized reading' (duration: 400.243018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:06.526524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:06.125945Z","time spent":"400.564284ms","remote":"127.0.0.1:34424","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-26T21:58:06.538916Z","caller":"traceutil/trace.go:171","msg":"trace[1138313108] transaction","detail":"{read_only:false; response_revision:24912; number_of_response:1; }","duration":"113.046979ms","start":"2026-05-26T21:58:06.425851Z","end":"2026-05-26T21:58:06.538898Z","steps":["trace[1138313108] 'process raft request' (duration: 112.871436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:06.538984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.971984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:06.539014Z","caller":"traceutil/trace.go:171","msg":"trace[1034956170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24912; }","duration":"117.049155ms","start":"2026-05-26T21:58:06.421956Z","end":"2026-05-26T21:58:06.539005Z","steps":["trace[1034956170] 'agreement among raft nodes before linearized reading' (duration: 116.986054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:06.539144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.765204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-26T21:58:06.539244Z","caller":"traceutil/trace.go:171","msg":"trace[428624752] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:24912; }","duration":"263.889187ms","start":"2026-05-26T21:58:06.275331Z","end":"2026-05-26T21:58:06.53922Z","steps":["trace[428624752] 'agreement among raft nodes before linearized reading' (duration: 263.54817ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:11.304127Z","caller":"traceutil/trace.go:171","msg":"trace[1217605022] linearizableReadLoop","detail":"{readStateIndex:25755; appliedIndex:25754; }","duration":"120.40342ms","start":"2026-05-26T21:58:11.183694Z","end":"2026-05-26T21:58:11.304098Z","steps":["trace[1217605022] 'read index received' (duration: 120.206796ms)","trace[1217605022] 'applied index is now lower than readState.Index' (duration: 195.924µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:11.304216Z","caller":"traceutil/trace.go:171","msg":"trace[408824708] transaction","detail":"{read_only:false; response_revision:24942; number_of_response:1; }","duration":"168.305826ms","start":"2026-05-26T21:58:11.135867Z","end":"2026-05-26T21:58:11.304173Z","steps":["trace[408824708] 'process raft request' (duration: 168.081082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:11.304318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.608443ms","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-26T21:58:11.30434Z","caller":"traceutil/trace.go:171","msg":"trace[879468353] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24942; }","duration":"120.690385ms","start":"2026-05-26T21:58:11.183643Z","end":"2026-05-26T21:58:11.304333Z","steps":["trace[879468353] 'agreement among raft nodes before linearized reading' (duration: 120.551912ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:11.530582Z","caller":"traceutil/trace.go:171","msg":"trace[464373038] transaction","detail":"{read_only:false; response_revision:24943; number_of_response:1; }","duration":"221.535024ms","start":"2026-05-26T21:58:11.308998Z","end":"2026-05-26T21:58:11.530533Z","steps":["trace[464373038] 'process raft request' (duration: 135.18415ms)","trace[464373038] 'compare' (duration: 86.195761ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:11.77336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.163711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1862"} {"level":"info","ts":"2026-05-26T21:58:11.773464Z","caller":"traceutil/trace.go:171","msg":"trace[1011932504] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:24943; }","duration":"124.327694ms","start":"2026-05-26T21:58:11.64911Z","end":"2026-05-26T21:58:11.773438Z","steps":["trace[1011932504] 'range keys from in-memory index tree' (duration: 123.995008ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:12.105245Z","caller":"traceutil/trace.go:171","msg":"trace[648007331] transaction","detail":"{read_only:false; response_revision:24947; number_of_response:1; }","duration":"315.948063ms","start":"2026-05-26T21:58:11.789263Z","end":"2026-05-26T21:58:12.105211Z","steps":["trace[648007331] 'process raft request' (duration: 315.105596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:12.105443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:11.78924Z","time spent":"316.102445ms","remote":"127.0.0.1:34660","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-26T21:58:12.105665Z","caller":"traceutil/trace.go:171","msg":"trace[1044364306] linearizableReadLoop","detail":"{readStateIndex:25761; appliedIndex:25759; }","duration":"223.840718ms","start":"2026-05-26T21:58:11.881793Z","end":"2026-05-26T21:58:12.105633Z","steps":["trace[1044364306] 'read index received' (duration: 222.648596ms)","trace[1044364306] 'applied index is now lower than readState.Index' (duration: 1.190402ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:12.105822Z","caller":"traceutil/trace.go:171","msg":"trace[816829014] transaction","detail":"{read_only:false; response_revision:24948; number_of_response:1; }","duration":"316.111246ms","start":"2026-05-26T21:58:11.7897Z","end":"2026-05-26T21:58:12.105811Z","steps":["trace[816829014] 'process raft request' (duration: 315.741169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:12.105954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:11.789685Z","time spent":"316.161486ms","remote":"127.0.0.1:34660","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-26T21:58:12.105967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.323963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-26T21:58:12.105979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.133583ms","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-26T21:58:12.106056Z","caller":"traceutil/trace.go:171","msg":"trace[83360960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24948; }","duration":"224.253206ms","start":"2026-05-26T21:58:11.881784Z","end":"2026-05-26T21:58:12.106037Z","steps":["trace[83360960] 'agreement among raft nodes before linearized reading' (duration: 224.068722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:12.106197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.787141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:12.106255Z","caller":"traceutil/trace.go:171","msg":"trace[316263722] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:24948; }","duration":"210.858773ms","start":"2026-05-26T21:58:11.895375Z","end":"2026-05-26T21:58:12.106234Z","steps":["trace[316263722] 'agreement among raft nodes before linearized reading' (duration: 210.772782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:12.106271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.478361ms","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-26T21:58:12.106326Z","caller":"traceutil/trace.go:171","msg":"trace[862103284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24948; }","duration":"224.577113ms","start":"2026-05-26T21:58:11.881739Z","end":"2026-05-26T21:58:12.106316Z","steps":["trace[862103284] 'agreement among raft nodes before linearized reading' (duration: 224.458601ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:12.106014Z","caller":"traceutil/trace.go:171","msg":"trace[813733590] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24948; }","duration":"144.415174ms","start":"2026-05-26T21:58:11.961585Z","end":"2026-05-26T21:58:12.106Z","steps":["trace[813733590] 'agreement among raft nodes before linearized reading' (duration: 144.327852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:16.356045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.550297ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T21:58:16.356173Z","caller":"traceutil/trace.go:171","msg":"trace[2014445450] linearizableReadLoop","detail":"{readStateIndex:25795; appliedIndex:25793; }","duration":"229.990224ms","start":"2026-05-26T21:58:16.12616Z","end":"2026-05-26T21:58:16.35615Z","steps":["trace[2014445450] 'read index received' (duration: 7.719486ms)","trace[2014445450] 'applied index is now lower than readState.Index' (duration: 222.269438ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:16.356261Z","caller":"traceutil/trace.go:171","msg":"trace[1485801628] transaction","detail":"{read_only:false; response_revision:24981; number_of_response:1; }","duration":"230.27904ms","start":"2026-05-26T21:58:16.125938Z","end":"2026-05-26T21:58:16.356217Z","steps":["trace[1485801628] 'process raft request' (duration: 65.427088ms)","trace[1485801628] 'compare' (duration: 164.386783ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:16.356372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.207558ms","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-26T21:58:16.356414Z","caller":"traceutil/trace.go:171","msg":"trace[1970670232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24981; }","duration":"230.249029ms","start":"2026-05-26T21:58:16.126154Z","end":"2026-05-26T21:58:16.356403Z","steps":["trace[1970670232] 'agreement among raft nodes before linearized reading' (duration: 230.098216ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:16.450344Z","caller":"traceutil/trace.go:171","msg":"trace[2008871071] transaction","detail":"{read_only:false; response_revision:24982; number_of_response:1; }","duration":"114.566709ms","start":"2026-05-26T21:58:16.335724Z","end":"2026-05-26T21:58:16.45029Z","steps":["trace[2008871071] 'process raft request' (duration: 114.250954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:16.450383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.956613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:16.450498Z","caller":"traceutil/trace.go:171","msg":"trace[3668885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24982; }","duration":"324.105287ms","start":"2026-05-26T21:58:16.126355Z","end":"2026-05-26T21:58:16.45046Z","steps":["trace[3668885] 'agreement among raft nodes before linearized reading' (duration: 323.882362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:16.450563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:16.126336Z","time spent":"324.216058ms","remote":"127.0.0.1:34424","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-26T21:58:16.450567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.175708ms","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-26T21:58:16.450687Z","caller":"traceutil/trace.go:171","msg":"trace[1566760809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24982; }","duration":"324.373701ms","start":"2026-05-26T21:58:16.126289Z","end":"2026-05-26T21:58:16.450663Z","steps":["trace[1566760809] 'agreement among raft nodes before linearized reading' (duration: 324.015355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:16.450746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:16.126255Z","time spent":"324.481213ms","remote":"127.0.0.1:34660","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-26T21:58:16.450558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.284339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-26T21:58:16.450873Z","caller":"traceutil/trace.go:171","msg":"trace[626880585] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24982; }","duration":"324.645546ms","start":"2026-05-26T21:58:16.126196Z","end":"2026-05-26T21:58:16.450842Z","steps":["trace[626880585] 'agreement among raft nodes before linearized reading' (duration: 324.165547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:16.450951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:16.126189Z","time spent":"324.743919ms","remote":"127.0.0.1:57622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-05-26T21:58:16.614337Z","caller":"traceutil/trace.go:171","msg":"trace[542031454] linearizableReadLoop","detail":"{readStateIndex:25798; appliedIndex:25797; }","duration":"120.817117ms","start":"2026-05-26T21:58:16.493457Z","end":"2026-05-26T21:58:16.614274Z","steps":["trace[542031454] 'read index received' (duration: 120.719325ms)","trace[542031454] 'applied index is now lower than readState.Index' (duration: 96.342µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:16.614494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.00488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:16.614585Z","caller":"traceutil/trace.go:171","msg":"trace[792615485] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:24984; }","duration":"121.161703ms","start":"2026-05-26T21:58:16.493407Z","end":"2026-05-26T21:58:16.614569Z","steps":["trace[792615485] 'agreement among raft nodes before linearized reading' (duration: 120.97886ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:16.614945Z","caller":"traceutil/trace.go:171","msg":"trace[652183929] transaction","detail":"{read_only:false; response_revision:24984; number_of_response:1; }","duration":"159.110732ms","start":"2026-05-26T21:58:16.455222Z","end":"2026-05-26T21:58:16.614332Z","steps":["trace[652183929] 'process raft request' (duration: 158.881578ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:16.786685Z","caller":"traceutil/trace.go:171","msg":"trace[1671923779] linearizableReadLoop","detail":"{readStateIndex:25800; appliedIndex:25798; }","duration":"172.224341ms","start":"2026-05-26T21:58:16.614429Z","end":"2026-05-26T21:58:16.786653Z","steps":["trace[1671923779] 'read index received' (duration: 167.601944ms)","trace[1671923779] 'applied index is now lower than readState.Index' (duration: 4.619967ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:16.786813Z","caller":"traceutil/trace.go:171","msg":"trace[198383697] transaction","detail":"{read_only:false; response_revision:24985; number_of_response:1; }","duration":"199.368925ms","start":"2026-05-26T21:58:16.587386Z","end":"2026-05-26T21:58:16.786755Z","steps":["trace[198383697] 'process raft request' (duration: 194.699777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:16.786898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.430623ms","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":"info","ts":"2026-05-26T21:58:16.786951Z","caller":"traceutil/trace.go:171","msg":"trace[1465351683] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:24986; }","duration":"249.544336ms","start":"2026-05-26T21:58:16.537389Z","end":"2026-05-26T21:58:16.786933Z","steps":["trace[1465351683] 'agreement among raft nodes before linearized reading' (duration: 249.387692ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:16.787199Z","caller":"traceutil/trace.go:171","msg":"trace[1363529563] transaction","detail":"{read_only:false; response_revision:24986; number_of_response:1; }","duration":"198.965777ms","start":"2026-05-26T21:58:16.588152Z","end":"2026-05-26T21:58:16.787118Z","steps":["trace[1363529563] 'process raft request' (duration: 198.400566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:16.787351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.429142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-05-26T21:58:16.787493Z","caller":"traceutil/trace.go:171","msg":"trace[1464067864] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:24986; }","duration":"183.622706ms","start":"2026-05-26T21:58:16.603831Z","end":"2026-05-26T21:58:16.787454Z","steps":["trace[1464067864] 'agreement among raft nodes before linearized reading' (duration: 183.2519ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:17.450225Z","caller":"traceutil/trace.go:171","msg":"trace[167170332] transaction","detail":"{read_only:false; response_revision:24988; number_of_response:1; }","duration":"124.252962ms","start":"2026-05-26T21:58:17.325926Z","end":"2026-05-26T21:58:17.450179Z","steps":["trace[167170332] 'process raft request' (duration: 123.993767ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:17.651442Z","caller":"traceutil/trace.go:171","msg":"trace[434766215] transaction","detail":"{read_only:false; response_revision:24989; number_of_response:1; }","duration":"160.381277ms","start":"2026-05-26T21:58:17.491021Z","end":"2026-05-26T21:58:17.651402Z","steps":["trace[434766215] 'process raft request' (duration: 121.066953ms)","trace[434766215] 'compare' (duration: 38.775564ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:17.894959Z","caller":"traceutil/trace.go:171","msg":"trace[549158848] transaction","detail":"{read_only:false; response_revision:24990; number_of_response:1; }","duration":"239.331442ms","start":"2026-05-26T21:58:17.655593Z","end":"2026-05-26T21:58:17.894925Z","steps":["trace[549158848] 'process raft request' (duration: 239.144869ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:17.915719Z","caller":"traceutil/trace.go:171","msg":"trace[20974568] transaction","detail":"{read_only:false; response_revision:24992; number_of_response:1; }","duration":"122.311366ms","start":"2026-05-26T21:58:17.793331Z","end":"2026-05-26T21:58:17.915643Z","steps":["trace[20974568] 'process raft request' (duration: 122.196994ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:17.915729Z","caller":"traceutil/trace.go:171","msg":"trace[1911577276] transaction","detail":"{read_only:false; response_revision:24991; number_of_response:1; }","duration":"123.660882ms","start":"2026-05-26T21:58:17.791976Z","end":"2026-05-26T21:58:17.915637Z","steps":["trace[1911577276] 'process raft request' (duration: 123.435078ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:17.915883Z","caller":"traceutil/trace.go:171","msg":"trace[872274751] transaction","detail":"{read_only:false; response_revision:24993; number_of_response:1; }","duration":"119.560013ms","start":"2026-05-26T21:58:17.796288Z","end":"2026-05-26T21:58:17.915848Z","steps":["trace[872274751] 'process raft request' (duration: 119.299018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:18.251641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.03638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-26T21:58:18.251782Z","caller":"traceutil/trace.go:171","msg":"trace[2093674299] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24993; }","duration":"113.235184ms","start":"2026-05-26T21:58:18.13851Z","end":"2026-05-26T21:58:18.251745Z","steps":["trace[2093674299] 'range keys from in-memory index tree' (duration: 112.781706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:18.543617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.102966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:18.543725Z","caller":"traceutil/trace.go:171","msg":"trace[1031978845] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24994; }","duration":"118.244118ms","start":"2026-05-26T21:58:18.425457Z","end":"2026-05-26T21:58:18.543701Z","steps":["trace[1031978845] 'range keys from in-memory index tree' (duration: 118.024845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:18.544139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.446013ms","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-26T21:58:18.544197Z","caller":"traceutil/trace.go:171","msg":"trace[148962247] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24994; }","duration":"183.539715ms","start":"2026-05-26T21:58:18.360642Z","end":"2026-05-26T21:58:18.544182Z","steps":["trace[148962247] 'range keys from in-memory index tree' (duration: 183.29384ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:18.741468Z","caller":"traceutil/trace.go:171","msg":"trace[1872101998] linearizableReadLoop","detail":"{readStateIndex:25809; appliedIndex:25808; }","duration":"195.963581ms","start":"2026-05-26T21:58:18.545477Z","end":"2026-05-26T21:58:18.741441Z","steps":["trace[1872101998] 'read index received' (duration: 195.833658ms)","trace[1872101998] 'applied index is now lower than readState.Index' (duration: 128.583µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:18.741585Z","caller":"traceutil/trace.go:171","msg":"trace[60412042] transaction","detail":"{read_only:false; response_revision:24995; number_of_response:1; }","duration":"284.999447ms","start":"2026-05-26T21:58:18.456548Z","end":"2026-05-26T21:58:18.741547Z","steps":["trace[60412042] 'process raft request' (duration: 284.711471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:18.741643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.143934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:18.741709Z","caller":"traceutil/trace.go:171","msg":"trace[436108518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24995; }","duration":"196.231716ms","start":"2026-05-26T21:58:18.545461Z","end":"2026-05-26T21:58:18.741693Z","steps":["trace[436108518] 'agreement among raft nodes before linearized reading' (duration: 196.116884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:18.992033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.238ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T21:58:18.992227Z","caller":"traceutil/trace.go:171","msg":"trace[619716302] transaction","detail":"{read_only:false; response_revision:24996; number_of_response:1; }","duration":"444.432965ms","start":"2026-05-26T21:58:18.547764Z","end":"2026-05-26T21:58:18.992197Z","steps":["trace[619716302] 'process raft request' (duration: 308.94442ms)","trace[619716302] 'compare' (duration: 134.67337ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:18.992304Z","caller":"traceutil/trace.go:171","msg":"trace[1371078132] transaction","detail":"{read_only:false; response_revision:24999; number_of_response:1; }","duration":"198.257434ms","start":"2026-05-26T21:58:18.794029Z","end":"2026-05-26T21:58:18.992287Z","steps":["trace[1371078132] 'process raft request' (duration: 198.220693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:18.992393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:18.547748Z","time spent":"444.558637ms","remote":"127.0.0.1:34660","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-26T21:58:18.992577Z","caller":"traceutil/trace.go:171","msg":"trace[630197719] transaction","detail":"{read_only:false; response_revision:24997; number_of_response:1; }","duration":"443.957816ms","start":"2026-05-26T21:58:18.548609Z","end":"2026-05-26T21:58:18.992567Z","steps":["trace[630197719] 'process raft request' (duration: 443.523308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:18.992627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:18.548574Z","time spent":"444.027017ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:58:18.992744Z","caller":"traceutil/trace.go:171","msg":"trace[864675400] linearizableReadLoop","detail":"{readStateIndex:25811; appliedIndex:25809; }","duration":"251.191185ms","start":"2026-05-26T21:58:18.741544Z","end":"2026-05-26T21:58:18.992735Z","steps":["trace[864675400] 'read index received' (duration: 115.13921ms)","trace[864675400] 'applied index is now lower than readState.Index' (duration: 136.050915ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:18.992846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.915859ms","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-26T21:58:18.992865Z","caller":"traceutil/trace.go:171","msg":"trace[700616774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24999; }","duration":"373.98075ms","start":"2026-05-26T21:58:18.618877Z","end":"2026-05-26T21:58:18.992858Z","steps":["trace[700616774] 'agreement among raft nodes before linearized reading' (duration: 373.889719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:18.992893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:18.618851Z","time spent":"374.033212ms","remote":"127.0.0.1:34660","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-26T21:58:18.993016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.302779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:18.992883Z","caller":"traceutil/trace.go:171","msg":"trace[872432727] transaction","detail":"{read_only:false; response_revision:24998; number_of_response:1; }","duration":"199.199642ms","start":"2026-05-26T21:58:18.793668Z","end":"2026-05-26T21:58:18.992868Z","steps":["trace[872432727] 'process raft request' (duration: 198.55465ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:18.993044Z","caller":"traceutil/trace.go:171","msg":"trace[425503147] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:24999; }","duration":"315.380271ms","start":"2026-05-26T21:58:18.677654Z","end":"2026-05-26T21:58:18.993034Z","steps":["trace[425503147] 'agreement among raft nodes before linearized reading' (duration: 315.33857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:18.993064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:18.677628Z","time spent":"315.430912ms","remote":"127.0.0.1:44258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true "} {"level":"info","ts":"2026-05-26T21:58:19.293945Z","caller":"traceutil/trace.go:171","msg":"trace[1403576812] transaction","detail":"{read_only:false; response_revision:25001; number_of_response:1; }","duration":"295.593026ms","start":"2026-05-26T21:58:18.998261Z","end":"2026-05-26T21:58:19.293854Z","steps":["trace[1403576812] 'process raft request' (duration: 295.386653ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:19.294093Z","caller":"traceutil/trace.go:171","msg":"trace[1085331667] linearizableReadLoop","detail":"{readStateIndex:25815; appliedIndex:25815; }","duration":"181.766642ms","start":"2026-05-26T21:58:19.112294Z","end":"2026-05-26T21:58:19.29406Z","steps":["trace[1085331667] 'read index received' (duration: 181.756312ms)","trace[1085331667] 'applied index is now lower than readState.Index' (duration: 7.68µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:19.29426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.932945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:19.294307Z","caller":"traceutil/trace.go:171","msg":"trace[188723716] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:25001; }","duration":"182.042176ms","start":"2026-05-26T21:58:19.112251Z","end":"2026-05-26T21:58:19.294293Z","steps":["trace[188723716] 'agreement among raft nodes before linearized reading' (duration: 181.925454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:19.602691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.556914ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-26T21:58:19.603342Z","caller":"traceutil/trace.go:171","msg":"trace[1689844124] transaction","detail":"{read_only:false; response_revision:25002; number_of_response:1; }","duration":"144.199031ms","start":"2026-05-26T21:58:19.459104Z","end":"2026-05-26T21:58:19.603303Z","steps":["trace[1689844124] 'process raft request' (duration: 143.718902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:19.842561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.412976ms","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-26T21:58:19.842675Z","caller":"traceutil/trace.go:171","msg":"trace[2133279369] 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:25002; }","duration":"133.616829ms","start":"2026-05-26T21:58:19.709035Z","end":"2026-05-26T21:58:19.842651Z","steps":["trace[2133279369] 'count revisions from in-memory index tree' (duration: 133.229422ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:20.002273Z","caller":"traceutil/trace.go:171","msg":"trace[1098832792] transaction","detail":"{read_only:false; response_revision:25003; number_of_response:1; }","duration":"100.33105ms","start":"2026-05-26T21:58:19.90192Z","end":"2026-05-26T21:58:20.002251Z","steps":["trace[1098832792] 'process raft request' (duration: 100.197198ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:20.293792Z","caller":"traceutil/trace.go:171","msg":"trace[374753439] transaction","detail":"{read_only:false; response_revision:25004; number_of_response:1; }","duration":"373.323158ms","start":"2026-05-26T21:58:19.920413Z","end":"2026-05-26T21:58:20.293736Z","steps":["trace[374753439] 'process raft request' (duration: 356.226534ms)","trace[374753439] 'compare' (duration: 16.835389ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:20.29393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:19.920405Z","time spent":"373.45188ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:58:20.294157Z","caller":"traceutil/trace.go:171","msg":"trace[220000318] transaction","detail":"{read_only:false; response_revision:25005; number_of_response:1; }","duration":"373.398249ms","start":"2026-05-26T21:58:19.92072Z","end":"2026-05-26T21:58:20.294118Z","steps":["trace[220000318] 'process raft request' (duration: 372.984411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:20.294373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:19.920687Z","time spent":"373.583294ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:58:20.294465Z","caller":"traceutil/trace.go:171","msg":"trace[983472700] linearizableReadLoop","detail":"{readStateIndex:25821; appliedIndex:25818; }","duration":"103.410068ms","start":"2026-05-26T21:58:20.191047Z","end":"2026-05-26T21:58:20.294457Z","steps":["trace[983472700] 'read index received' (duration: 85.627821ms)","trace[983472700] 'applied index is now lower than readState.Index' (duration: 17.781747ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:20.294664Z","caller":"traceutil/trace.go:171","msg":"trace[590044626] transaction","detail":"{read_only:false; response_revision:25006; number_of_response:1; }","duration":"373.257398ms","start":"2026-05-26T21:58:19.921401Z","end":"2026-05-26T21:58:20.294658Z","steps":["trace[590044626] 'process raft request' (duration: 372.605265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:20.294736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:19.921395Z","time spent":"373.320389ms","remote":"127.0.0.1:34660","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-26T21:58:20.294803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.775785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:20.294825Z","caller":"traceutil/trace.go:171","msg":"trace[1370537228] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:25006; }","duration":"103.842986ms","start":"2026-05-26T21:58:20.190976Z","end":"2026-05-26T21:58:20.294819Z","steps":["trace[1370537228] 'agreement among raft nodes before linearized reading' (duration: 103.802625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:20.647711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.540468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T21:58:20.648244Z","caller":"traceutil/trace.go:171","msg":"trace[438184387] transaction","detail":"{read_only:false; response_revision:25007; number_of_response:1; }","duration":"339.838574ms","start":"2026-05-26T21:58:20.308356Z","end":"2026-05-26T21:58:20.648194Z","steps":["trace[438184387] 'process raft request' (duration: 186.719485ms)","trace[438184387] 'compare' (duration: 152.431226ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:20.648349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:20.308339Z","time spent":"339.954375ms","remote":"127.0.0.1:34660","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-26T21:58:20.64833Z","caller":"traceutil/trace.go:171","msg":"trace[107298675] linearizableReadLoop","detail":"{readStateIndex:25822; appliedIndex:25821; }","duration":"231.214467ms","start":"2026-05-26T21:58:20.417069Z","end":"2026-05-26T21:58:20.648283Z","steps":["trace[107298675] 'read index received' (duration: 77.878295ms)","trace[107298675] 'applied index is now lower than readState.Index' (duration: 153.326742ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:20.648429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.346161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:20.648503Z","caller":"traceutil/trace.go:171","msg":"trace[866555356] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:25007; }","duration":"231.458653ms","start":"2026-05-26T21:58:20.417029Z","end":"2026-05-26T21:58:20.648488Z","steps":["trace[866555356] 'agreement among raft nodes before linearized reading' (duration: 231.343901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:20.64902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.725783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:20.649076Z","caller":"traceutil/trace.go:171","msg":"trace[1065054016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25008; }","duration":"225.799825ms","start":"2026-05-26T21:58:20.423265Z","end":"2026-05-26T21:58:20.649065Z","steps":["trace[1065054016] 'agreement among raft nodes before linearized reading' (duration: 225.698913ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:20.649155Z","caller":"traceutil/trace.go:171","msg":"trace[613332256] transaction","detail":"{read_only:false; response_revision:25008; number_of_response:1; }","duration":"134.419605ms","start":"2026-05-26T21:58:20.514704Z","end":"2026-05-26T21:58:20.649123Z","steps":["trace[613332256] 'process raft request' (duration: 133.638049ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:20.952537Z","caller":"traceutil/trace.go:171","msg":"trace[1006591360] linearizableReadLoop","detail":"{readStateIndex:25824; appliedIndex:25823; }","duration":"204.38233ms","start":"2026-05-26T21:58:20.748121Z","end":"2026-05-26T21:58:20.952503Z","steps":["trace[1006591360] 'read index received' (duration: 184.392571ms)","trace[1006591360] 'applied index is now lower than readState.Index' (duration: 19.988669ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:20.952754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.609534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18622"} {"level":"info","ts":"2026-05-26T21:58:20.952729Z","caller":"traceutil/trace.go:171","msg":"trace[404676942] transaction","detail":"{read_only:false; response_revision:25009; number_of_response:1; }","duration":"204.629874ms","start":"2026-05-26T21:58:20.748059Z","end":"2026-05-26T21:58:20.952689Z","steps":["trace[404676942] 'process raft request' (duration: 184.575425ms)","trace[404676942] 'compare' (duration: 19.605351ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:20.952795Z","caller":"traceutil/trace.go:171","msg":"trace[1125550203] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25009; }","duration":"204.689725ms","start":"2026-05-26T21:58:20.748093Z","end":"2026-05-26T21:58:20.952783Z","steps":["trace[1125550203] 'agreement among raft nodes before linearized reading' (duration: 204.517862ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:21.158926Z","caller":"traceutil/trace.go:171","msg":"trace[901063956] transaction","detail":"{read_only:false; response_revision:25010; number_of_response:1; }","duration":"160.191993ms","start":"2026-05-26T21:58:20.9987Z","end":"2026-05-26T21:58:21.158892Z","steps":["trace[901063956] 'process raft request' (duration: 101.200576ms)","trace[901063956] 'compare' (duration: 58.799483ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:21.314291Z","caller":"traceutil/trace.go:171","msg":"trace[876773521] linearizableReadLoop","detail":"{readStateIndex:25829; appliedIndex:25824; }","duration":"261.294198ms","start":"2026-05-26T21:58:21.052961Z","end":"2026-05-26T21:58:21.314255Z","steps":["trace[876773521] 'read index received' (duration: 46.901929ms)","trace[876773521] 'applied index is now lower than readState.Index' (duration: 214.390369ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:21.315236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.238796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-26T21:58:21.315355Z","caller":"traceutil/trace.go:171","msg":"trace[1000287915] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:25014; }","duration":"262.404399ms","start":"2026-05-26T21:58:21.05291Z","end":"2026-05-26T21:58:21.315314Z","steps":["trace[1000287915] 'agreement among raft nodes before linearized reading' (duration: 262.217086ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:21.314769Z","caller":"traceutil/trace.go:171","msg":"trace[1166166067] transaction","detail":"{read_only:false; response_revision:25011; number_of_response:1; }","duration":"314.866561ms","start":"2026-05-26T21:58:20.999881Z","end":"2026-05-26T21:58:21.314747Z","steps":["trace[1166166067] 'process raft request' (duration: 300.888886ms)","trace[1166166067] 'compare' (duration: 13.102349ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:21.31561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:20.999852Z","time spent":"315.636285ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:58:21.31566Z","caller":"traceutil/trace.go:171","msg":"trace[2135040647] transaction","detail":"{read_only:false; response_revision:25012; number_of_response:1; }","duration":"313.903303ms","start":"2026-05-26T21:58:21.001737Z","end":"2026-05-26T21:58:21.31564Z","steps":["trace[2135040647] 'process raft request' (duration: 312.372644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:21.315793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:21.001727Z","time spent":"313.978334ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:58:21.316087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.332184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:21.316137Z","caller":"traceutil/trace.go:171","msg":"trace[119979567] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25014; }","duration":"171.408935ms","start":"2026-05-26T21:58:21.144713Z","end":"2026-05-26T21:58:21.316122Z","steps":["trace[119979567] 'agreement among raft nodes before linearized reading' (duration: 171.13713ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:21.316168Z","caller":"traceutil/trace.go:171","msg":"trace[1678716716] transaction","detail":"{read_only:false; response_revision:25013; number_of_response:1; }","duration":"314.177359ms","start":"2026-05-26T21:58:21.001977Z","end":"2026-05-26T21:58:21.316155Z","steps":["trace[1678716716] 'process raft request' (duration: 312.178481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:21.316235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:21.00197Z","time spent":"314.231069ms","remote":"127.0.0.1:34660","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-26T21:58:21.316406Z","caller":"traceutil/trace.go:171","msg":"trace[1224874024] transaction","detail":"{read_only:false; response_revision:25014; number_of_response:1; }","duration":"313.146059ms","start":"2026-05-26T21:58:21.00325Z","end":"2026-05-26T21:58:21.316396Z","steps":["trace[1224874024] 'process raft request' (duration: 310.945287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:21.316503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:21.003241Z","time spent":"313.19874ms","remote":"127.0.0.1:34660","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-26T21:58:21.632372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.266739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T21:58:21.63255Z","caller":"traceutil/trace.go:171","msg":"trace[937350323] linearizableReadLoop","detail":"{readStateIndex:25830; appliedIndex:25829; }","duration":"293.825203ms","start":"2026-05-26T21:58:21.338702Z","end":"2026-05-26T21:58:21.632527Z","steps":["trace[937350323] 'read index received' (duration: 127.195118ms)","trace[937350323] 'applied index is now lower than readState.Index' (duration: 166.628575ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:21.632695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.963916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:21.632673Z","caller":"traceutil/trace.go:171","msg":"trace[1875835137] transaction","detail":"{read_only:false; response_revision:25015; number_of_response:1; }","duration":"312.293393ms","start":"2026-05-26T21:58:21.320332Z","end":"2026-05-26T21:58:21.632626Z","steps":["trace[1875835137] 'process raft request' (duration: 145.662397ms)","trace[1875835137] 'compare' (duration: 165.977334ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:21.632768Z","caller":"traceutil/trace.go:171","msg":"trace[1601761266] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:25015; }","duration":"294.091138ms","start":"2026-05-26T21:58:21.338654Z","end":"2026-05-26T21:58:21.632745Z","steps":["trace[1601761266] 'agreement among raft nodes before linearized reading' (duration: 293.960655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:21.632889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:21.320306Z","time spent":"312.474728ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:58:21.635779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.249402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:21.635821Z","caller":"traceutil/trace.go:171","msg":"trace[1148290856] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:25016; }","duration":"163.368614ms","start":"2026-05-26T21:58:21.472444Z","end":"2026-05-26T21:58:21.635813Z","steps":["trace[1148290856] 'agreement among raft nodes before linearized reading' (duration: 163.274082ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:22.225262Z","caller":"traceutil/trace.go:171","msg":"trace[818793277] transaction","detail":"{read_only:false; response_revision:25017; number_of_response:1; }","duration":"214.240847ms","start":"2026-05-26T21:58:22.010988Z","end":"2026-05-26T21:58:22.225228Z","steps":["trace[818793277] 'process raft request' (duration: 213.458701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:22.560417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.495892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:22.560543Z","caller":"traceutil/trace.go:171","msg":"trace[1448030959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25021; }","duration":"138.655565ms","start":"2026-05-26T21:58:22.421866Z","end":"2026-05-26T21:58:22.560522Z","steps":["trace[1448030959] 'range keys from in-memory index tree' (duration: 138.259068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:31.284353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.715132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18622"} {"level":"warn","ts":"2026-05-26T21:58:31.284413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.695015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e84d8cb2-0f74-4624-af49-f674a56f857a\" ","response":"range_response_count:1 size:3642"} {"level":"info","ts":"2026-05-26T21:58:31.284503Z","caller":"traceutil/trace.go:171","msg":"trace[882087678] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25140; }","duration":"199.856264ms","start":"2026-05-26T21:58:31.084586Z","end":"2026-05-26T21:58:31.284443Z","steps":["trace[882087678] 'range keys from in-memory index tree' (duration: 199.538689ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:31.284514Z","caller":"traceutil/trace.go:171","msg":"trace[2120515315] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e84d8cb2-0f74-4624-af49-f674a56f857a; range_end:; response_count:1; response_revision:25140; }","duration":"140.830397ms","start":"2026-05-26T21:58:31.143661Z","end":"2026-05-26T21:58:31.284491Z","steps":["trace[2120515315] 'range keys from in-memory index tree' (duration: 140.458089ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:31.421314Z","caller":"traceutil/trace.go:171","msg":"trace[182909638] transaction","detail":"{read_only:false; response_revision:25141; number_of_response:1; }","duration":"217.040329ms","start":"2026-05-26T21:58:31.20425Z","end":"2026-05-26T21:58:31.42129Z","steps":["trace[182909638] 'process raft request' (duration: 216.885087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:31.79149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.776494ms","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-26T21:58:31.791655Z","caller":"traceutil/trace.go:171","msg":"trace[1318797417] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25141; }","duration":"226.976198ms","start":"2026-05-26T21:58:31.564655Z","end":"2026-05-26T21:58:31.791631Z","steps":["trace[1318797417] 'range keys from in-memory index tree' (duration: 226.740244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:31.791831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.105696ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T21:58:31.792044Z","caller":"traceutil/trace.go:171","msg":"trace[2011670704] linearizableReadLoop","detail":"{readStateIndex:25961; appliedIndex:25958; }","duration":"422.283086ms","start":"2026-05-26T21:58:31.36974Z","end":"2026-05-26T21:58:31.792023Z","steps":["trace[2011670704] 'read index received' (duration: 51.445164ms)","trace[2011670704] 'applied index is now lower than readState.Index' (duration: 370.836952ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:31.792143Z","caller":"traceutil/trace.go:171","msg":"trace[334521977] transaction","detail":"{read_only:false; response_revision:25142; number_of_response:1; }","duration":"429.808558ms","start":"2026-05-26T21:58:31.362322Z","end":"2026-05-26T21:58:31.792131Z","steps":["trace[334521977] 'process raft request' (duration: 143.298143ms)","trace[334521977] 'compare' (duration: 285.925443ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:31.792208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:31.36225Z","time spent":"429.92409ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:58:31.792204Z","caller":"traceutil/trace.go:171","msg":"trace[1563943467] transaction","detail":"{read_only:false; response_revision:25143; number_of_response:1; }","duration":"426.842331ms","start":"2026-05-26T21:58:31.365304Z","end":"2026-05-26T21:58:31.792146Z","steps":["trace[1563943467] 'process raft request' (duration: 426.655487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:31.792376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:31.36529Z","time spent":"426.998025ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:58:31.792667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.925637ms","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-26T21:58:31.792743Z","caller":"traceutil/trace.go:171","msg":"trace[436827598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25143; }","duration":"423.0517ms","start":"2026-05-26T21:58:31.369678Z","end":"2026-05-26T21:58:31.792729Z","steps":["trace[436827598] 'agreement among raft nodes before linearized reading' (duration: 422.823716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:31.792785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:31.36965Z","time spent":"423.124942ms","remote":"127.0.0.1:34660","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-26T21:58:32.085597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.800096ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T21:58:32.085731Z","caller":"traceutil/trace.go:171","msg":"trace[117003368] linearizableReadLoop","detail":"{readStateIndex:25962; appliedIndex:25961; }","duration":"293.622729ms","start":"2026-05-26T21:58:31.792087Z","end":"2026-05-26T21:58:32.08571Z","steps":["trace[117003368] 'read index received' (duration: 124.63122ms)","trace[117003368] 'applied index is now lower than readState.Index' (duration: 168.990189ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:32.085856Z","caller":"traceutil/trace.go:171","msg":"trace[1956161784] transaction","detail":"{read_only:false; response_revision:25144; number_of_response:1; }","duration":"416.113118ms","start":"2026-05-26T21:58:31.66973Z","end":"2026-05-26T21:58:32.085843Z","steps":["trace[1956161784] 'process raft request' (duration: 246.966416ms)","trace[1956161784] 'compare' (duration: 168.666363ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:32.085923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.223446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-26T21:58:32.085952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:31.669697Z","time spent":"416.2195ms","remote":"127.0.0.1:34660","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-26T21:58:32.085975Z","caller":"traceutil/trace.go:171","msg":"trace[470543927] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:25144; }","duration":"542.310547ms","start":"2026-05-26T21:58:31.54365Z","end":"2026-05-26T21:58:32.08596Z","steps":["trace[470543927] 'agreement among raft nodes before linearized reading' (duration: 542.218726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:32.085955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.288201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-26T21:58:32.086007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:31.54363Z","time spent":"542.370659ms","remote":"127.0.0.1:34044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"info","ts":"2026-05-26T21:58:32.086041Z","caller":"traceutil/trace.go:171","msg":"trace[1400354762] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:25144; }","duration":"416.473744ms","start":"2026-05-26T21:58:31.66955Z","end":"2026-05-26T21:58:32.086023Z","steps":["trace[1400354762] 'agreement among raft nodes before linearized reading' (duration: 416.317122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:32.086043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.550935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-26T21:58:32.086079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:31.669519Z","time spent":"416.551677ms","remote":"127.0.0.1:42146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-26T21:58:32.08603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.579719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:32.086084Z","caller":"traceutil/trace.go:171","msg":"trace[2113134526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25144; }","duration":"662.604296ms","start":"2026-05-26T21:58:31.423466Z","end":"2026-05-26T21:58:32.086071Z","steps":["trace[2113134526] 'agreement among raft nodes before linearized reading' (duration: 662.556644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:32.08613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:31.423455Z","time spent":"662.669087ms","remote":"127.0.0.1:34424","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-26T21:58:32.086164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.838076ms","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-26T21:58:32.086146Z","caller":"traceutil/trace.go:171","msg":"trace[954847854] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25144; }","duration":"695.753103ms","start":"2026-05-26T21:58:31.390367Z","end":"2026-05-26T21:58:32.08612Z","steps":["trace[954847854] 'agreement among raft nodes before linearized reading' (duration: 695.531448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:32.086174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.294962ms","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-26T21:58:32.08619Z","caller":"traceutil/trace.go:171","msg":"trace[684039751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25144; }","duration":"403.881897ms","start":"2026-05-26T21:58:31.682302Z","end":"2026-05-26T21:58:32.086184Z","steps":["trace[684039751] 'agreement among raft nodes before linearized reading' (duration: 403.817056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:32.086208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:31.682292Z","time spent":"403.911748ms","remote":"127.0.0.1:34660","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-26T21:58:32.086208Z","caller":"traceutil/trace.go:171","msg":"trace[2118328845] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25144; }","duration":"716.349294ms","start":"2026-05-26T21:58:31.369847Z","end":"2026-05-26T21:58:32.086196Z","steps":["trace[2118328845] 'agreement among raft nodes before linearized reading' (duration: 716.220801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:32.086221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:31.390351Z","time spent":"695.856145ms","remote":"127.0.0.1:34856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-05-26T21:58:32.086085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.681225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:32.086279Z","caller":"traceutil/trace.go:171","msg":"trace[1528621927] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25144; }","duration":"673.89713ms","start":"2026-05-26T21:58:31.412375Z","end":"2026-05-26T21:58:32.086272Z","steps":["trace[1528621927] 'agreement among raft nodes before linearized reading' (duration: 673.688816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:32.086227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.396781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e84d8cb2-0f74-4624-af49-f674a56f857a\" ","response":"range_response_count:1 size:3642"} {"level":"warn","ts":"2026-05-26T21:58:32.086303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:31.412361Z","time spent":"673.93664ms","remote":"127.0.0.1:44546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true "} {"level":"warn","ts":"2026-05-26T21:58:32.086239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:31.36984Z","time spent":"716.392744ms","remote":"127.0.0.1:34660","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-26T21:58:32.086337Z","caller":"traceutil/trace.go:171","msg":"trace[1642918671] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e84d8cb2-0f74-4624-af49-f674a56f857a; range_end:; response_count:1; response_revision:25144; }","duration":"391.548294ms","start":"2026-05-26T21:58:31.694763Z","end":"2026-05-26T21:58:32.086311Z","steps":["trace[1642918671] 'agreement among raft nodes before linearized reading' (duration: 391.195606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:32.086402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:31.694755Z","time spent":"391.636435ms","remote":"127.0.0.1:34578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3665,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e84d8cb2-0f74-4624-af49-f674a56f857a\" "} {"level":"warn","ts":"2026-05-26T21:58:32.085955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.46989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:32.086479Z","caller":"traceutil/trace.go:171","msg":"trace[529628707] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:25144; }","duration":"580.064192ms","start":"2026-05-26T21:58:31.5064Z","end":"2026-05-26T21:58:32.086464Z","steps":["trace[529628707] 'agreement among raft nodes before linearized reading' (duration: 579.470961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:32.086525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:31.506368Z","time spent":"580.143854ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-05-26T21:58:32.086517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.274349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:32.086664Z","caller":"traceutil/trace.go:171","msg":"trace[963488810] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:25144; }","duration":"654.597944ms","start":"2026-05-26T21:58:31.432013Z","end":"2026-05-26T21:58:32.086611Z","steps":["trace[963488810] 'agreement among raft nodes before linearized reading' (duration: 653.851659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:32.086732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:31.432003Z","time spent":"654.712826ms","remote":"127.0.0.1:44528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true "} {"level":"info","ts":"2026-05-26T21:58:32.092087Z","caller":"traceutil/trace.go:171","msg":"trace[862231842] transaction","detail":"{read_only:false; response_revision:25145; number_of_response:1; }","duration":"296.217859ms","start":"2026-05-26T21:58:31.795847Z","end":"2026-05-26T21:58:32.092065Z","steps":["trace[862231842] 'process raft request' (duration: 296.002215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:32.376864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.902528ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T21:58:32.376964Z","caller":"traceutil/trace.go:171","msg":"trace[1394418605] linearizableReadLoop","detail":"{readStateIndex:25964; appliedIndex:25963; }","duration":"284.943825ms","start":"2026-05-26T21:58:32.092003Z","end":"2026-05-26T21:58:32.376947Z","steps":["trace[1394418605] 'read index received' (duration: 106.950945ms)","trace[1394418605] 'applied index is now lower than readState.Index' (duration: 177.99191ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:32.377021Z","caller":"traceutil/trace.go:171","msg":"trace[709513680] transaction","detail":"{read_only:false; response_revision:25146; number_of_response:1; }","duration":"285.862222ms","start":"2026-05-26T21:58:32.09115Z","end":"2026-05-26T21:58:32.377012Z","steps":["trace[709513680] 'process raft request' (duration: 107.72713ms)","trace[709513680] 'compare' (duration: 177.792056ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:32.377176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.917211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:32.377196Z","caller":"traceutil/trace.go:171","msg":"trace[795821714] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25146; }","duration":"286.985894ms","start":"2026-05-26T21:58:32.090203Z","end":"2026-05-26T21:58:32.377189Z","steps":["trace[795821714] 'agreement among raft nodes before linearized reading' (duration: 286.943303ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:32.393449Z","caller":"traceutil/trace.go:171","msg":"trace[900895934] transaction","detail":"{read_only:false; response_revision:25148; number_of_response:1; }","duration":"297.243667ms","start":"2026-05-26T21:58:32.096181Z","end":"2026-05-26T21:58:32.393425Z","steps":["trace[900895934] 'process raft request' (duration: 297.166446ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:32.393671Z","caller":"traceutil/trace.go:171","msg":"trace[73732112] transaction","detail":"{read_only:false; response_revision:25147; number_of_response:1; }","duration":"299.005451ms","start":"2026-05-26T21:58:32.094619Z","end":"2026-05-26T21:58:32.393624Z","steps":["trace[73732112] 'process raft request' (duration: 298.204216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:32.70674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.62945ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T21:58:32.707144Z","caller":"traceutil/trace.go:171","msg":"trace[564456165] transaction","detail":"{read_only:false; response_revision:25152; number_of_response:1; }","duration":"186.818777ms","start":"2026-05-26T21:58:32.520306Z","end":"2026-05-26T21:58:32.707125Z","steps":["trace[564456165] 'process raft request' (duration: 186.785526ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:32.707137Z","caller":"traceutil/trace.go:171","msg":"trace[2117826363] transaction","detail":"{read_only:false; response_revision:25149; number_of_response:1; }","duration":"348.142042ms","start":"2026-05-26T21:58:32.358937Z","end":"2026-05-26T21:58:32.707079Z","steps":["trace[2117826363] 'process raft request' (duration: 139.080294ms)","trace[2117826363] 'compare' (duration: 208.500577ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:32.707249Z","caller":"traceutil/trace.go:171","msg":"trace[1806052991] linearizableReadLoop","detail":"{readStateIndex:25968; appliedIndex:25964; }","duration":"330.249623ms","start":"2026-05-26T21:58:32.376984Z","end":"2026-05-26T21:58:32.707234Z","steps":["trace[1806052991] 'read index received' (duration: 15.909162ms)","trace[1806052991] 'applied index is now lower than readState.Index' (duration: 314.33814ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:32.70732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:32.358893Z","time spent":"348.330055ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:58:32.707372Z","caller":"traceutil/trace.go:171","msg":"trace[2044701847] transaction","detail":"{read_only:false; response_revision:25151; number_of_response:1; }","duration":"309.651742ms","start":"2026-05-26T21:58:32.397677Z","end":"2026-05-26T21:58:32.707329Z","steps":["trace[2044701847] 'process raft request' (duration: 309.386457ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:32.707249Z","caller":"traceutil/trace.go:171","msg":"trace[132616151] transaction","detail":"{read_only:false; response_revision:25150; number_of_response:1; }","duration":"345.97113ms","start":"2026-05-26T21:58:32.361239Z","end":"2026-05-26T21:58:32.707211Z","steps":["trace[132616151] 'process raft request' (duration: 345.769866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:32.707499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.258296ms","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-26T21:58:32.707539Z","caller":"traceutil/trace.go:171","msg":"trace[1415456247] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25152; }","duration":"439.360938ms","start":"2026-05-26T21:58:32.268167Z","end":"2026-05-26T21:58:32.707528Z","steps":["trace[1415456247] 'agreement among raft nodes before linearized reading' (duration: 439.184375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:32.707525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.292089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e84d8cb2-0f74-4624-af49-f674a56f857a\" ","response":"range_response_count:1 size:3759"} {"level":"warn","ts":"2026-05-26T21:58:32.707581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.763996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-05-26T21:58:32.707577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:32.361198Z","time spent":"346.304886ms","remote":"127.0.0.1:34660","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-26T21:58:32.707575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:32.268135Z","time spent":"439.431449ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-26T21:58:32.70758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:32.397653Z","time spent":"309.826646ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:58:32.707618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.923095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"warn","ts":"2026-05-26T21:58:32.707606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.875959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:32.707684Z","caller":"traceutil/trace.go:171","msg":"trace[1966380941] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25152; }","duration":"509.019437ms","start":"2026-05-26T21:58:32.198641Z","end":"2026-05-26T21:58:32.70766Z","steps":["trace[1966380941] 'agreement among raft nodes before linearized reading' (duration: 508.843794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:32.707719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:32.19862Z","time spent":"509.090429ms","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3332,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-05-26T21:58:32.707721Z","caller":"traceutil/trace.go:171","msg":"trace[1125004508] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:25152; }","duration":"345.061703ms","start":"2026-05-26T21:58:32.362633Z","end":"2026-05-26T21:58:32.707694Z","steps":["trace[1125004508] 'agreement among raft nodes before linearized reading' (duration: 344.811348ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:32.707615Z","caller":"traceutil/trace.go:171","msg":"trace[861701948] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:25152; }","duration":"419.826238ms","start":"2026-05-26T21:58:32.287781Z","end":"2026-05-26T21:58:32.707607Z","steps":["trace[861701948] 'agreement among raft nodes before linearized reading' (duration: 419.531713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:32.707788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:32.362616Z","time spent":"345.158545ms","remote":"127.0.0.1:51048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true "} {"level":"info","ts":"2026-05-26T21:58:32.707636Z","caller":"traceutil/trace.go:171","msg":"trace[961889542] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e84d8cb2-0f74-4624-af49-f674a56f857a; range_end:; response_count:1; response_revision:25152; }","duration":"418.426601ms","start":"2026-05-26T21:58:32.289186Z","end":"2026-05-26T21:58:32.707613Z","steps":["trace[961889542] 'agreement among raft nodes before linearized reading' (duration: 418.129386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:32.707872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:32.287774Z","time spent":"420.045383ms","remote":"127.0.0.1:34704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":180,"response size":32,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-05-26T21:58:32.707934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:32.28918Z","time spent":"418.713788ms","remote":"127.0.0.1:34578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3782,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e84d8cb2-0f74-4624-af49-f674a56f857a\" "} {"level":"warn","ts":"2026-05-26T21:58:32.728767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.960683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-26T21:58:32.728841Z","caller":"traceutil/trace.go:171","msg":"trace[35153046] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:25153; }","duration":"240.066636ms","start":"2026-05-26T21:58:32.488753Z","end":"2026-05-26T21:58:32.72882Z","steps":["trace[35153046] 'agreement among raft nodes before linearized reading' (duration: 239.944983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:32.728937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.841346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-26T21:58:32.729066Z","caller":"traceutil/trace.go:171","msg":"trace[1035339852] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:25153; }","duration":"123.096681ms","start":"2026-05-26T21:58:32.605951Z","end":"2026-05-26T21:58:32.729048Z","steps":["trace[1035339852] 'agreement among raft nodes before linearized reading' (duration: 122.736973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:32.729105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.870016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-26T21:58:32.72916Z","caller":"traceutil/trace.go:171","msg":"trace[222458628] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25153; }","duration":"348.954207ms","start":"2026-05-26T21:58:32.380193Z","end":"2026-05-26T21:58:32.729147Z","steps":["trace[222458628] 'agreement among raft nodes before linearized reading' (duration: 348.800234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:32.729182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:32.380166Z","time spent":"349.010928ms","remote":"127.0.0.1:34578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-26T21:58:32.976169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.742815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T21:58:32.976331Z","caller":"traceutil/trace.go:171","msg":"trace[976482030] linearizableReadLoop","detail":"{readStateIndex:25972; appliedIndex:25971; }","duration":"247.637429ms","start":"2026-05-26T21:58:32.728657Z","end":"2026-05-26T21:58:32.976295Z","steps":["trace[976482030] 'read index received' (duration: 88.530137ms)","trace[976482030] 'applied index is now lower than readState.Index' (duration: 159.101722ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:32.976581Z","caller":"traceutil/trace.go:171","msg":"trace[228449016] transaction","detail":"{read_only:false; response_revision:25154; number_of_response:1; }","duration":"263.105561ms","start":"2026-05-26T21:58:32.71342Z","end":"2026-05-26T21:58:32.976525Z","steps":["trace[228449016] 'process raft request' (duration: 103.894007ms)","trace[228449016] 'compare' (duration: 158.611973ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:32.976613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.977541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:32.976712Z","caller":"traceutil/trace.go:171","msg":"trace[1677037777] 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:25154; }","duration":"196.090443ms","start":"2026-05-26T21:58:32.780595Z","end":"2026-05-26T21:58:32.976685Z","steps":["trace[1677037777] 'agreement among raft nodes before linearized reading' (duration: 195.924369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:32.976699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.210282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"warn","ts":"2026-05-26T21:58:32.976707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.528273ms","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-26T21:58:32.976789Z","caller":"traceutil/trace.go:171","msg":"trace[2004764472] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25154; }","duration":"214.636624ms","start":"2026-05-26T21:58:32.762137Z","end":"2026-05-26T21:58:32.976774Z","steps":["trace[2004764472] 'agreement among raft nodes before linearized reading' (duration: 214.428031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:32.976622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.228102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18622"} {"level":"info","ts":"2026-05-26T21:58:32.976846Z","caller":"traceutil/trace.go:171","msg":"trace[1405617008] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25154; }","duration":"246.483116ms","start":"2026-05-26T21:58:32.73035Z","end":"2026-05-26T21:58:32.976833Z","steps":["trace[1405617008] 'agreement among raft nodes before linearized reading' (duration: 246.081418ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:32.976789Z","caller":"traceutil/trace.go:171","msg":"trace[611713852] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25154; }","duration":"264.315514ms","start":"2026-05-26T21:58:32.71245Z","end":"2026-05-26T21:58:32.976765Z","steps":["trace[611713852] 'agreement among raft nodes before linearized reading' (duration: 263.973157ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:35.100139Z","caller":"traceutil/trace.go:171","msg":"trace[1894796781] linearizableReadLoop","detail":"{readStateIndex:25993; appliedIndex:25992; }","duration":"112.706143ms","start":"2026-05-26T21:58:34.987393Z","end":"2026-05-26T21:58:35.100099Z","steps":["trace[1894796781] 'read index received' (duration: 112.576161ms)","trace[1894796781] 'applied index is now lower than readState.Index' (duration: 128.722µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:35.100187Z","caller":"traceutil/trace.go:171","msg":"trace[1571536580] transaction","detail":"{read_only:false; response_revision:25174; number_of_response:1; }","duration":"114.716622ms","start":"2026-05-26T21:58:34.985425Z","end":"2026-05-26T21:58:35.100141Z","steps":["trace[1571536580] 'process raft request' (duration: 114.488678ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:35.100351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.941668ms","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-26T21:58:35.100519Z","caller":"traceutil/trace.go:171","msg":"trace[1235674251] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25174; }","duration":"113.185863ms","start":"2026-05-26T21:58:34.987318Z","end":"2026-05-26T21:58:35.100504Z","steps":["trace[1235674251] 'agreement among raft nodes before linearized reading' (duration: 112.903218ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:35.237947Z","caller":"traceutil/trace.go:171","msg":"trace[393419529] transaction","detail":"{read_only:false; response_revision:25175; number_of_response:1; }","duration":"198.389706ms","start":"2026-05-26T21:58:35.039518Z","end":"2026-05-26T21:58:35.237907Z","steps":["trace[393419529] 'process raft request' (duration: 198.12518ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:35.238233Z","caller":"traceutil/trace.go:171","msg":"trace[1706012700] linearizableReadLoop","detail":"{readStateIndex:25994; appliedIndex:25994; }","duration":"137.3668ms","start":"2026-05-26T21:58:35.10083Z","end":"2026-05-26T21:58:35.238196Z","steps":["trace[1706012700] 'read index received' (duration: 137.34684ms)","trace[1706012700] 'applied index is now lower than readState.Index' (duration: 17.41µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:35.238415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.542223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:35.238475Z","caller":"traceutil/trace.go:171","msg":"trace[747464272] transaction","detail":"{read_only:false; response_revision:25176; number_of_response:1; }","duration":"133.204082ms","start":"2026-05-26T21:58:35.105255Z","end":"2026-05-26T21:58:35.238459Z","steps":["trace[747464272] 'process raft request' (duration: 132.977588ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:35.238527Z","caller":"traceutil/trace.go:171","msg":"trace[1498447040] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:25175; }","duration":"137.680716ms","start":"2026-05-26T21:58:35.10082Z","end":"2026-05-26T21:58:35.2385Z","steps":["trace[1498447040] 'agreement among raft nodes before linearized reading' (duration: 137.462932ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:38.590496Z","caller":"traceutil/trace.go:171","msg":"trace[140645108] transaction","detail":"{read_only:false; response_revision:25219; number_of_response:1; }","duration":"138.917351ms","start":"2026-05-26T21:58:38.451536Z","end":"2026-05-26T21:58:38.590453Z","steps":["trace[140645108] 'process raft request' (duration: 71.687538ms)","trace[140645108] 'compare' (duration: 66.922677ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:38.841539Z","caller":"traceutil/trace.go:171","msg":"trace[1251629482] transaction","detail":"{read_only:false; response_revision:25220; number_of_response:1; }","duration":"114.037148ms","start":"2026-05-26T21:58:38.727474Z","end":"2026-05-26T21:58:38.841511Z","steps":["trace[1251629482] 'process raft request' (duration: 94.02301ms)","trace[1251629482] 'compare' (duration: 19.713083ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:38.848885Z","caller":"traceutil/trace.go:171","msg":"trace[1259791323] transaction","detail":"{read_only:false; response_revision:25221; number_of_response:1; }","duration":"121.190695ms","start":"2026-05-26T21:58:38.727683Z","end":"2026-05-26T21:58:38.848874Z","steps":["trace[1259791323] 'process raft request' (duration: 121.058082ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:38.8492Z","caller":"traceutil/trace.go:171","msg":"trace[203557654] transaction","detail":"{read_only:false; response_revision:25222; number_of_response:1; }","duration":"120.669775ms","start":"2026-05-26T21:58:38.728413Z","end":"2026-05-26T21:58:38.849082Z","steps":["trace[203557654] 'process raft request' (duration: 120.42809ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:44.011446Z","caller":"traceutil/trace.go:171","msg":"trace[2059694716] linearizableReadLoop","detail":"{readStateIndex:26083; appliedIndex:26082; }","duration":"124.11301ms","start":"2026-05-26T21:58:43.887265Z","end":"2026-05-26T21:58:44.011378Z","steps":["trace[2059694716] 'read index received' (duration: 123.929057ms)","trace[2059694716] 'applied index is now lower than readState.Index' (duration: 182.863µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:44.011587Z","caller":"traceutil/trace.go:171","msg":"trace[1201420663] transaction","detail":"{read_only:false; response_revision:25262; number_of_response:1; }","duration":"144.828663ms","start":"2026-05-26T21:58:43.866715Z","end":"2026-05-26T21:58:44.011543Z","steps":["trace[1201420663] 'process raft request' (duration: 144.464456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:44.011893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.484397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.4\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-26T21:58:44.011947Z","caller":"traceutil/trace.go:171","msg":"trace[796051396] range","detail":"{range_begin:/registry/masterleases/199.204.45.4; range_end:; response_count:1; response_revision:25262; }","duration":"124.707561ms","start":"2026-05-26T21:58:43.887225Z","end":"2026-05-26T21:58:44.011932Z","steps":["trace[796051396] 'agreement among raft nodes before linearized reading' (duration: 124.346614ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:44.023713Z","caller":"traceutil/trace.go:171","msg":"trace[897943130] transaction","detail":"{read_only:false; response_revision:25263; number_of_response:1; }","duration":"108.187559ms","start":"2026-05-26T21:58:43.915494Z","end":"2026-05-26T21:58:44.023682Z","steps":["trace[897943130] 'process raft request' (duration: 108.015505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:46.820186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.770977ms","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-26T21:58:46.820307Z","caller":"traceutil/trace.go:171","msg":"trace[1597736895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25283; }","duration":"194.958411ms","start":"2026-05-26T21:58:46.625315Z","end":"2026-05-26T21:58:46.820273Z","steps":["trace[1597736895] 'range keys from in-memory index tree' (duration: 194.523193ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:50.828256Z","caller":"traceutil/trace.go:171","msg":"trace[1019454093] linearizableReadLoop","detail":"{readStateIndex:26137; appliedIndex:26136; }","duration":"134.797781ms","start":"2026-05-26T21:58:50.693409Z","end":"2026-05-26T21:58:50.828207Z","steps":["trace[1019454093] 'read index received' (duration: 107.045607ms)","trace[1019454093] 'applied index is now lower than readState.Index' (duration: 27.751044ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:50.82857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.140019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-05-26T21:58:50.828606Z","caller":"traceutil/trace.go:171","msg":"trace[299994091] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25314; }","duration":"135.20326ms","start":"2026-05-26T21:58:50.693392Z","end":"2026-05-26T21:58:50.828595Z","steps":["trace[299994091] 'agreement among raft nodes before linearized reading' (duration: 134.971356ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:50.828709Z","caller":"traceutil/trace.go:171","msg":"trace[2139912023] transaction","detail":"{read_only:false; response_revision:25314; number_of_response:1; }","duration":"145.423903ms","start":"2026-05-26T21:58:50.683222Z","end":"2026-05-26T21:58:50.828646Z","steps":["trace[2139912023] 'process raft request' (duration: 117.129578ms)","trace[2139912023] 'compare' (duration: 27.711574ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:59:02.883035Z","caller":"traceutil/trace.go:171","msg":"trace[864739347] transaction","detail":"{read_only:false; response_revision:25412; number_of_response:1; }","duration":"126.935112ms","start":"2026-05-26T21:59:02.756052Z","end":"2026-05-26T21:59:02.882987Z","steps":["trace[864739347] 'process raft request' (duration: 65.418548ms)","trace[864739347] 'compare' (duration: 61.113687ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:59:03.119767Z","caller":"traceutil/trace.go:171","msg":"trace[1144615355] linearizableReadLoop","detail":"{readStateIndex:26238; appliedIndex:26237; }","duration":"203.044544ms","start":"2026-05-26T21:59:02.916699Z","end":"2026-05-26T21:59:03.119744Z","steps":["trace[1144615355] 'read index received' (duration: 182.24091ms)","trace[1144615355] 'applied index is now lower than readState.Index' (duration: 20.802784ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:59:03.119883Z","caller":"traceutil/trace.go:171","msg":"trace[1569131091] transaction","detail":"{read_only:false; response_revision:25413; number_of_response:1; }","duration":"206.066172ms","start":"2026-05-26T21:59:02.913805Z","end":"2026-05-26T21:59:03.119872Z","steps":["trace[1569131091] 'process raft request' (duration: 185.124685ms)","trace[1569131091] 'compare' (duration: 20.710682ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:59:03.120018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.291699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:59:03.120053Z","caller":"traceutil/trace.go:171","msg":"trace[1433195651] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:25413; }","duration":"203.356191ms","start":"2026-05-26T21:59:02.916684Z","end":"2026-05-26T21:59:03.120041Z","steps":["trace[1433195651] 'agreement among raft nodes before linearized reading' (duration: 203.278458ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:03.128388Z","caller":"traceutil/trace.go:171","msg":"trace[1823874822] transaction","detail":"{read_only:false; response_revision:25414; number_of_response:1; }","duration":"203.250398ms","start":"2026-05-26T21:59:02.925105Z","end":"2026-05-26T21:59:03.128356Z","steps":["trace[1823874822] 'process raft request' (duration: 203.028364ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:03.128712Z","caller":"traceutil/trace.go:171","msg":"trace[1228877618] transaction","detail":"{read_only:false; response_revision:25415; number_of_response:1; }","duration":"201.399664ms","start":"2026-05-26T21:59:02.927285Z","end":"2026-05-26T21:59:03.128685Z","steps":["trace[1228877618] 'process raft request' (duration: 201.025366ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:03.128964Z","caller":"traceutil/trace.go:171","msg":"trace[50942720] transaction","detail":"{read_only:false; response_revision:25416; number_of_response:1; }","duration":"198.224894ms","start":"2026-05-26T21:59:02.930714Z","end":"2026-05-26T21:59:03.128939Z","steps":["trace[50942720] 'process raft request' (duration: 197.868587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:03.36819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.451191ms","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-26T21:59:03.368314Z","caller":"traceutil/trace.go:171","msg":"trace[556387761] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25416; }","duration":"111.670295ms","start":"2026-05-26T21:59:03.256616Z","end":"2026-05-26T21:59:03.368286Z","steps":["trace[556387761] 'count revisions from in-memory index tree' (duration: 111.317508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:03.368513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.954953ms","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-26T21:59:03.368632Z","caller":"traceutil/trace.go:171","msg":"trace[524207499] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25416; }","duration":"137.177737ms","start":"2026-05-26T21:59:03.231427Z","end":"2026-05-26T21:59:03.368604Z","steps":["trace[524207499] 'range keys from in-memory index tree' (duration: 136.706059ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:03.405853Z","caller":"traceutil/trace.go:171","msg":"trace[606935571] transaction","detail":"{read_only:false; response_revision:25417; number_of_response:1; }","duration":"108.094658ms","start":"2026-05-26T21:59:03.297741Z","end":"2026-05-26T21:59:03.405836Z","steps":["trace[606935571] 'process raft request' (duration: 107.908274ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:03.577049Z","caller":"traceutil/trace.go:171","msg":"trace[1658957382] transaction","detail":"{read_only:false; response_revision:25418; number_of_response:1; }","duration":"203.926932ms","start":"2026-05-26T21:59:03.37307Z","end":"2026-05-26T21:59:03.576997Z","steps":["trace[1658957382] 'process raft request' (duration: 110.33842ms)","trace[1658957382] 'compare' (duration: 93.111023ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:59:03.57709Z","caller":"traceutil/trace.go:171","msg":"trace[415060152] linearizableReadLoop","detail":"{readStateIndex:26244; appliedIndex:26242; }","duration":"152.611529ms","start":"2026-05-26T21:59:03.424449Z","end":"2026-05-26T21:59:03.577061Z","steps":["trace[415060152] 'read index received' (duration: 58.945876ms)","trace[415060152] 'applied index is now lower than readState.Index' (duration: 93.664463ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:59:03.577305Z","caller":"traceutil/trace.go:171","msg":"trace[2111242623] transaction","detail":"{read_only:false; response_revision:25419; number_of_response:1; }","duration":"199.442096ms","start":"2026-05-26T21:59:03.377826Z","end":"2026-05-26T21:59:03.577268Z","steps":["trace[2111242623] 'process raft request' (duration: 199.066669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:03.57737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.894725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:59:03.577482Z","caller":"traceutil/trace.go:171","msg":"trace[407869578] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25419; }","duration":"153.080838ms","start":"2026-05-26T21:59:03.424373Z","end":"2026-05-26T21:59:03.577454Z","steps":["trace[407869578] 'agreement among raft nodes before linearized reading' (duration: 152.887755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:03.591584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.1195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-05-26T21:59:03.59162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.349242ms","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-26T21:59:03.591652Z","caller":"traceutil/trace.go:171","msg":"trace[1020318934] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25420; }","duration":"161.242183ms","start":"2026-05-26T21:59:03.430393Z","end":"2026-05-26T21:59:03.591635Z","steps":["trace[1020318934] 'agreement among raft nodes before linearized reading' (duration: 161.015299ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:03.591663Z","caller":"traceutil/trace.go:171","msg":"trace[111013470] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25420; }","duration":"154.429054ms","start":"2026-05-26T21:59:03.437223Z","end":"2026-05-26T21:59:03.591652Z","steps":["trace[111013470] 'agreement among raft nodes before linearized reading' (duration: 154.256891ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:04.303285Z","caller":"traceutil/trace.go:171","msg":"trace[920218666] transaction","detail":"{read_only:false; response_revision:25424; number_of_response:1; }","duration":"161.708122ms","start":"2026-05-26T21:59:04.141546Z","end":"2026-05-26T21:59:04.303255Z","steps":["trace[920218666] 'process raft request' (duration: 161.504737ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:04.34618Z","caller":"traceutil/trace.go:171","msg":"trace[1283719989] linearizableReadLoop","detail":"{readStateIndex:26251; appliedIndex:26249; }","duration":"101.247756ms","start":"2026-05-26T21:59:04.244897Z","end":"2026-05-26T21:59:04.346145Z","steps":["trace[1283719989] 'read index received' (duration: 58.259693ms)","trace[1283719989] 'applied index is now lower than readState.Index' (duration: 42.986783ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:59:04.346345Z","caller":"traceutil/trace.go:171","msg":"trace[419018684] transaction","detail":"{read_only:false; response_revision:25425; number_of_response:1; }","duration":"151.327405ms","start":"2026-05-26T21:59:04.194962Z","end":"2026-05-26T21:59:04.346289Z","steps":["trace[419018684] 'process raft request' (duration: 150.940048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:04.346537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.605223ms","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-26T21:59:04.34659Z","caller":"traceutil/trace.go:171","msg":"trace[1483154303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25425; }","duration":"101.690986ms","start":"2026-05-26T21:59:04.244886Z","end":"2026-05-26T21:59:04.346577Z","steps":["trace[1483154303] 'agreement among raft nodes before linearized reading' (duration: 101.38608ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:07.321274Z","caller":"traceutil/trace.go:171","msg":"trace[1427432461] transaction","detail":"{read_only:false; response_revision:25444; number_of_response:1; }","duration":"178.366377ms","start":"2026-05-26T21:59:07.142875Z","end":"2026-05-26T21:59:07.321241Z","steps":["trace[1427432461] 'process raft request' (duration: 155.483084ms)","trace[1427432461] 'compare' (duration: 22.673149ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:59:07.321518Z","caller":"traceutil/trace.go:171","msg":"trace[797612181] transaction","detail":"{read_only:false; response_revision:25445; number_of_response:1; }","duration":"178.54707ms","start":"2026-05-26T21:59:07.142935Z","end":"2026-05-26T21:59:07.321482Z","steps":["trace[797612181] 'process raft request' (duration: 178.259145ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:07.321573Z","caller":"traceutil/trace.go:171","msg":"trace[1243575353] linearizableReadLoop","detail":"{readStateIndex:26272; appliedIndex:26268; }","duration":"170.841195ms","start":"2026-05-26T21:59:07.1507Z","end":"2026-05-26T21:59:07.321542Z","steps":["trace[1243575353] 'read index received' (duration: 26.013213ms)","trace[1243575353] 'applied index is now lower than readState.Index' (duration: 144.826542ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:59:07.321683Z","caller":"traceutil/trace.go:171","msg":"trace[877103402] transaction","detail":"{read_only:false; response_revision:25446; number_of_response:1; }","duration":"178.579461ms","start":"2026-05-26T21:59:07.143095Z","end":"2026-05-26T21:59:07.321674Z","steps":["trace[877103402] 'process raft request' (duration: 178.309716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:07.321727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.989087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:59:07.321766Z","caller":"traceutil/trace.go:171","msg":"trace[1931003638] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:25446; }","duration":"171.121859ms","start":"2026-05-26T21:59:07.150631Z","end":"2026-05-26T21:59:07.321753Z","steps":["trace[1931003638] 'agreement among raft nodes before linearized reading' (duration: 171.018597ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:10.325671Z","caller":"traceutil/trace.go:171","msg":"trace[1893962097] transaction","detail":"{read_only:false; response_revision:25464; number_of_response:1; }","duration":"128.679956ms","start":"2026-05-26T21:59:10.196954Z","end":"2026-05-26T21:59:10.325634Z","steps":["trace[1893962097] 'process raft request' (duration: 128.293219ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:10.459085Z","caller":"traceutil/trace.go:171","msg":"trace[1481980587] transaction","detail":"{read_only:false; response_revision:25465; number_of_response:1; }","duration":"109.114466ms","start":"2026-05-26T21:59:10.349918Z","end":"2026-05-26T21:59:10.459033Z","steps":["trace[1481980587] 'process raft request' (duration: 103.322326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:10.716019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.464725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T21:59:10.716242Z","caller":"traceutil/trace.go:171","msg":"trace[1437595831] linearizableReadLoop","detail":"{readStateIndex:26293; appliedIndex:26291; }","duration":"301.760734ms","start":"2026-05-26T21:59:10.41446Z","end":"2026-05-26T21:59:10.716221Z","steps":["trace[1437595831] 'read index received' (duration: 38.802464ms)","trace[1437595831] 'applied index is now lower than readState.Index' (duration: 262.95717ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:59:10.716288Z","caller":"traceutil/trace.go:171","msg":"trace[1912817507] transaction","detail":"{read_only:false; response_revision:25467; number_of_response:1; }","duration":"300.143362ms","start":"2026-05-26T21:59:10.416103Z","end":"2026-05-26T21:59:10.716246Z","steps":["trace[1912817507] 'process raft request' (duration: 300.04811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:10.716434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.975158ms","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-26T21:59:10.716463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:59:10.416077Z","time spent":"300.298467ms","remote":"127.0.0.1:34660","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-26T21:59:10.71653Z","caller":"traceutil/trace.go:171","msg":"trace[1689914131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25467; }","duration":"302.061589ms","start":"2026-05-26T21:59:10.414409Z","end":"2026-05-26T21:59:10.716471Z","steps":["trace[1689914131] 'agreement among raft nodes before linearized reading' (duration: 301.868606ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:10.716275Z","caller":"traceutil/trace.go:171","msg":"trace[139412628] transaction","detail":"{read_only:false; response_revision:25466; number_of_response:1; }","duration":"327.863958ms","start":"2026-05-26T21:59:10.38837Z","end":"2026-05-26T21:59:10.716234Z","steps":["trace[139412628] 'process raft request' (duration: 201.032996ms)","trace[139412628] 'compare' (duration: 126.286121ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:59:10.716579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:59:10.414377Z","time spent":"302.192622ms","remote":"127.0.0.1:34660","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-26T21:59:10.71672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:59:10.38834Z","time spent":"328.282365ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:59:11.037167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.304098ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T21:59:11.037305Z","caller":"traceutil/trace.go:171","msg":"trace[1521147879] linearizableReadLoop","detail":"{readStateIndex:26295; appliedIndex:26294; }","duration":"196.918127ms","start":"2026-05-26T21:59:10.840365Z","end":"2026-05-26T21:59:11.037283Z","steps":["trace[1521147879] 'read index received' (duration: 39.264833ms)","trace[1521147879] 'applied index is now lower than readState.Index' (duration: 157.651664ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:59:11.037389Z","caller":"traceutil/trace.go:171","msg":"trace[15360677] transaction","detail":"{read_only:false; response_revision:25468; number_of_response:1; }","duration":"315.744688ms","start":"2026-05-26T21:59:10.721575Z","end":"2026-05-26T21:59:11.037319Z","steps":["trace[15360677] 'process raft request' (duration: 158.198715ms)","trace[15360677] 'compare' (duration: 157.073544ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:59:11.037477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.125622ms","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-26T21:59:11.037521Z","caller":"traceutil/trace.go:171","msg":"trace[1014986200] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25468; }","duration":"197.216354ms","start":"2026-05-26T21:59:10.84029Z","end":"2026-05-26T21:59:11.037506Z","steps":["trace[1014986200] 'agreement among raft nodes before linearized reading' (duration: 197.050361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:11.037607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:59:10.721543Z","time spent":"315.953821ms","remote":"127.0.0.1:34660","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-26T21:59:11.058523Z","caller":"traceutil/trace.go:171","msg":"trace[8083516] transaction","detail":"{read_only:false; response_revision:25469; number_of_response:1; }","duration":"160.313705ms","start":"2026-05-26T21:59:10.898175Z","end":"2026-05-26T21:59:11.058488Z","steps":["trace[8083516] 'process raft request' (duration: 160.124261ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:31.776816Z","caller":"traceutil/trace.go:171","msg":"trace[1108354270] transaction","detail":"{read_only:false; response_revision:25633; number_of_response:1; }","duration":"111.994391ms","start":"2026-05-26T21:59:31.664789Z","end":"2026-05-26T21:59:31.776783Z","steps":["trace[1108354270] 'process raft request' (duration: 111.766857ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:31.931247Z","caller":"traceutil/trace.go:171","msg":"trace[1522644081] linearizableReadLoop","detail":"{readStateIndex:26465; appliedIndex:26464; }","duration":"154.467135ms","start":"2026-05-26T21:59:31.776742Z","end":"2026-05-26T21:59:31.931209Z","steps":["trace[1522644081] 'read index received' (duration: 78.371074ms)","trace[1522644081] 'applied index is now lower than readState.Index' (duration: 76.094861ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:59:31.931279Z","caller":"traceutil/trace.go:171","msg":"trace[1591984902] transaction","detail":"{read_only:false; response_revision:25634; number_of_response:1; }","duration":"231.457522ms","start":"2026-05-26T21:59:31.699782Z","end":"2026-05-26T21:59:31.931239Z","steps":["trace[1591984902] 'process raft request' (duration: 155.426133ms)","trace[1591984902] 'compare' (duration: 75.579931ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:59:31.931597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.549777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-26T21:59:31.931644Z","caller":"traceutil/trace.go:171","msg":"trace[62772300] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25634; }","duration":"181.661379ms","start":"2026-05-26T21:59:31.74997Z","end":"2026-05-26T21:59:31.931631Z","steps":["trace[62772300] 'agreement among raft nodes before linearized reading' (duration: 181.429034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:31.932082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.861929ms","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-26T21:59:31.932188Z","caller":"traceutil/trace.go:171","msg":"trace[410791037] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25634; }","duration":"140.044412ms","start":"2026-05-26T21:59:31.792117Z","end":"2026-05-26T21:59:31.932161Z","steps":["trace[410791037] 'agreement among raft nodes before linearized reading' (duration: 139.685545ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T22:00:00.859574Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23035} {"level":"info","ts":"2026-05-26T22:00:01.024188Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23035,"took":"160.749404ms","hash":3339200463,"current-db-size-bytes":58920960,"current-db-size":"59 MB","current-db-size-in-use-bytes":36347904,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-26T22:00:01.024258Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3339200463,"revision":23035,"compact-revision":20133} {"level":"info","ts":"2026-05-26T22:00:03.405754Z","caller":"traceutil/trace.go:171","msg":"trace[684660342] linearizableReadLoop","detail":"{readStateIndex:26924; appliedIndex:26923; }","duration":"110.758467ms","start":"2026-05-26T22:00:03.294959Z","end":"2026-05-26T22:00:03.405718Z","steps":["trace[684660342] 'read index received' (duration: 101.517972ms)","trace[684660342] 'applied index is now lower than readState.Index' (duration: 9.238735ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T22:00:03.405811Z","caller":"traceutil/trace.go:171","msg":"trace[1495084999] transaction","detail":"{read_only:false; response_revision:26084; number_of_response:1; }","duration":"112.075482ms","start":"2026-05-26T22:00:03.293703Z","end":"2026-05-26T22:00:03.405779Z","steps":["trace[1495084999] 'process raft request' (duration: 102.784926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T22:00:03.405951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.971201ms","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-26T22:00:03.40599Z","caller":"traceutil/trace.go:171","msg":"trace[49269566] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26084; }","duration":"111.064894ms","start":"2026-05-26T22:00:03.294915Z","end":"2026-05-26T22:00:03.40598Z","steps":["trace[49269566] 'agreement among raft nodes before linearized reading' (duration: 110.974672ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T22:00:05.435108Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000694a.wal"} {"level":"info","ts":"2026-05-26T22:05:00.865036Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26055} {"level":"info","ts":"2026-05-26T22:05:01.016198Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26055,"took":"147.746352ms","hash":2835339323,"current-db-size-bytes":58920960,"current-db-size":"59 MB","current-db-size-in-use-bytes":34988032,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-05-26T22:05:01.016274Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2835339323,"revision":26055,"compact-revision":23035} {"level":"info","ts":"2026-05-26T22:05:33.146059Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"66f7b57d56ad567d","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-26T22:05:33.153192Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-26T22:05:33.153294Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-26T22:10:00.872451Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28799} {"level":"info","ts":"2026-05-26T22:10:01.019109Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28799,"took":"140.966634ms","hash":3694730918,"current-db-size-bytes":58920960,"current-db-size":"59 MB","current-db-size-in-use-bytes":30527488,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-26T22:10:01.019179Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3694730918,"revision":28799,"compact-revision":26055} {"level":"info","ts":"2026-05-26T22:15:00.880328Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31044} {"level":"info","ts":"2026-05-26T22:15:01.024582Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31044,"took":"139.780232ms","hash":384197316,"current-db-size-bytes":58920960,"current-db-size":"59 MB","current-db-size-in-use-bytes":30457856,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-26T22:15:01.024659Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":384197316,"revision":31044,"compact-revision":28799} {"level":"info","ts":"2026-05-26T22:20:00.889836Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33308} {"level":"info","ts":"2026-05-26T22:20:01.047214Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33308,"took":"151.639425ms","hash":653956017,"current-db-size-bytes":59965440,"current-db-size":"60 MB","current-db-size-in-use-bytes":31879168,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-26T22:20:01.047262Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":653956017,"revision":33308,"compact-revision":31044} {"level":"info","ts":"2026-05-26T22:25:00.898313Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35601} {"level":"info","ts":"2026-05-26T22:25:01.056569Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35601,"took":"154.02211ms","hash":4226186007,"current-db-size-bytes":63414272,"current-db-size":"63 MB","current-db-size-in-use-bytes":32067584,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-26T22:25:01.056703Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4226186007,"revision":35601,"compact-revision":33308} {"level":"info","ts":"2026-05-26T22:27:03.556202Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"66f7b57d56ad567d","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-26T22:27:03.562197Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-26T22:27:03.562626Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-26T22:30:00.916062Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37825} {"level":"info","ts":"2026-05-26T22:30:01.057119Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37825,"took":"134.827976ms","hash":3615893331,"current-db-size-bytes":63414272,"current-db-size":"63 MB","current-db-size-in-use-bytes":31551488,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-26T22:30:01.057187Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3615893331,"revision":37825,"compact-revision":35601}